{"level":"warn","ts":"2026-06-06T05:07:30.711544Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-06T05:07:30.711647Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-06-06T05:07:30.711741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-06T05:07:30.71176Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-06T05:07:30.711778Z","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-06-06T05:07:30.712421Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-06-06T05:07:30.712495Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-06-06T05:07:30.716946Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.20269ms"} {"level":"info","ts":"2026-06-06T05:07:30.724846Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-06-06T05:07:30.724973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-06-06T05:07:30.725006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-06-06T05:07:30.725022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-06T05:07:30.725043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-06-06T05:07:30.725092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-06-06T05:07:30.733567Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-06T05:07:30.735393Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-06T05:07:30.736506Z","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-06-06T05:07:30.739513Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-06T05:07:30.740491Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-06T05:07:30.7406Z","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-06-06T05:07:30.740746Z","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-06-06T05:07:30.740759Z","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-06-06T05:07:30.742746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-06-06T05:07:30.743397Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-06T05:07:30.744759Z","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-06-06T05:07:30.745072Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-06T05:07:30.745135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-06T05:07:30.745111Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-06T05:07:30.745196Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-06T05:07:31.026057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-06-06T05:07:31.026135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-06T05:07:31.026167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-06-06T05:07:31.026186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-06-06T05:07:31.026195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-06T05:07:31.026208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-06-06T05:07:31.026219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-06T05:07:31.027262Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-06T05:07:31.02766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-06T05:07:31.02783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-06T05:07:31.028098Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-06T05:07:31.028156Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-06T05:07:31.028183Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-06-06T05:07:31.028319Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-06T05:07:31.028364Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-06T05:07:31.027622Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-06T05:07:31.030528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-06T05:07:31.032787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-06-06T05:07:56.566145Z","caller":"traceutil/trace.go:171","msg":"trace[2132109646] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"115.818808ms","start":"2026-06-06T05:07:56.450299Z","end":"2026-06-06T05:07:56.566118Z","steps":["trace[2132109646] 'process raft request' (duration: 115.679184ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:08:14.190748Z","caller":"traceutil/trace.go:171","msg":"trace[638268381] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"154.957336ms","start":"2026-06-06T05:08:14.035743Z","end":"2026-06-06T05:08:14.1907Z","steps":["trace[638268381] 'process raft request' (duration: 153.821169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:08:14.695984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.953177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-06T05:08:14.696268Z","caller":"traceutil/trace.go:171","msg":"trace[687259353] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"424.999262ms","start":"2026-06-06T05:08:14.271251Z","end":"2026-06-06T05:08:14.69625Z","steps":["trace[687259353] 'read index received' (duration: 247.71859ms)","trace[687259353] 'applied index is now lower than readState.Index' (duration: 177.279362ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:08:14.696294Z","caller":"traceutil/trace.go:171","msg":"trace[2063829113] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"486.686502ms","start":"2026-06-06T05:08:14.209569Z","end":"2026-06-06T05:08:14.696256Z","steps":["trace[2063829113] 'process raft request' (duration: 49.864625ms)","trace[2063829113] 'compare' (duration: 435.775113ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:08:14.69639Z","caller":"traceutil/trace.go:171","msg":"trace[2146869474] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"442.291378ms","start":"2026-06-06T05:08:14.254049Z","end":"2026-06-06T05:08:14.696341Z","steps":["trace[2146869474] 'process raft request' (duration: 442.135415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:08:14.696457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:08:14.209548Z","time spent":"486.829365ms","remote":"127.0.0.1:39280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:08:14.696603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:08:14.254011Z","time spent":"442.488663ms","remote":"127.0.0.1:39302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-06T05:08:14.69672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.451504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-06T05:08:14.696791Z","caller":"traceutil/trace.go:171","msg":"trace[2116393439] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:622; }","duration":"425.565697ms","start":"2026-06-06T05:08:14.271207Z","end":"2026-06-06T05:08:14.696773Z","steps":["trace[2116393439] 'agreement among raft nodes before linearized reading' (duration: 425.154156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:08:14.696828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:08:14.271192Z","time spent":"425.628439ms","remote":"127.0.0.1:39126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"info","ts":"2026-06-06T05:09:01.246666Z","caller":"traceutil/trace.go:171","msg":"trace[1213816385] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"237.517953ms","start":"2026-06-06T05:09:01.009122Z","end":"2026-06-06T05:09:01.24664Z","steps":["trace[1213816385] 'process raft request' (duration: 237.368549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:09:47.592666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.183327ms","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-06-06T05:09:47.592802Z","caller":"traceutil/trace.go:171","msg":"trace[1685292234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"111.391272ms","start":"2026-06-06T05:09:47.481381Z","end":"2026-06-06T05:09:47.592772Z","steps":["trace[1685292234] 'range keys from in-memory index tree' (duration: 110.805338ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:09:48.029738Z","caller":"traceutil/trace.go:171","msg":"trace[626366601] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"135.330767ms","start":"2026-06-06T05:09:47.894376Z","end":"2026-06-06T05:09:48.029707Z","steps":["trace[626366601] 'read index received' (duration: 87.885068ms)","trace[626366601] 'applied index is now lower than readState.Index' (duration: 47.444919ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:09:48.029777Z","caller":"traceutil/trace.go:171","msg":"trace[1132766821] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"136.932196ms","start":"2026-06-06T05:09:47.892796Z","end":"2026-06-06T05:09:48.029728Z","steps":["trace[1132766821] 'process raft request' (duration: 89.397896ms)","trace[1132766821] 'compare' (duration: 47.376577ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:09:48.030036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.575622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-06T05:09:48.030202Z","caller":"traceutil/trace.go:171","msg":"trace[1785125280] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1873; }","duration":"135.833169ms","start":"2026-06-06T05:09:47.894308Z","end":"2026-06-06T05:09:48.030141Z","steps":["trace[1785125280] 'agreement among raft nodes before linearized reading' (duration: 135.490841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:09:48.030063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.25893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-06T05:09:48.030407Z","caller":"traceutil/trace.go:171","msg":"trace[343405888] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1873; }","duration":"101.656371ms","start":"2026-06-06T05:09:47.928717Z","end":"2026-06-06T05:09:48.030374Z","steps":["trace[343405888] 'agreement among raft nodes before linearized reading' (duration: 101.182458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:10:00.950153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.740715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-06T05:10:00.950287Z","caller":"traceutil/trace.go:171","msg":"trace[993252173] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"159.638841ms","start":"2026-06-06T05:10:00.790621Z","end":"2026-06-06T05:10:00.95026Z","steps":["trace[993252173] 'process raft request' (duration: 41.686019ms)","trace[993252173] 'compare' (duration: 117.635113ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:10:04.961873Z","caller":"traceutil/trace.go:171","msg":"trace[601686908] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"174.246092ms","start":"2026-06-06T05:10:04.78759Z","end":"2026-06-06T05:10:04.961837Z","steps":["trace[601686908] 'process raft request' (duration: 138.203917ms)","trace[601686908] 'compare' (duration: 35.901801ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:10:37.256557Z","caller":"traceutil/trace.go:171","msg":"trace[422394902] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"128.406812ms","start":"2026-06-06T05:10:37.128118Z","end":"2026-06-06T05:10:37.256525Z","steps":["trace[422394902] 'process raft request' (duration: 102.612342ms)","trace[422394902] 'compare' (duration: 25.644876ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:10:43.921904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.833826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-06T05:10:43.921972Z","caller":"traceutil/trace.go:171","msg":"trace[124537240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2219; }","duration":"139.964709ms","start":"2026-06-06T05:10:43.781995Z","end":"2026-06-06T05:10:43.92196Z","steps":["trace[124537240] 'range keys from in-memory index tree' (duration: 139.647921ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:10:44.17604Z","caller":"traceutil/trace.go:171","msg":"trace[401352156] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"249.012096ms","start":"2026-06-06T05:10:43.926999Z","end":"2026-06-06T05:10:44.176011Z","steps":["trace[401352156] 'process raft request' (duration: 248.916263ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:10:44.17621Z","caller":"traceutil/trace.go:171","msg":"trace[1972848511] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"248.011422ms","start":"2026-06-06T05:10:43.928168Z","end":"2026-06-06T05:10:44.176179Z","steps":["trace[1972848511] 'process raft request' (duration: 247.808887ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:10:44.176287Z","caller":"traceutil/trace.go:171","msg":"trace[1377398979] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"249.512208ms","start":"2026-06-06T05:10:43.926754Z","end":"2026-06-06T05:10:44.176266Z","steps":["trace[1377398979] 'process raft request' (duration: 217.895909ms)","trace[1377398979] 'compare' (duration: 31.122557ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:11:52.823119Z","caller":"traceutil/trace.go:171","msg":"trace[156245779] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"130.417674ms","start":"2026-06-06T05:11:52.692669Z","end":"2026-06-06T05:11:52.823087Z","steps":["trace[156245779] 'process raft request' (duration: 130.25988ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:15:41.685941Z","caller":"traceutil/trace.go:171","msg":"trace[916730788] linearizableReadLoop","detail":"{readStateIndex:4876; appliedIndex:4875; }","duration":"132.296828ms","start":"2026-06-06T05:15:41.553621Z","end":"2026-06-06T05:15:41.685918Z","steps":["trace[916730788] 'read index received' (duration: 132.020021ms)","trace[916730788] 'applied index is now lower than readState.Index' (duration: 275.587µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:15:41.686Z","caller":"traceutil/trace.go:171","msg":"trace[626205077] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"133.731003ms","start":"2026-06-06T05:15:41.55223Z","end":"2026-06-06T05:15:41.685961Z","steps":["trace[626205077] 'process raft request' (duration: 133.486157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:15:41.686279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.626225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-06-06T05:15:41.686371Z","caller":"traceutil/trace.go:171","msg":"trace[1787758766] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4695; }","duration":"132.766109ms","start":"2026-06-06T05:15:41.553583Z","end":"2026-06-06T05:15:41.686349Z","steps":["trace[1787758766] 'agreement among raft nodes before linearized reading' (duration: 132.482392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:15:45.93201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.068323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-06T05:15:45.932371Z","caller":"traceutil/trace.go:171","msg":"trace[1655743009] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"173.090691ms","start":"2026-06-06T05:15:45.759229Z","end":"2026-06-06T05:15:45.932319Z","steps":["trace[1655743009] 'process raft request' (duration: 69.627878ms)","trace[1655743009] 'compare' (duration: 102.860959ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:15:45.932673Z","caller":"traceutil/trace.go:171","msg":"trace[1533962971] linearizableReadLoop","detail":"{readStateIndex:4986; appliedIndex:4985; }","duration":"170.378876ms","start":"2026-06-06T05:15:45.762229Z","end":"2026-06-06T05:15:45.932608Z","steps":["trace[1533962971] 'read index received' (duration: 66.703158ms)","trace[1533962971] 'applied index is now lower than readState.Index' (duration: 103.671188ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:15:45.932875Z","caller":"traceutil/trace.go:171","msg":"trace[1177523995] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"170.270253ms","start":"2026-06-06T05:15:45.76259Z","end":"2026-06-06T05:15:45.93286Z","steps":["trace[1177523995] 'process raft request' (duration: 169.519345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:15:45.93305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.352299ms","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-06-06T05:15:45.933112Z","caller":"traceutil/trace.go:171","msg":"trace[181810163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4802; }","duration":"166.44344ms","start":"2026-06-06T05:15:45.766651Z","end":"2026-06-06T05:15:45.933094Z","steps":["trace[181810163] 'agreement among raft nodes before linearized reading' (duration: 166.202685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:15:45.933203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.903512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-06T05:15:45.933173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.862507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-06-06T05:15:45.933239Z","caller":"traceutil/trace.go:171","msg":"trace[578720872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4802; }","duration":"149.955223ms","start":"2026-06-06T05:15:45.783276Z","end":"2026-06-06T05:15:45.933231Z","steps":["trace[578720872] 'agreement among raft nodes before linearized reading' (duration: 149.897341ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:15:45.933317Z","caller":"traceutil/trace.go:171","msg":"trace[1892856081] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4802; }","duration":"171.071262ms","start":"2026-06-06T05:15:45.762208Z","end":"2026-06-06T05:15:45.933279Z","steps":["trace[1892856081] 'agreement among raft nodes before linearized reading' (duration: 170.546379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:15:45.933499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.703289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-06T05:15:45.933565Z","caller":"traceutil/trace.go:171","msg":"trace[1606146112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4802; }","duration":"164.783971ms","start":"2026-06-06T05:15:45.768766Z","end":"2026-06-06T05:15:45.93355Z","steps":["trace[1606146112] 'agreement among raft nodes before linearized reading' (duration: 164.607267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:15:47.083292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.789861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-06T05:15:47.083458Z","caller":"traceutil/trace.go:171","msg":"trace[56370943] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4843; }","duration":"109.003476ms","start":"2026-06-06T05:15:46.97443Z","end":"2026-06-06T05:15:47.083434Z","steps":["trace[56370943] 'range keys from in-memory index tree' (duration: 108.445733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:15:49.973208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.654506ms","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-06-06T05:15:49.973277Z","caller":"traceutil/trace.go:171","msg":"trace[843780342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4968; }","duration":"203.768219ms","start":"2026-06-06T05:15:49.769493Z","end":"2026-06-06T05:15:49.973262Z","steps":["trace[843780342] 'range keys from in-memory index tree' (duration: 203.445191ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:16:03.414508Z","caller":"traceutil/trace.go:171","msg":"trace[1425563707] linearizableReadLoop","detail":"{readStateIndex:5439; appliedIndex:5438; }","duration":"146.699785ms","start":"2026-06-06T05:16:03.267788Z","end":"2026-06-06T05:16:03.414488Z","steps":["trace[1425563707] 'read index received' (duration: 69.031274ms)","trace[1425563707] 'applied index is now lower than readState.Index' (duration: 77.667781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:16:03.414538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.696878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-06-06T05:16:03.41463Z","caller":"traceutil/trace.go:171","msg":"trace[1956429636] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5239; }","duration":"291.858213ms","start":"2026-06-06T05:16:03.12275Z","end":"2026-06-06T05:16:03.414608Z","steps":["trace[1956429636] 'range keys from in-memory index tree' (duration: 291.402301ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:16:03.414624Z","caller":"traceutil/trace.go:171","msg":"trace[948739211] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"276.84636ms","start":"2026-06-06T05:16:03.13773Z","end":"2026-06-06T05:16:03.414577Z","steps":["trace[948739211] 'process raft request' (duration: 199.162459ms)","trace[948739211] 'compare' (duration: 77.501937ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:16:03.414694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.90252ms","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-06-06T05:16:03.414719Z","caller":"traceutil/trace.go:171","msg":"trace[1513585201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5240; }","duration":"146.983302ms","start":"2026-06-06T05:16:03.267728Z","end":"2026-06-06T05:16:03.414712Z","steps":["trace[1513585201] 'agreement among raft nodes before linearized reading' (duration: 146.867498ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:16:03.535609Z","caller":"traceutil/trace.go:171","msg":"trace[1828737316] linearizableReadLoop","detail":"{readStateIndex:5441; appliedIndex:5439; }","duration":"115.903353ms","start":"2026-06-06T05:16:03.41968Z","end":"2026-06-06T05:16:03.535583Z","steps":["trace[1828737316] 'read index received' (duration: 79.681251ms)","trace[1828737316] 'applied index is now lower than readState.Index' (duration: 36.221372ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:16:03.535667Z","caller":"traceutil/trace.go:171","msg":"trace[628653519] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"116.000015ms","start":"2026-06-06T05:16:03.419624Z","end":"2026-06-06T05:16:03.535624Z","steps":["trace[628653519] 'process raft request' (duration: 101.482984ms)","trace[628653519] 'compare' (duration: 14.368938ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:16:03.535875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.17098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70948"} {"level":"info","ts":"2026-06-06T05:16:03.535921Z","caller":"traceutil/trace.go:171","msg":"trace[1516456608] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5242; }","duration":"116.235231ms","start":"2026-06-06T05:16:03.419673Z","end":"2026-06-06T05:16:03.535908Z","steps":["trace[1516456608] 'agreement among raft nodes before linearized reading' (duration: 116.015426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:16:10.563253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.366664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-06T05:16:10.563346Z","caller":"traceutil/trace.go:171","msg":"trace[835454726] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:5421; }","duration":"131.525348ms","start":"2026-06-06T05:16:10.4318Z","end":"2026-06-06T05:16:10.563326Z","steps":["trace[835454726] 'count revisions from in-memory index tree' (duration: 131.215291ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:17:31.209107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3004} {"level":"info","ts":"2026-06-06T05:17:31.300544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3004,"took":"89.952099ms","hash":1320748951,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":32108544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-06T05:17:31.300616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1320748951,"revision":3004,"compact-revision":-1} {"level":"info","ts":"2026-06-06T05:22:13.552268Z","caller":"traceutil/trace.go:171","msg":"trace[704817671] linearizableReadLoop","detail":"{readStateIndex:8510; appliedIndex:8509; }","duration":"125.843084ms","start":"2026-06-06T05:22:13.426404Z","end":"2026-06-06T05:22:13.552247Z","steps":["trace[704817671] 'read index received' (duration: 125.70726ms)","trace[704817671] 'applied index is now lower than readState.Index' (duration: 134.794µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:22:13.552344Z","caller":"traceutil/trace.go:171","msg":"trace[1405036101] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"267.670113ms","start":"2026-06-06T05:22:13.284642Z","end":"2026-06-06T05:22:13.552312Z","steps":["trace[1405036101] 'process raft request' (duration: 267.466257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:22:13.552448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.032509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-06T05:22:13.552486Z","caller":"traceutil/trace.go:171","msg":"trace[1393916016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8220; }","duration":"126.121091ms","start":"2026-06-06T05:22:13.426354Z","end":"2026-06-06T05:22:13.552475Z","steps":["trace[1393916016] 'agreement among raft nodes before linearized reading' (duration: 125.985288ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:22:13.742661Z","caller":"traceutil/trace.go:171","msg":"trace[2001457275] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"186.572373ms","start":"2026-06-06T05:22:13.556067Z","end":"2026-06-06T05:22:13.74264Z","steps":["trace[2001457275] 'process raft request' (duration: 182.071836ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:22:25.810037Z","caller":"traceutil/trace.go:171","msg":"trace[1556253120] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"174.157596ms","start":"2026-06-06T05:22:25.635856Z","end":"2026-06-06T05:22:25.810014Z","steps":["trace[1556253120] 'process raft request' (duration: 174.006902ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:22:26.287982Z","caller":"traceutil/trace.go:171","msg":"trace[1079235445] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"126.662744ms","start":"2026-06-06T05:22:26.161287Z","end":"2026-06-06T05:22:26.28795Z","steps":["trace[1079235445] 'process raft request' (duration: 56.409036ms)","trace[1079235445] 'compare' (duration: 70.059283ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:22:26.44698Z","caller":"traceutil/trace.go:171","msg":"trace[2109722293] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"153.204062ms","start":"2026-06-06T05:22:26.293732Z","end":"2026-06-06T05:22:26.446936Z","steps":["trace[2109722293] 'process raft request' (duration: 99.933341ms)","trace[2109722293] 'compare' (duration: 53.033085ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:22:27.517995Z","caller":"traceutil/trace.go:171","msg":"trace[1515980109] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"225.087431ms","start":"2026-06-06T05:22:27.292869Z","end":"2026-06-06T05:22:27.517956Z","steps":["trace[1515980109] 'process raft request' (duration: 208.684606ms)","trace[1515980109] 'compare' (duration: 15.837231ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:22:31.216395Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6132} {"level":"info","ts":"2026-06-06T05:22:31.318115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6132,"took":"98.812124ms","hash":490166407,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":23928832,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-06T05:22:31.318191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":490166407,"revision":6132,"compact-revision":3004} {"level":"info","ts":"2026-06-06T05:22:56.027077Z","caller":"traceutil/trace.go:171","msg":"trace[454709345] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"109.210355ms","start":"2026-06-06T05:22:55.917828Z","end":"2026-06-06T05:22:56.027039Z","steps":["trace[454709345] 'process raft request' (duration: 108.983329ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:22:56.051967Z","caller":"traceutil/trace.go:171","msg":"trace[737857843] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"104.310147ms","start":"2026-06-06T05:22:55.947634Z","end":"2026-06-06T05:22:56.051944Z","steps":["trace[737857843] 'process raft request' (duration: 103.922847ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:23:08.264843Z","caller":"traceutil/trace.go:171","msg":"trace[1275973905] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8610; }","duration":"118.774894ms","start":"2026-06-06T05:23:08.146037Z","end":"2026-06-06T05:23:08.264812Z","steps":["trace[1275973905] 'compare' (duration: 98.241201ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:23:08.267524Z","caller":"traceutil/trace.go:171","msg":"trace[254735297] linearizableReadLoop","detail":"{readStateIndex:8918; appliedIndex:8916; }","duration":"107.585885ms","start":"2026-06-06T05:23:08.159916Z","end":"2026-06-06T05:23:08.267502Z","steps":["trace[254735297] 'read index received' (duration: 6.614529ms)","trace[254735297] 'applied index is now lower than readState.Index' (duration: 100.969606ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:23:08.267663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.730009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-25p6x\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-06T05:23:08.267694Z","caller":"traceutil/trace.go:171","msg":"trace[544554707] range","detail":"{range_begin:/registry/pods/openstack/keepalived-25p6x; range_end:; response_count:1; response_revision:8611; }","duration":"107.810591ms","start":"2026-06-06T05:23:08.159877Z","end":"2026-06-06T05:23:08.267687Z","steps":["trace[544554707] 'agreement among raft nodes before linearized reading' (duration: 107.707318ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:23:08.267908Z","caller":"traceutil/trace.go:171","msg":"trace[62727956] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"121.351276ms","start":"2026-06-06T05:23:08.146483Z","end":"2026-06-06T05:23:08.267835Z","steps":["trace[62727956] 'process raft request' (duration: 120.892095ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:23:24.09891Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000236a.wal"} {"level":"info","ts":"2026-06-06T05:24:24.637969Z","caller":"traceutil/trace.go:171","msg":"trace[2032581683] transaction","detail":"{read_only:false; response_revision:9361; number_of_response:1; }","duration":"137.453786ms","start":"2026-06-06T05:24:24.500484Z","end":"2026-06-06T05:24:24.637938Z","steps":["trace[2032581683] 'process raft request' (duration: 123.561162ms)","trace[2032581683] 'compare' (duration: 13.72362ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:25:05.100611Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-06T05:25:05.109653Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-06T05:25:05.109739Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-06T05:27:31.222491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8322} {"level":"info","ts":"2026-06-06T05:27:31.29907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8322,"took":"74.70146ms","hash":1188293447,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":26394624,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-06T05:27:31.299125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1188293447,"revision":8322,"compact-revision":6132} {"level":"info","ts":"2026-06-06T05:28:02.441452Z","caller":"traceutil/trace.go:171","msg":"trace[1892091812] transaction","detail":"{read_only:false; response_revision:11174; number_of_response:1; }","duration":"101.960523ms","start":"2026-06-06T05:28:02.339463Z","end":"2026-06-06T05:28:02.441423Z","steps":["trace[1892091812] 'process raft request' (duration: 86.853549ms)","trace[1892091812] 'compare' (duration: 15.009772ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:32:31.232399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10947} {"level":"info","ts":"2026-06-06T05:32:31.318101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10947,"took":"81.989853ms","hash":3343145222,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":27942912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-06T05:32:31.318181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3343145222,"revision":10947,"compact-revision":8322} {"level":"info","ts":"2026-06-06T05:33:48.505497Z","caller":"traceutil/trace.go:171","msg":"trace[1550490472] transaction","detail":"{read_only:false; response_revision:13546; number_of_response:1; }","duration":"104.989364ms","start":"2026-06-06T05:33:48.400481Z","end":"2026-06-06T05:33:48.50547Z","steps":["trace[1550490472] 'process raft request' (duration: 104.791979ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:34:26.914197Z","caller":"traceutil/trace.go:171","msg":"trace[1874556593] linearizableReadLoop","detail":"{readStateIndex:14236; appliedIndex:14235; }","duration":"174.532857ms","start":"2026-06-06T05:34:26.739635Z","end":"2026-06-06T05:34:26.914167Z","steps":["trace[1874556593] 'read index received' (duration: 144.712769ms)","trace[1874556593] 'applied index is now lower than readState.Index' (duration: 29.818628ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:34:26.914258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.028308ms","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-06-06T05:34:26.914397Z","caller":"traceutil/trace.go:171","msg":"trace[369792603] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13763; }","duration":"316.232673ms","start":"2026-06-06T05:34:26.598132Z","end":"2026-06-06T05:34:26.914365Z","steps":["trace[369792603] 'range keys from in-memory index tree' (duration: 315.67721ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:34:26.914381Z","caller":"traceutil/trace.go:171","msg":"trace[282221032] transaction","detail":"{read_only:false; response_revision:13764; number_of_response:1; }","duration":"278.089936ms","start":"2026-06-06T05:34:26.636232Z","end":"2026-06-06T05:34:26.914322Z","steps":["trace[282221032] 'process raft request' (duration: 248.208608ms)","trace[282221032] 'compare' (duration: 29.51402ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:34:26.914416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.739721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-06T05:34:26.914469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:34:26.598111Z","time spent":"316.347506ms","remote":"127.0.0.1:39280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-06T05:34:26.914519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.617107ms","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-06-06T05:34:26.914538Z","caller":"traceutil/trace.go:171","msg":"trace[1346927530] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:13764; }","duration":"174.912676ms","start":"2026-06-06T05:34:26.739593Z","end":"2026-06-06T05:34:26.914506Z","steps":["trace[1346927530] 'agreement among raft nodes before linearized reading' (duration: 174.720311ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:34:26.914563Z","caller":"traceutil/trace.go:171","msg":"trace[1532700458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13764; }","duration":"139.714169ms","start":"2026-06-06T05:34:26.774838Z","end":"2026-06-06T05:34:26.914552Z","steps":["trace[1532700458] 'agreement among raft nodes before linearized reading' (duration: 139.543825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:34:26.914693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.52804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-qg7gl\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-06-06T05:34:26.914809Z","caller":"traceutil/trace.go:171","msg":"trace[2057793508] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-qg7gl; range_end:; response_count:1; response_revision:13764; }","duration":"133.667224ms","start":"2026-06-06T05:34:26.781116Z","end":"2026-06-06T05:34:26.914783Z","steps":["trace[2057793508] 'agreement among raft nodes before linearized reading' (duration: 133.411028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:34:35.524693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.501268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-06T05:34:35.524712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.614547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dfb50dba-6840-4b7c-b963-2bc3615458b7\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-06-06T05:34:35.524769Z","caller":"traceutil/trace.go:171","msg":"trace[79603447] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:13925; }","duration":"286.633041ms","start":"2026-06-06T05:34:35.238119Z","end":"2026-06-06T05:34:35.524752Z","steps":["trace[79603447] 'count revisions from in-memory index tree' (duration: 286.408096ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:34:35.524788Z","caller":"traceutil/trace.go:171","msg":"trace[313786259] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dfb50dba-6840-4b7c-b963-2bc3615458b7; range_end:; response_count:1; response_revision:13925; }","duration":"337.73269ms","start":"2026-06-06T05:34:35.187039Z","end":"2026-06-06T05:34:35.524772Z","steps":["trace[313786259] 'range keys from in-memory index tree' (duration: 337.476714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:34:35.524828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:34:35.18702Z","time spent":"337.797153ms","remote":"127.0.0.1:39280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3629,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dfb50dba-6840-4b7c-b963-2bc3615458b7\" "} {"level":"warn","ts":"2026-06-06T05:34:35.525062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.907245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de600ed2-7eca-4744-9589-6c5c7525ad41\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-06-06T05:34:35.525093Z","caller":"traceutil/trace.go:171","msg":"trace[466955039] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-de600ed2-7eca-4744-9589-6c5c7525ad41; range_end:; response_count:1; response_revision:13925; }","duration":"342.978147ms","start":"2026-06-06T05:34:35.182105Z","end":"2026-06-06T05:34:35.525083Z","steps":["trace[466955039] 'range keys from in-memory index tree' (duration: 342.778702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:34:35.525121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:34:35.182084Z","time spent":"343.030728ms","remote":"127.0.0.1:39280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3629,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de600ed2-7eca-4744-9589-6c5c7525ad41\" "} {"level":"info","ts":"2026-06-06T05:34:35.525513Z","caller":"traceutil/trace.go:171","msg":"trace[1633222588] transaction","detail":"{read_only:false; response_revision:13926; number_of_response:1; }","duration":"144.048624ms","start":"2026-06-06T05:34:35.381442Z","end":"2026-06-06T05:34:35.52549Z","steps":["trace[1633222588] 'process raft request' (duration: 143.060969ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:34:35.52553Z","caller":"traceutil/trace.go:171","msg":"trace[1961599550] linearizableReadLoop","detail":"{readStateIndex:14401; appliedIndex:14401; }","duration":"107.659929ms","start":"2026-06-06T05:34:35.41784Z","end":"2026-06-06T05:34:35.5255Z","steps":["trace[1961599550] 'read index received' (duration: 107.646199ms)","trace[1961599550] 'applied index is now lower than readState.Index' (duration: 11.3µs)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:34:35.525688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.829553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-25p6x\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-06T05:34:35.525782Z","caller":"traceutil/trace.go:171","msg":"trace[1474999445] range","detail":"{range_begin:/registry/pods/openstack/keepalived-25p6x; range_end:; response_count:1; response_revision:13926; }","duration":"107.962926ms","start":"2026-06-06T05:34:35.417797Z","end":"2026-06-06T05:34:35.52576Z","steps":["trace[1474999445] 'agreement among raft nodes before linearized reading' (duration: 107.779512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:35:41.086935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.047098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-06T05:35:41.087057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.08083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-06T05:35:41.087067Z","caller":"traceutil/trace.go:171","msg":"trace[156538536] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14835; }","duration":"103.224002ms","start":"2026-06-06T05:35:40.983815Z","end":"2026-06-06T05:35:41.087039Z","steps":["trace[156538536] 'range keys from in-memory index tree' (duration: 102.945386ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:35:41.087124Z","caller":"traceutil/trace.go:171","msg":"trace[513269393] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14835; }","duration":"101.194123ms","start":"2026-06-06T05:35:40.985913Z","end":"2026-06-06T05:35:41.087107Z","steps":["trace[513269393] 'range keys from in-memory index tree' (duration: 100.913246ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:36:05.567554Z","caller":"traceutil/trace.go:171","msg":"trace[186325154] linearizableReadLoop","detail":"{readStateIndex:15476; appliedIndex:15475; }","duration":"153.334307ms","start":"2026-06-06T05:36:05.414176Z","end":"2026-06-06T05:36:05.567511Z","steps":["trace[186325154] 'read index received' (duration: 153.128482ms)","trace[186325154] 'applied index is now lower than readState.Index' (duration: 203.585µs)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:36:05.567706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.502701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-06T05:36:05.56777Z","caller":"traceutil/trace.go:171","msg":"trace[747701063] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:14966; }","duration":"153.602924ms","start":"2026-06-06T05:36:05.414155Z","end":"2026-06-06T05:36:05.567758Z","steps":["trace[747701063] 'agreement among raft nodes before linearized reading' (duration: 153.495231ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:36:05.567699Z","caller":"traceutil/trace.go:171","msg":"trace[985273754] transaction","detail":"{read_only:false; response_revision:14966; number_of_response:1; }","duration":"156.79686ms","start":"2026-06-06T05:36:05.410856Z","end":"2026-06-06T05:36:05.567653Z","steps":["trace[985273754] 'process raft request' (duration: 156.508143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:36:05.845678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.272447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-vgkcq\" ","response":"range_response_count:1 size:9793"} {"level":"info","ts":"2026-06-06T05:36:05.845769Z","caller":"traceutil/trace.go:171","msg":"trace[1931690118] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-vgkcq; range_end:; response_count:1; response_revision:14966; }","duration":"136.40108ms","start":"2026-06-06T05:36:05.709343Z","end":"2026-06-06T05:36:05.845744Z","steps":["trace[1931690118] 'range keys from in-memory index tree' (duration: 136.113014ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:37:31.238951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13010} {"level":"info","ts":"2026-06-06T05:37:31.314159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13010,"took":"72.928494ms","hash":4022292190,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-06T05:37:31.314224Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4022292190,"revision":13010,"compact-revision":10947} {"level":"warn","ts":"2026-06-06T05:41:57.583473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.680757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:41:57.584923Z","caller":"traceutil/trace.go:171","msg":"trace[144599176] transaction","detail":"{read_only:false; response_revision:17603; number_of_response:1; }","duration":"228.281273ms","start":"2026-06-06T05:41:57.35661Z","end":"2026-06-06T05:41:57.584891Z","steps":["trace[144599176] 'process raft request' (duration: 61.086ms)","trace[144599176] 'compare' (duration: 165.368989ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:41:57.584949Z","caller":"traceutil/trace.go:171","msg":"trace[491702117] linearizableReadLoop","detail":"{readStateIndex:18200; appliedIndex:18199; }","duration":"209.271676ms","start":"2026-06-06T05:41:57.375642Z","end":"2026-06-06T05:41:57.584913Z","steps":["trace[491702117] 'read index received' (duration: 41.911118ms)","trace[491702117] 'applied index is now lower than readState.Index' (duration: 167.356888ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:41:57.585292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.642654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-r7hs8\" ","response":"range_response_count:1 size:20864"} {"level":"info","ts":"2026-06-06T05:41:57.585404Z","caller":"traceutil/trace.go:171","msg":"trace[908211911] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-r7hs8; range_end:; response_count:1; response_revision:17603; }","duration":"209.777677ms","start":"2026-06-06T05:41:57.375591Z","end":"2026-06-06T05:41:57.585368Z","steps":["trace[908211911] 'agreement among raft nodes before linearized reading' (duration: 209.548162ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:41:57.615827Z","caller":"traceutil/trace.go:171","msg":"trace[637171378] transaction","detail":"{read_only:false; response_revision:17604; number_of_response:1; }","duration":"171.355073ms","start":"2026-06-06T05:41:57.444101Z","end":"2026-06-06T05:41:57.615456Z","steps":["trace[637171378] 'process raft request' (duration: 171.169258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:41:58.111431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.096385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:41:58.111584Z","caller":"traceutil/trace.go:171","msg":"trace[176864075] linearizableReadLoop","detail":"{readStateIndex:18203; appliedIndex:18201; }","duration":"352.750318ms","start":"2026-06-06T05:41:57.758819Z","end":"2026-06-06T05:41:58.11157Z","steps":["trace[176864075] 'read index received' (duration: 24.454808ms)","trace[176864075] 'applied index is now lower than readState.Index' (duration: 328.2949ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:41:58.111651Z","caller":"traceutil/trace.go:171","msg":"trace[670881492] transaction","detail":"{read_only:false; response_revision:17605; number_of_response:1; }","duration":"512.761828ms","start":"2026-06-06T05:41:57.598854Z","end":"2026-06-06T05:41:58.111616Z","steps":["trace[670881492] 'process raft request' (duration: 184.412017ms)","trace[670881492] 'compare' (duration: 327.747476ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:41:58.111806Z","caller":"traceutil/trace.go:171","msg":"trace[340134548] transaction","detail":"{read_only:false; response_revision:17606; number_of_response:1; }","duration":"511.528739ms","start":"2026-06-06T05:41:57.600268Z","end":"2026-06-06T05:41:58.111797Z","steps":["trace[340134548] 'process raft request' (duration: 511.247092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:41:58.111823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:41:57.598828Z","time spent":"512.911631ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:41:58.111863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:41:57.600259Z","time spent":"511.57751ms","remote":"127.0.0.1:39280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20979,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:41:58.111986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.147368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-06T05:41:58.112057Z","caller":"traceutil/trace.go:171","msg":"trace[180384427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17606; }","duration":"353.25846ms","start":"2026-06-06T05:41:57.758784Z","end":"2026-06-06T05:41:58.112043Z","steps":["trace[180384427] 'agreement among raft nodes before linearized reading' (duration: 353.058256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:41:58.112089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:41:57.758752Z","time spent":"353.331611ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-06T05:41:58.112105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.842029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-vgkcq\" ","response":"range_response_count:1 size:9794"} {"level":"info","ts":"2026-06-06T05:41:58.112228Z","caller":"traceutil/trace.go:171","msg":"trace[2081258817] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-vgkcq; range_end:; response_count:1; response_revision:17606; }","duration":"264.007413ms","start":"2026-06-06T05:41:57.848177Z","end":"2026-06-06T05:41:58.112185Z","steps":["trace[2081258817] 'agreement among raft nodes before linearized reading' (duration: 263.661785ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:42:31.245567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15526} {"level":"info","ts":"2026-06-06T05:42:31.337786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15526,"took":"87.644349ms","hash":3476391476,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":32948224,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-06T05:42:31.33784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3476391476,"revision":15526,"compact-revision":13010} {"level":"info","ts":"2026-06-06T05:45:32.619147Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-06T05:45:32.632729Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-06T05:45:32.632853Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-06-06T05:47:08.737873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.139922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-06T05:47:08.737987Z","caller":"traceutil/trace.go:171","msg":"trace[1453295034] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20012; }","duration":"120.348227ms","start":"2026-06-06T05:47:08.617616Z","end":"2026-06-06T05:47:08.737964Z","steps":["trace[1453295034] 'range keys from in-memory index tree' (duration: 119.865796ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:47:14.147287Z","caller":"traceutil/trace.go:171","msg":"trace[1443163097] transaction","detail":"{read_only:false; response_revision:20038; number_of_response:1; }","duration":"133.174706ms","start":"2026-06-06T05:47:14.014081Z","end":"2026-06-06T05:47:14.147256Z","steps":["trace[1443163097] 'process raft request' (duration: 132.984182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:47:14.34465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.490216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:47:14.344799Z","caller":"traceutil/trace.go:171","msg":"trace[598658856] transaction","detail":"{read_only:false; response_revision:20039; number_of_response:1; }","duration":"328.710052ms","start":"2026-06-06T05:47:14.016062Z","end":"2026-06-06T05:47:14.344772Z","steps":["trace[598658856] 'process raft request' (duration: 220.98184ms)","trace[598658856] 'compare' (duration: 107.303042ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:47:14.344874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:47:14.016034Z","time spent":"328.808815ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:47:14.626412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.635426ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-06-06T05:47:14.908763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.943193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:47:14.908878Z","caller":"traceutil/trace.go:171","msg":"trace[209519372] linearizableReadLoop","detail":"{readStateIndex:20721; appliedIndex:20720; }","duration":"129.131348ms","start":"2026-06-06T05:47:14.779731Z","end":"2026-06-06T05:47:14.908862Z","steps":["trace[209519372] 'read index received' (duration: 35.74µs)","trace[209519372] 'applied index is now lower than readState.Index' (duration: 129.094398ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:47:14.908939Z","caller":"traceutil/trace.go:171","msg":"trace[1327605232] transaction","detail":"{read_only:false; response_revision:20042; number_of_response:1; }","duration":"145.134734ms","start":"2026-06-06T05:47:14.763769Z","end":"2026-06-06T05:47:14.908904Z","steps":["trace[1327605232] 'compare' (duration: 135.652076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:47:14.909218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.456216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:47:14.909339Z","caller":"traceutil/trace.go:171","msg":"trace[893071856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20042; }","duration":"129.613719ms","start":"2026-06-06T05:47:14.779694Z","end":"2026-06-06T05:47:14.909308Z","steps":["trace[893071856] 'agreement among raft nodes before linearized reading' (duration: 129.430135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:47:14.909388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.9122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-06T05:47:14.909439Z","caller":"traceutil/trace.go:171","msg":"trace[536818807] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20042; }","duration":"120.978842ms","start":"2026-06-06T05:47:14.788445Z","end":"2026-06-06T05:47:14.909424Z","steps":["trace[536818807] 'agreement among raft nodes before linearized reading' (duration: 120.760317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:47:15.264152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.809526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:47:15.264267Z","caller":"traceutil/trace.go:171","msg":"trace[455862451] linearizableReadLoop","detail":"{readStateIndex:20723; appliedIndex:20722; }","duration":"162.098652ms","start":"2026-06-06T05:47:15.102151Z","end":"2026-06-06T05:47:15.26425Z","steps":["trace[455862451] 'read index received' (duration: 16.850285ms)","trace[455862451] 'applied index is now lower than readState.Index' (duration: 145.246917ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:47:15.264424Z","caller":"traceutil/trace.go:171","msg":"trace[1819189235] transaction","detail":"{read_only:false; response_revision:20044; number_of_response:1; }","duration":"256.341031ms","start":"2026-06-06T05:47:15.008044Z","end":"2026-06-06T05:47:15.264385Z","steps":["trace[1819189235] 'process raft request' (duration: 111.056744ms)","trace[1819189235] 'compare' (duration: 144.60574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:47:15.264834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.687185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T05:47:15.264888Z","caller":"traceutil/trace.go:171","msg":"trace[1580285147] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:20044; }","duration":"162.795659ms","start":"2026-06-06T05:47:15.10208Z","end":"2026-06-06T05:47:15.264876Z","steps":["trace[1580285147] 'agreement among raft nodes before linearized reading' (duration: 162.42142ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:47:15.392625Z","caller":"traceutil/trace.go:171","msg":"trace[470319285] transaction","detail":"{read_only:false; response_revision:20045; number_of_response:1; }","duration":"124.130989ms","start":"2026-06-06T05:47:15.26847Z","end":"2026-06-06T05:47:15.392601Z","steps":["trace[470319285] 'process raft request' (duration: 123.990234ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:47:31.253208Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18053} {"level":"info","ts":"2026-06-06T05:47:31.363334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18053,"took":"106.924535ms","hash":2758609600,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":29405184,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-06T05:47:31.363411Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2758609600,"revision":18053,"compact-revision":15526} {"level":"info","ts":"2026-06-06T05:51:16.619017Z","caller":"traceutil/trace.go:171","msg":"trace[1020503461] transaction","detail":"{read_only:false; response_revision:22504; number_of_response:1; }","duration":"178.433825ms","start":"2026-06-06T05:51:16.440468Z","end":"2026-06-06T05:51:16.618902Z","steps":["trace[1020503461] 'process raft request' (duration: 178.195549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:51:16.887854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.286402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:51:16.887977Z","caller":"traceutil/trace.go:171","msg":"trace[583126896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22506; }","duration":"107.495098ms","start":"2026-06-06T05:51:16.780462Z","end":"2026-06-06T05:51:16.887957Z","steps":["trace[583126896] 'range keys from in-memory index tree' (duration: 107.188211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:51:16.888362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.627702ms","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-06-06T05:51:16.888464Z","caller":"traceutil/trace.go:171","msg":"trace[1785163839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22506; }","duration":"188.810706ms","start":"2026-06-06T05:51:16.699633Z","end":"2026-06-06T05:51:16.888443Z","steps":["trace[1785163839] 'range keys from in-memory index tree' (duration: 188.399516ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:52:31.265466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20127} {"level":"info","ts":"2026-06-06T05:52:31.375954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20127,"took":"107.245122ms","hash":2873161127,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":41771008,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-06T05:52:31.376051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2873161127,"revision":20127,"compact-revision":18053} {"level":"info","ts":"2026-06-06T05:54:24.475671Z","caller":"traceutil/trace.go:171","msg":"trace[836070683] transaction","detail":"{read_only:false; response_revision:24452; number_of_response:1; }","duration":"234.507666ms","start":"2026-06-06T05:54:24.24112Z","end":"2026-06-06T05:54:24.475627Z","steps":["trace[836070683] 'process raft request' (duration: 221.572445ms)","trace[836070683] 'compare' (duration: 12.360527ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:24.493553Z","caller":"traceutil/trace.go:171","msg":"trace[2105293732] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"220.600891ms","start":"2026-06-06T05:54:24.272906Z","end":"2026-06-06T05:54:24.493507Z","steps":["trace[2105293732] 'process raft request' (duration: 220.170029ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:29.764371Z","caller":"traceutil/trace.go:171","msg":"trace[338593567] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"157.183794ms","start":"2026-06-06T05:54:29.607161Z","end":"2026-06-06T05:54:29.764345Z","steps":["trace[338593567] 'process raft request' (duration: 121.498065ms)","trace[338593567] 'compare' (duration: 35.532075ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:29.910918Z","caller":"traceutil/trace.go:171","msg":"trace[249304459] linearizableReadLoop","detail":"{readStateIndex:25298; appliedIndex:25297; }","duration":"131.210287ms","start":"2026-06-06T05:54:29.779675Z","end":"2026-06-06T05:54:29.910885Z","steps":["trace[249304459] 'read index received' (duration: 48.357433ms)","trace[249304459] 'applied index is now lower than readState.Index' (duration: 82.850834ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:29.911046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.347041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:29.911133Z","caller":"traceutil/trace.go:171","msg":"trace[1635322779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24494; }","duration":"131.479155ms","start":"2026-06-06T05:54:29.779632Z","end":"2026-06-06T05:54:29.911111Z","steps":["trace[1635322779] 'agreement among raft nodes before linearized reading' (duration: 131.327971ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:30.199799Z","caller":"traceutil/trace.go:171","msg":"trace[1123938758] transaction","detail":"{read_only:false; response_revision:24497; number_of_response:1; }","duration":"172.202605ms","start":"2026-06-06T05:54:30.02756Z","end":"2026-06-06T05:54:30.199763Z","steps":["trace[1123938758] 'process raft request' (duration: 144.443828ms)","trace[1123938758] 'compare' (duration: 27.495471ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:30.456503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.427924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:30.456623Z","caller":"traceutil/trace.go:171","msg":"trace[822720514] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24497; }","duration":"114.627569ms","start":"2026-06-06T05:54:30.34197Z","end":"2026-06-06T05:54:30.456597Z","steps":["trace[822720514] 'count revisions from in-memory index tree' (duration: 114.344013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:30.713542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.742288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:54:30.713636Z","caller":"traceutil/trace.go:171","msg":"trace[533300640] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"209.793591ms","start":"2026-06-06T05:54:30.503826Z","end":"2026-06-06T05:54:30.71362Z","steps":["trace[533300640] 'process raft request' (duration: 83.89102ms)","trace[533300640] 'compare' (duration: 125.384308ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:30.98168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.184459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:54:30.981916Z","caller":"traceutil/trace.go:171","msg":"trace[537251249] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"454.490482ms","start":"2026-06-06T05:54:30.527391Z","end":"2026-06-06T05:54:30.981882Z","steps":["trace[537251249] 'process raft request' (duration: 302.993925ms)","trace[537251249] 'compare' (duration: 150.954403ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:30.981959Z","caller":"traceutil/trace.go:171","msg":"trace[1129876843] linearizableReadLoop","detail":"{readStateIndex:25304; appliedIndex:25302; }","duration":"431.014596ms","start":"2026-06-06T05:54:30.550893Z","end":"2026-06-06T05:54:30.981908Z","steps":["trace[1129876843] 'read index received' (duration: 36.842317ms)","trace[1129876843] 'applied index is now lower than readState.Index' (duration: 394.170429ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:30.98208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:30.527357Z","time spent":"454.642885ms","remote":"127.0.0.1:39376","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-06-06T05:54:30.982094Z","caller":"traceutil/trace.go:171","msg":"trace[494214548] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"218.116091ms","start":"2026-06-06T05:54:30.76396Z","end":"2026-06-06T05:54:30.982076Z","steps":["trace[494214548] 'process raft request' (duration: 217.957767ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:30.982208Z","caller":"traceutil/trace.go:171","msg":"trace[1634443989] transaction","detail":"{read_only:false; response_revision:24504; number_of_response:1; }","duration":"208.93847ms","start":"2026-06-06T05:54:30.773251Z","end":"2026-06-06T05:54:30.982189Z","steps":["trace[1634443989] 'process raft request' (duration: 208.886019ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:30.982225Z","caller":"traceutil/trace.go:171","msg":"trace[2143271686] transaction","detail":"{read_only:false; response_revision:24501; number_of_response:1; }","duration":"273.628557ms","start":"2026-06-06T05:54:30.708588Z","end":"2026-06-06T05:54:30.982216Z","steps":["trace[2143271686] 'process raft request' (duration: 273.221328ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:30.982324Z","caller":"traceutil/trace.go:171","msg":"trace[1076041889] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"209.087554ms","start":"2026-06-06T05:54:30.773228Z","end":"2026-06-06T05:54:30.982316Z","steps":["trace[1076041889] 'process raft request' (duration: 208.803587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:30.982405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.470376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-06T05:54:30.982478Z","caller":"traceutil/trace.go:171","msg":"trace[716424252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24504; }","duration":"431.686761ms","start":"2026-06-06T05:54:30.550775Z","end":"2026-06-06T05:54:30.982462Z","steps":["trace[716424252] 'agreement among raft nodes before linearized reading' (duration: 431.404935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:30.98254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.537275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:30.982575Z","caller":"traceutil/trace.go:171","msg":"trace[1020336797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24504; }","duration":"202.588567ms","start":"2026-06-06T05:54:30.779977Z","end":"2026-06-06T05:54:30.982566Z","steps":["trace[1020336797] 'agreement among raft nodes before linearized reading' (duration: 202.541056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:30.982557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:30.550747Z","time spent":"431.796924ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-06T05:54:30.9825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.333215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T05:54:30.982895Z","caller":"traceutil/trace.go:171","msg":"trace[302066081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24504; }","duration":"429.737164ms","start":"2026-06-06T05:54:30.553144Z","end":"2026-06-06T05:54:30.982881Z","steps":["trace[302066081] 'agreement among raft nodes before linearized reading' (duration: 429.260433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:30.982957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:30.553138Z","time spent":"429.803846ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-06T05:54:31.149843Z","caller":"traceutil/trace.go:171","msg":"trace[263702096] transaction","detail":"{read_only:false; response_revision:24505; number_of_response:1; }","duration":"161.99703ms","start":"2026-06-06T05:54:30.98782Z","end":"2026-06-06T05:54:31.149817Z","steps":["trace[263702096] 'process raft request' (duration: 161.833016ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:31.179076Z","caller":"traceutil/trace.go:171","msg":"trace[2126410569] transaction","detail":"{read_only:false; response_revision:24506; number_of_response:1; }","duration":"189.460281ms","start":"2026-06-06T05:54:30.989585Z","end":"2026-06-06T05:54:31.179046Z","steps":["trace[2126410569] 'process raft request' (duration: 189.321738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:31.521242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.577388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-06T05:54:31.521359Z","caller":"traceutil/trace.go:171","msg":"trace[1272112327] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24506; }","duration":"195.739922ms","start":"2026-06-06T05:54:31.325603Z","end":"2026-06-06T05:54:31.521343Z","steps":["trace[1272112327] 'range keys from in-memory index tree' (duration: 195.323712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:31.52155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.916595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-06T05:54:31.52158Z","caller":"traceutil/trace.go:171","msg":"trace[1238836709] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24506; }","duration":"180.984196ms","start":"2026-06-06T05:54:31.340586Z","end":"2026-06-06T05:54:31.52157Z","steps":["trace[1238836709] 'range keys from in-memory index tree' (duration: 180.558856ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:31.521946Z","caller":"traceutil/trace.go:171","msg":"trace[1265003898] transaction","detail":"{read_only:false; response_revision:24507; number_of_response:1; }","duration":"181.183271ms","start":"2026-06-06T05:54:31.340753Z","end":"2026-06-06T05:54:31.521937Z","steps":["trace[1265003898] 'process raft request' (duration: 171.679853ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:31.934949Z","caller":"traceutil/trace.go:171","msg":"trace[1684808568] linearizableReadLoop","detail":"{readStateIndex:25313; appliedIndex:25312; }","duration":"154.642382ms","start":"2026-06-06T05:54:31.780285Z","end":"2026-06-06T05:54:31.934927Z","steps":["trace[1684808568] 'read index received' (duration: 154.423827ms)","trace[1684808568] 'applied index is now lower than readState.Index' (duration: 217.675µs)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:31.935135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.836957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:31.935176Z","caller":"traceutil/trace.go:171","msg":"trace[1202359806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24509; }","duration":"154.911529ms","start":"2026-06-06T05:54:31.780253Z","end":"2026-06-06T05:54:31.935164Z","steps":["trace[1202359806] 'agreement among raft nodes before linearized reading' (duration: 154.763695ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:31.935464Z","caller":"traceutil/trace.go:171","msg":"trace[749648227] transaction","detail":"{read_only:false; response_revision:24509; number_of_response:1; }","duration":"166.663402ms","start":"2026-06-06T05:54:31.76879Z","end":"2026-06-06T05:54:31.935454Z","steps":["trace[749648227] 'process raft request' (duration: 166.002896ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:32.432438Z","caller":"traceutil/trace.go:171","msg":"trace[552969701] linearizableReadLoop","detail":"{readStateIndex:25315; appliedIndex:25313; }","duration":"328.162729ms","start":"2026-06-06T05:54:32.104249Z","end":"2026-06-06T05:54:32.432412Z","steps":["trace[552969701] 'read index received' (duration: 69.047802ms)","trace[552969701] 'applied index is now lower than readState.Index' (duration: 259.113967ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:32.43248Z","caller":"traceutil/trace.go:171","msg":"trace[1274231927] transaction","detail":"{read_only:false; response_revision:24511; number_of_response:1; }","duration":"334.704776ms","start":"2026-06-06T05:54:32.097761Z","end":"2026-06-06T05:54:32.432466Z","steps":["trace[1274231927] 'process raft request' (duration: 334.496941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:32.432621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.369426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T05:54:32.432623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.871315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T05:54:32.432644Z","caller":"traceutil/trace.go:171","msg":"trace[826570189] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24511; }","duration":"328.444267ms","start":"2026-06-06T05:54:32.104194Z","end":"2026-06-06T05:54:32.432638Z","steps":["trace[826570189] 'agreement among raft nodes before linearized reading' (duration: 328.311913ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:32.432656Z","caller":"traceutil/trace.go:171","msg":"trace[411065700] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24511; }","duration":"234.960007ms","start":"2026-06-06T05:54:32.197685Z","end":"2026-06-06T05:54:32.432645Z","steps":["trace[411065700] 'agreement among raft nodes before linearized reading' (duration: 234.870694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:32.432667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:32.104178Z","time spent":"328.482238ms","remote":"127.0.0.1:45068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-06-06T05:54:32.432661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:32.097737Z","time spent":"334.846951ms","remote":"127.0.0.1:39376","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-06-06T05:54:32.432944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.013875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-06T05:54:32.433045Z","caller":"traceutil/trace.go:171","msg":"trace[411133923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24511; }","duration":"227.150528ms","start":"2026-06-06T05:54:32.205867Z","end":"2026-06-06T05:54:32.433017Z","steps":["trace[411133923] 'agreement among raft nodes before linearized reading' (duration: 226.912833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:32.659593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.309453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:32.65973Z","caller":"traceutil/trace.go:171","msg":"trace[538422691] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24512; }","duration":"146.502218ms","start":"2026-06-06T05:54:32.513205Z","end":"2026-06-06T05:54:32.659707Z","steps":["trace[538422691] 'count revisions from in-memory index tree' (duration: 146.21293ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:33.182231Z","caller":"traceutil/trace.go:171","msg":"trace[1926165930] transaction","detail":"{read_only:false; response_revision:24514; number_of_response:1; }","duration":"193.332934ms","start":"2026-06-06T05:54:32.988874Z","end":"2026-06-06T05:54:33.182207Z","steps":["trace[1926165930] 'process raft request' (duration: 193.16229ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:33.44668Z","caller":"traceutil/trace.go:171","msg":"trace[1096789010] transaction","detail":"{read_only:false; response_revision:24515; number_of_response:1; }","duration":"456.762625ms","start":"2026-06-06T05:54:32.989889Z","end":"2026-06-06T05:54:33.446652Z","steps":["trace[1096789010] 'process raft request' (duration: 456.486719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:33.44697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:32.989876Z","time spent":"456.885479ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:54:33.447038Z","caller":"traceutil/trace.go:171","msg":"trace[800195020] linearizableReadLoop","detail":"{readStateIndex:25321; appliedIndex:25318; }","duration":"339.224026ms","start":"2026-06-06T05:54:33.107782Z","end":"2026-06-06T05:54:33.447006Z","steps":["trace[800195020] 'read index received' (duration: 75.165868ms)","trace[800195020] 'applied index is now lower than readState.Index' (duration: 264.057038ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:33.447075Z","caller":"traceutil/trace.go:171","msg":"trace[1084062475] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"456.875068ms","start":"2026-06-06T05:54:32.990155Z","end":"2026-06-06T05:54:33.44703Z","steps":["trace[1084062475] 'process raft request' (duration: 456.447057ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:33.447107Z","caller":"traceutil/trace.go:171","msg":"trace[2061379961] transaction","detail":"{read_only:false; response_revision:24517; number_of_response:1; }","duration":"456.791096ms","start":"2026-06-06T05:54:32.990286Z","end":"2026-06-06T05:54:33.447077Z","steps":["trace[2061379961] 'process raft request' (duration: 456.595192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:33.447263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:32.990146Z","time spent":"457.011321ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:54:33.447275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:32.990278Z","time spent":"456.896779ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:54:33.44736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.570055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-06T05:54:33.447374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.022131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T05:54:33.447493Z","caller":"traceutil/trace.go:171","msg":"trace[1227595349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24517; }","duration":"292.184635ms","start":"2026-06-06T05:54:33.155283Z","end":"2026-06-06T05:54:33.447467Z","steps":["trace[1227595349] 'agreement among raft nodes before linearized reading' (duration: 291.918508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:33.447634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.875824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-06T05:54:33.447418Z","caller":"traceutil/trace.go:171","msg":"trace[1594082627] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:24517; }","duration":"339.664707ms","start":"2026-06-06T05:54:33.107734Z","end":"2026-06-06T05:54:33.447399Z","steps":["trace[1594082627] 'agreement among raft nodes before linearized reading' (duration: 339.461422ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:33.447684Z","caller":"traceutil/trace.go:171","msg":"trace[1960203913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24517; }","duration":"261.947727ms","start":"2026-06-06T05:54:33.185724Z","end":"2026-06-06T05:54:33.447672Z","steps":["trace[1960203913] 'agreement among raft nodes before linearized reading' (duration: 261.6831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:33.447721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:33.107576Z","time spent":"340.121858ms","remote":"127.0.0.1:54126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":75,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-06T05:54:33.78104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.036475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:54:33.781111Z","caller":"traceutil/trace.go:171","msg":"trace[718302587] linearizableReadLoop","detail":"{readStateIndex:25324; appliedIndex:25323; }","duration":"242.907108ms","start":"2026-06-06T05:54:33.538194Z","end":"2026-06-06T05:54:33.781101Z","steps":["trace[718302587] 'read index received' (duration: 96.834832ms)","trace[718302587] 'applied index is now lower than readState.Index' (duration: 146.071276ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:33.781239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.053781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-06T05:54:33.781253Z","caller":"traceutil/trace.go:171","msg":"trace[966765975] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24520; }","duration":"243.096672ms","start":"2026-06-06T05:54:33.538151Z","end":"2026-06-06T05:54:33.781247Z","steps":["trace[966765975] 'agreement among raft nodes before linearized reading' (duration: 242.975149ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:33.781483Z","caller":"traceutil/trace.go:171","msg":"trace[1591644495] transaction","detail":"{read_only:false; response_revision:24520; number_of_response:1; }","duration":"255.438499ms","start":"2026-06-06T05:54:33.525967Z","end":"2026-06-06T05:54:33.781405Z","steps":["trace[1591644495] 'process raft request' (duration: 109.097586ms)","trace[1591644495] 'compare' (duration: 145.673947ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:34.207359Z","caller":"traceutil/trace.go:171","msg":"trace[1228457222] linearizableReadLoop","detail":"{readStateIndex:25325; appliedIndex:25324; }","duration":"401.613067ms","start":"2026-06-06T05:54:33.805722Z","end":"2026-06-06T05:54:34.207335Z","steps":["trace[1228457222] 'read index received' (duration: 378.409879ms)","trace[1228457222] 'applied index is now lower than readState.Index' (duration: 23.201998ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:34.207428Z","caller":"traceutil/trace.go:171","msg":"trace[22315540] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"420.268047ms","start":"2026-06-06T05:54:33.787116Z","end":"2026-06-06T05:54:34.207384Z","steps":["trace[22315540] 'process raft request' (duration: 396.997447ms)","trace[22315540] 'compare' (duration: 22.782219ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:34.207483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.73706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T05:54:34.207538Z","caller":"traceutil/trace.go:171","msg":"trace[1535471289] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:24521; }","duration":"401.835353ms","start":"2026-06-06T05:54:33.805687Z","end":"2026-06-06T05:54:34.207522Z","steps":["trace[1535471289] 'agreement among raft nodes before linearized reading' (duration: 401.71543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:34.207581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:33.805672Z","time spent":"401.899154ms","remote":"127.0.0.1:42398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":30,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-06-06T05:54:34.207585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:33.787101Z","time spent":"420.40017ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:54:34.265447Z","caller":"traceutil/trace.go:171","msg":"trace[873282090] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"323.799774ms","start":"2026-06-06T05:54:33.941617Z","end":"2026-06-06T05:54:34.265417Z","steps":["trace[873282090] 'process raft request' (duration: 323.558229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:34.265598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:33.941589Z","time spent":"323.928498ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:54:34.656506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.778941ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-06T05:54:34.65662Z","caller":"traceutil/trace.go:171","msg":"trace[1169167725] linearizableReadLoop","detail":"{readStateIndex:25329; appliedIndex:25328; }","duration":"219.062472ms","start":"2026-06-06T05:54:34.43754Z","end":"2026-06-06T05:54:34.656602Z","steps":["trace[1169167725] 'read index received' (duration: 70.2137ms)","trace[1169167725] 'applied index is now lower than readState.Index' (duration: 148.847142ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:34.65709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.554586ms","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-06-06T05:54:34.657133Z","caller":"traceutil/trace.go:171","msg":"trace[1606383861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24524; }","duration":"219.636458ms","start":"2026-06-06T05:54:34.437486Z","end":"2026-06-06T05:54:34.657123Z","steps":["trace[1606383861] 'agreement among raft nodes before linearized reading' (duration: 219.476214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:34.664435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.929745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-06T05:54:34.664507Z","caller":"traceutil/trace.go:171","msg":"trace[1385217883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24525; }","duration":"169.055978ms","start":"2026-06-06T05:54:34.495436Z","end":"2026-06-06T05:54:34.664492Z","steps":["trace[1385217883] 'agreement among raft nodes before linearized reading' (duration: 168.762921ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:34.664581Z","caller":"traceutil/trace.go:171","msg":"trace[615328069] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"119.901687ms","start":"2026-06-06T05:54:34.544607Z","end":"2026-06-06T05:54:34.664509Z","steps":["trace[615328069] 'process raft request' (duration: 119.441736ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:34.95635Z","caller":"traceutil/trace.go:171","msg":"trace[1646445336] transaction","detail":"{read_only:false; response_revision:24527; number_of_response:1; }","duration":"293.529585ms","start":"2026-06-06T05:54:34.662795Z","end":"2026-06-06T05:54:34.956325Z","steps":["trace[1646445336] 'process raft request' (duration: 293.406673ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:34.956589Z","caller":"traceutil/trace.go:171","msg":"trace[2040939541] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"298.757241ms","start":"2026-06-06T05:54:34.65782Z","end":"2026-06-06T05:54:34.956578Z","steps":["trace[2040939541] 'process raft request' (duration: 199.661147ms)","trace[2040939541] 'compare' (duration: 98.450989ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:35.004506Z","caller":"traceutil/trace.go:171","msg":"trace[810110413] transaction","detail":"{read_only:false; response_revision:24528; number_of_response:1; }","duration":"335.500396ms","start":"2026-06-06T05:54:34.668984Z","end":"2026-06-06T05:54:35.004485Z","steps":["trace[810110413] 'process raft request' (duration: 335.357553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:35.004634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:34.66894Z","time spent":"335.631919ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:54:35.004787Z","caller":"traceutil/trace.go:171","msg":"trace[2113445150] transaction","detail":"{read_only:false; response_revision:24529; number_of_response:1; }","duration":"208.269444ms","start":"2026-06-06T05:54:34.796465Z","end":"2026-06-06T05:54:35.004735Z","steps":["trace[2113445150] 'process raft request' (duration: 207.985818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:35.323227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.93942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-06-06T05:54:35.323393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.571462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:35.32348Z","caller":"traceutil/trace.go:171","msg":"trace[849774780] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:24529; }","duration":"255.746436ms","start":"2026-06-06T05:54:35.067714Z","end":"2026-06-06T05:54:35.323461Z","steps":["trace[849774780] 'count revisions from in-memory index tree' (duration: 255.46248ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:35.323439Z","caller":"traceutil/trace.go:171","msg":"trace[1018682483] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24529; }","duration":"187.210856ms","start":"2026-06-06T05:54:35.136196Z","end":"2026-06-06T05:54:35.323407Z","steps":["trace[1018682483] 'range keys from in-memory index tree' (duration: 186.739155ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:35.323869Z","caller":"traceutil/trace.go:171","msg":"trace[548882481] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"133.854462ms","start":"2026-06-06T05:54:35.189978Z","end":"2026-06-06T05:54:35.323833Z","steps":["trace[548882481] 'process raft request' (duration: 113.724897ms)","trace[548882481] 'compare' (duration: 19.343665ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:35.439535Z","caller":"traceutil/trace.go:171","msg":"trace[709808352] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"110.645624ms","start":"2026-06-06T05:54:35.328861Z","end":"2026-06-06T05:54:35.439507Z","steps":["trace[709808352] 'process raft request' (duration: 109.540397ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:35.605235Z","caller":"traceutil/trace.go:171","msg":"trace[1833388097] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"151.363104ms","start":"2026-06-06T05:54:35.453837Z","end":"2026-06-06T05:54:35.6052Z","steps":["trace[1833388097] 'process raft request' (duration: 151.163029ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:35.605941Z","caller":"traceutil/trace.go:171","msg":"trace[1079302723] linearizableReadLoop","detail":"{readStateIndex:25339; appliedIndex:25336; }","duration":"134.830216ms","start":"2026-06-06T05:54:35.471075Z","end":"2026-06-06T05:54:35.605905Z","steps":["trace[1079302723] 'read index received' (duration: 133.939544ms)","trace[1079302723] 'applied index is now lower than readState.Index' (duration: 889.081µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:54:35.606003Z","caller":"traceutil/trace.go:171","msg":"trace[977415461] transaction","detail":"{read_only:false; response_revision:24533; number_of_response:1; }","duration":"148.862793ms","start":"2026-06-06T05:54:35.457106Z","end":"2026-06-06T05:54:35.605969Z","steps":["trace[977415461] 'process raft request' (duration: 148.469004ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:35.606108Z","caller":"traceutil/trace.go:171","msg":"trace[1420858547] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"145.195404ms","start":"2026-06-06T05:54:35.460874Z","end":"2026-06-06T05:54:35.606069Z","steps":["trace[1420858547] 'process raft request' (duration: 144.962339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:35.606284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.142753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T05:54:35.606341Z","caller":"traceutil/trace.go:171","msg":"trace[595091995] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24534; }","duration":"135.255225ms","start":"2026-06-06T05:54:35.47107Z","end":"2026-06-06T05:54:35.606325Z","steps":["trace[595091995] 'agreement among raft nodes before linearized reading' (duration: 135.001739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:35.606496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.817487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-06T05:54:35.606537Z","caller":"traceutil/trace.go:171","msg":"trace[1909379669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24534; }","duration":"110.9145ms","start":"2026-06-06T05:54:35.495614Z","end":"2026-06-06T05:54:35.606528Z","steps":["trace[1909379669] 'agreement among raft nodes before linearized reading' (duration: 110.741015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:35.6067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.876079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-06T05:54:35.606723Z","caller":"traceutil/trace.go:171","msg":"trace[290102015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24534; }","duration":"110.917981ms","start":"2026-06-06T05:54:35.495799Z","end":"2026-06-06T05:54:35.606717Z","steps":["trace[290102015] 'agreement among raft nodes before linearized reading' (duration: 110.852739ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:36.094169Z","caller":"traceutil/trace.go:171","msg":"trace[1304045888] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"268.669817ms","start":"2026-06-06T05:54:35.825476Z","end":"2026-06-06T05:54:36.094146Z","steps":["trace[1304045888] 'process raft request' (duration: 215.2129ms)","trace[1304045888] 'compare' (duration: 53.302214ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:36.350245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.470279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-06T05:54:36.350321Z","caller":"traceutil/trace.go:171","msg":"trace[1193844211] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24538; }","duration":"137.584632ms","start":"2026-06-06T05:54:36.212717Z","end":"2026-06-06T05:54:36.350302Z","steps":["trace[1193844211] 'range keys from in-memory index tree' (duration: 137.320355ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:36.486815Z","caller":"traceutil/trace.go:171","msg":"trace[179083815] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"130.688216ms","start":"2026-06-06T05:54:36.356017Z","end":"2026-06-06T05:54:36.486706Z","steps":["trace[179083815] 'process raft request' (duration: 128.049812ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:36.486884Z","caller":"traceutil/trace.go:171","msg":"trace[1062377355] transaction","detail":"{read_only:false; response_revision:24541; number_of_response:1; }","duration":"130.781908ms","start":"2026-06-06T05:54:36.35608Z","end":"2026-06-06T05:54:36.486862Z","steps":["trace[1062377355] 'process raft request' (duration: 130.586624ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:43.191245Z","caller":"traceutil/trace.go:171","msg":"trace[1855876226] linearizableReadLoop","detail":"{readStateIndex:25390; appliedIndex:25389; }","duration":"174.333287ms","start":"2026-06-06T05:54:43.016885Z","end":"2026-06-06T05:54:43.191218Z","steps":["trace[1855876226] 'read index received' (duration: 174.228054ms)","trace[1855876226] 'applied index is now lower than readState.Index' (duration: 103.552µs)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:43.191411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.5047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:43.191463Z","caller":"traceutil/trace.go:171","msg":"trace[398697623] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:24584; }","duration":"174.597213ms","start":"2026-06-06T05:54:43.016851Z","end":"2026-06-06T05:54:43.191449Z","steps":["trace[398697623] 'agreement among raft nodes before linearized reading' (duration: 174.492911ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:43.191352Z","caller":"traceutil/trace.go:171","msg":"trace[452699979] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"199.802659ms","start":"2026-06-06T05:54:42.991496Z","end":"2026-06-06T05:54:43.191299Z","steps":["trace[452699979] 'process raft request' (duration: 199.564964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:43.194812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.060449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:43.194884Z","caller":"traceutil/trace.go:171","msg":"trace[143506697] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24585; }","duration":"159.177212ms","start":"2026-06-06T05:54:43.035692Z","end":"2026-06-06T05:54:43.194869Z","steps":["trace[143506697] 'agreement among raft nodes before linearized reading' (duration: 159.036939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:43.195045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.211882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-06T05:54:43.195075Z","caller":"traceutil/trace.go:171","msg":"trace[1035958135] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"161.753173ms","start":"2026-06-06T05:54:43.03331Z","end":"2026-06-06T05:54:43.195063Z","steps":["trace[1035958135] 'process raft request' (duration: 161.320252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:43.195084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.907128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:43.195108Z","caller":"traceutil/trace.go:171","msg":"trace[1692358587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24585; }","duration":"149.294793ms","start":"2026-06-06T05:54:43.045797Z","end":"2026-06-06T05:54:43.195092Z","steps":["trace[1692358587] 'agreement among raft nodes before linearized reading' (duration: 149.116899ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:43.195135Z","caller":"traceutil/trace.go:171","msg":"trace[139502350] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24585; }","duration":"155.016381ms","start":"2026-06-06T05:54:43.040105Z","end":"2026-06-06T05:54:43.195121Z","steps":["trace[139502350] 'agreement among raft nodes before linearized reading' (duration: 154.909499ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:43.502122Z","caller":"traceutil/trace.go:171","msg":"trace[1878524669] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"302.094222ms","start":"2026-06-06T05:54:43.200009Z","end":"2026-06-06T05:54:43.502104Z","steps":["trace[1878524669] 'process raft request' (duration: 301.982039ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:43.502245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:43.199976Z","time spent":"302.204945ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:54:43.50299Z","caller":"traceutil/trace.go:171","msg":"trace[1165811263] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"154.648812ms","start":"2026-06-06T05:54:43.348316Z","end":"2026-06-06T05:54:43.502965Z","steps":["trace[1165811263] 'process raft request' (duration: 154.506539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:44.077446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.01123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:54:44.077556Z","caller":"traceutil/trace.go:171","msg":"trace[1621767846] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"444.535391ms","start":"2026-06-06T05:54:43.633002Z","end":"2026-06-06T05:54:44.077537Z","steps":["trace[1621767846] 'process raft request' (duration: 218.370247ms)","trace[1621767846] 'compare' (duration: 225.827386ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:44.077625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:43.632967Z","time spent":"444.629703ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:54:44.245666Z","caller":"traceutil/trace.go:171","msg":"trace[1235915045] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"611.778437ms","start":"2026-06-06T05:54:43.633845Z","end":"2026-06-06T05:54:44.245623Z","steps":["trace[1235915045] 'process raft request' (duration: 609.80087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:44.245892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:43.633823Z","time spent":"611.954091ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:54:44.246516Z","caller":"traceutil/trace.go:171","msg":"trace[751519385] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"612.515296ms","start":"2026-06-06T05:54:43.633982Z","end":"2026-06-06T05:54:44.246497Z","steps":["trace[751519385] 'process raft request' (duration: 611.566722ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:44.246543Z","caller":"traceutil/trace.go:171","msg":"trace[1648417284] linearizableReadLoop","detail":"{readStateIndex:25396; appliedIndex:25393; }","duration":"544.850386ms","start":"2026-06-06T05:54:43.701671Z","end":"2026-06-06T05:54:44.246521Z","steps":["trace[1648417284] 'read index received' (duration: 149.771986ms)","trace[1648417284] 'applied index is now lower than readState.Index' (duration: 395.07696ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:54:44.246626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:43.633973Z","time spent":"612.590747ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:54:44.246756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.68005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T05:54:44.246792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.364876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-06T05:54:44.246784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.079752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-06T05:54:44.246808Z","caller":"traceutil/trace.go:171","msg":"trace[1372633214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24591; }","duration":"465.766732ms","start":"2026-06-06T05:54:43.781035Z","end":"2026-06-06T05:54:44.246801Z","steps":["trace[1372633214] 'agreement among raft nodes before linearized reading' (duration: 465.69551ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:44.246819Z","caller":"traceutil/trace.go:171","msg":"trace[1018658160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24591; }","duration":"544.488488ms","start":"2026-06-06T05:54:43.702323Z","end":"2026-06-06T05:54:44.246812Z","steps":["trace[1018658160] 'agreement among raft nodes before linearized reading' (duration: 544.334555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:44.246832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:43.781018Z","time spent":"465.809593ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-06T05:54:44.246838Z","caller":"traceutil/trace.go:171","msg":"trace[1154640751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24591; }","duration":"545.197765ms","start":"2026-06-06T05:54:43.701626Z","end":"2026-06-06T05:54:44.246824Z","steps":["trace[1154640751] 'agreement among raft nodes before linearized reading' (duration: 544.99466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:44.246854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:43.702298Z","time spent":"544.548859ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-06T05:54:44.246885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:43.701604Z","time spent":"545.272857ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-06T05:54:44.246898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.45037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:54:44.247Z","caller":"traceutil/trace.go:171","msg":"trace[676351983] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:24591; }","duration":"179.615094ms","start":"2026-06-06T05:54:44.067362Z","end":"2026-06-06T05:54:44.246977Z","steps":["trace[676351983] 'agreement among raft nodes before linearized reading' (duration: 179.377509ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:44.246607Z","caller":"traceutil/trace.go:171","msg":"trace[174948440] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"400.687586ms","start":"2026-06-06T05:54:43.845864Z","end":"2026-06-06T05:54:44.246552Z","steps":["trace[174948440] 'process raft request' (duration: 400.525742ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:54:44.247253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:54:43.845843Z","time spent":"401.351491ms","remote":"127.0.0.1:39376","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-06-06T05:54:50.821512Z","caller":"traceutil/trace.go:171","msg":"trace[793285437] transaction","detail":"{read_only:false; response_revision:24642; number_of_response:1; }","duration":"264.18419ms","start":"2026-06-06T05:54:50.557297Z","end":"2026-06-06T05:54:50.821481Z","steps":["trace[793285437] 'process raft request' (duration: 263.987386ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:54:50.856923Z","caller":"traceutil/trace.go:171","msg":"trace[377311726] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"296.854426ms","start":"2026-06-06T05:54:50.560044Z","end":"2026-06-06T05:54:50.856898Z","steps":["trace[377311726] 'process raft request' (duration: 296.521288ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:03.528462Z","caller":"traceutil/trace.go:171","msg":"trace[1581984671] linearizableReadLoop","detail":"{readStateIndex:25556; appliedIndex:25555; }","duration":"234.141547ms","start":"2026-06-06T05:55:03.294293Z","end":"2026-06-06T05:55:03.528434Z","steps":["trace[1581984671] 'read index received' (duration: 233.960913ms)","trace[1581984671] 'applied index is now lower than readState.Index' (duration: 179.264µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:03.52857Z","caller":"traceutil/trace.go:171","msg":"trace[1378550956] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"251.982296ms","start":"2026-06-06T05:55:03.276548Z","end":"2026-06-06T05:55:03.52853Z","steps":["trace[1378550956] 'process raft request' (duration: 251.73117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:03.528784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.471734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-06T05:55:03.528873Z","caller":"traceutil/trace.go:171","msg":"trace[174883736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24746; }","duration":"234.610428ms","start":"2026-06-06T05:55:03.294248Z","end":"2026-06-06T05:55:03.528858Z","steps":["trace[174883736] 'agreement among raft nodes before linearized reading' (duration: 234.29153ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:04.041475Z","caller":"traceutil/trace.go:171","msg":"trace[526025809] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"508.354588ms","start":"2026-06-06T05:55:03.53309Z","end":"2026-06-06T05:55:04.041444Z","steps":["trace[526025809] 'process raft request' (duration: 505.299115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:04.041652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:03.533073Z","time spent":"508.479251ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:04.041946Z","caller":"traceutil/trace.go:171","msg":"trace[1419174329] linearizableReadLoop","detail":"{readStateIndex:25558; appliedIndex:25556; }","duration":"434.864018ms","start":"2026-06-06T05:55:03.607056Z","end":"2026-06-06T05:55:04.04192Z","steps":["trace[1419174329] 'read index received' (duration: 431.344244ms)","trace[1419174329] 'applied index is now lower than readState.Index' (duration: 3.518804ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:04.042032Z","caller":"traceutil/trace.go:171","msg":"trace[524497460] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"455.613418ms","start":"2026-06-06T05:55:03.586408Z","end":"2026-06-06T05:55:04.042022Z","steps":["trace[524497460] 'process raft request' (duration: 455.390523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:04.042078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:03.586379Z","time spent":"455.6747ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:04.042208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.135305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-06T05:55:04.042247Z","caller":"traceutil/trace.go:171","msg":"trace[1561717673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24748; }","duration":"435.229017ms","start":"2026-06-06T05:55:03.607008Z","end":"2026-06-06T05:55:04.042237Z","steps":["trace[1561717673] 'agreement among raft nodes before linearized reading' (duration: 435.054313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:04.042249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.383727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:04.042278Z","caller":"traceutil/trace.go:171","msg":"trace[1450418869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24748; }","duration":"262.442118ms","start":"2026-06-06T05:55:03.779827Z","end":"2026-06-06T05:55:04.042269Z","steps":["trace[1450418869] 'agreement among raft nodes before linearized reading' (duration: 262.390737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:04.042688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:03.606989Z","time spent":"435.688667ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-06T05:55:04.769191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.897851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:55:04.769299Z","caller":"traceutil/trace.go:171","msg":"trace[723267954] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"722.940453ms","start":"2026-06-06T05:55:04.046334Z","end":"2026-06-06T05:55:04.769274Z","steps":["trace[723267954] 'process raft request' (duration: 213.889718ms)","trace[723267954] 'compare' (duration: 508.674496ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:04.769342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.04632Z","time spent":"723.001535ms","remote":"127.0.0.1:39376","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-06-06T05:55:04.783271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185601210036984,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-06T05:55:04.794552Z","caller":"traceutil/trace.go:171","msg":"trace[2051277459] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"653.526463ms","start":"2026-06-06T05:55:04.141002Z","end":"2026-06-06T05:55:04.794528Z","steps":["trace[2051277459] 'process raft request' (duration: 653.4143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:04.794669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.140973Z","time spent":"653.630925ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:05.659084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:05.264775Z","time spent":"394.3015ms","remote":"127.0.0.1:34322","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-06T05:55:05.65958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.192347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:55:05.659915Z","caller":"traceutil/trace.go:171","msg":"trace[969326440] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"1.257668795s","start":"2026-06-06T05:55:04.402222Z","end":"2026-06-06T05:55:05.659891Z","steps":["trace[969326440] 'process raft request' (duration: 1.257562322s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:05.660037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.402196Z","time spent":"1.257782908s","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:05.66009Z","caller":"traceutil/trace.go:171","msg":"trace[958206014] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"728.512356ms","start":"2026-06-06T05:55:04.931565Z","end":"2026-06-06T05:55:05.660077Z","steps":["trace[958206014] 'process raft request' (duration: 728.475855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:05.660249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.931538Z","time spent":"728.630259ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:05.660308Z","caller":"traceutil/trace.go:171","msg":"trace[1197467836] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"1.309613926s","start":"2026-06-06T05:55:04.350671Z","end":"2026-06-06T05:55:05.660285Z","steps":["trace[1197467836] 'process raft request' (duration: 1.015611889s)","trace[1197467836] 'compare' (duration: 293.064884ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:05.660403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.350644Z","time spent":"1.309715338s","remote":"127.0.0.1:39376","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-06-06T05:55:05.660442Z","caller":"traceutil/trace.go:171","msg":"trace[1054497697] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"954.500167ms","start":"2026-06-06T05:55:04.705935Z","end":"2026-06-06T05:55:05.660435Z","steps":["trace[1054497697] 'process raft request' (duration: 953.997345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:05.660511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.705907Z","time spent":"954.583599ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:05.660555Z","caller":"traceutil/trace.go:171","msg":"trace[1946292351] linearizableReadLoop","detail":"{readStateIndex:25565; appliedIndex:25559; }","duration":"1.378257007s","start":"2026-06-06T05:55:04.282292Z","end":"2026-06-06T05:55:05.660549Z","steps":["trace[1946292351] 'read index received' (duration: 512.178369ms)","trace[1946292351] 'applied index is now lower than readState.Index' (duration: 866.077658ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:05.66042Z","caller":"traceutil/trace.go:171","msg":"trace[1135167965] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"1.216572206s","start":"2026-06-06T05:55:04.443841Z","end":"2026-06-06T05:55:05.660414Z","steps":["trace[1135167965] 'process raft request' (duration: 1.215996672s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:05.66064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.443822Z","time spent":"1.21677387s","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:05.660952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378657817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:05.660957Z","caller":"traceutil/trace.go:171","msg":"trace[70253231] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"1.28576383s","start":"2026-06-06T05:55:04.375173Z","end":"2026-06-06T05:55:05.660936Z","steps":["trace[70253231] 'process raft request' (duration: 1.284528451s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:05.661072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.375146Z","time spent":"1.285869183s","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:05.660983Z","caller":"traceutil/trace.go:171","msg":"trace[1513603445] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24756; }","duration":"1.378734188s","start":"2026-06-06T05:55:04.282242Z","end":"2026-06-06T05:55:05.660977Z","steps":["trace[1513603445] 'agreement among raft nodes before linearized reading' (duration: 1.378685157s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:05.661214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.282219Z","time spent":"1.378954674s","remote":"127.0.0.1:52098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-06T05:55:06.237811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.727718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:55:06.237919Z","caller":"traceutil/trace.go:171","msg":"trace[646245860] linearizableReadLoop","detail":"{readStateIndex:25567; appliedIndex:25566; }","duration":"577.339028ms","start":"2026-06-06T05:55:05.660564Z","end":"2026-06-06T05:55:06.237903Z","steps":["trace[646245860] 'read index received' (duration: 323.159909ms)","trace[646245860] 'applied index is now lower than readState.Index' (duration: 254.177929ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:06.237983Z","caller":"traceutil/trace.go:171","msg":"trace[2134061702] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"704.312894ms","start":"2026-06-06T05:55:05.53366Z","end":"2026-06-06T05:55:06.237973Z","steps":["trace[2134061702] 'process raft request' (duration: 450.33719ms)","trace[2134061702] 'compare' (duration: 253.37597ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:06.238025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:05.533637Z","time spent":"704.364805ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:06.238273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.385438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-06T05:55:06.238295Z","caller":"traceutil/trace.go:171","msg":"trace[696964310] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24757; }","duration":"778.442189ms","start":"2026-06-06T05:55:05.459844Z","end":"2026-06-06T05:55:06.238286Z","steps":["trace[696964310] 'agreement among raft nodes before linearized reading' (duration: 778.330276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.238312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:05.45982Z","time spent":"778.48895ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-06T05:55:06.23843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32434759s","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-06-06T05:55:06.238448Z","caller":"traceutil/trace.go:171","msg":"trace[1490788291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24757; }","duration":"1.32436519s","start":"2026-06-06T05:55:04.914077Z","end":"2026-06-06T05:55:06.238442Z","steps":["trace[1490788291] 'agreement among raft nodes before linearized reading' (duration: 1.324308229s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.238539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.914051Z","time spent":"1.324416482s","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-06T05:55:06.238553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.798209706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-06T05:55:06.238586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369385454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-06T05:55:06.238618Z","caller":"traceutil/trace.go:171","msg":"trace[1253841394] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:24757; }","duration":"1.369454756s","start":"2026-06-06T05:55:04.869154Z","end":"2026-06-06T05:55:06.238609Z","steps":["trace[1253841394] 'agreement among raft nodes before linearized reading' (duration: 1.369292492s)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:06.238619Z","caller":"traceutil/trace.go:171","msg":"trace[1625292726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24757; }","duration":"1.798325049s","start":"2026-06-06T05:55:04.440279Z","end":"2026-06-06T05:55:06.238604Z","steps":["trace[1625292726] 'agreement among raft nodes before linearized reading' (duration: 1.798119414s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.23864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.869117Z","time spent":"1.369517977s","remote":"127.0.0.1:39436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":178,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-06-06T05:55:06.238646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327710881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-06-06T05:55:06.23865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.440226Z","time spent":"1.798417282s","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-06T05:55:06.238666Z","caller":"traceutil/trace.go:171","msg":"trace[569073120] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24757; }","duration":"1.327767692s","start":"2026-06-06T05:55:04.910892Z","end":"2026-06-06T05:55:06.23866Z","steps":["trace[569073120] 'agreement among raft nodes before linearized reading' (duration: 1.327706421s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.238705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.91087Z","time spent":"1.327830763s","remote":"127.0.0.1:39376","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-06-06T05:55:06.238812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578128489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T05:55:06.238833Z","caller":"traceutil/trace.go:171","msg":"trace[1454805885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24757; }","duration":"1.57819078s","start":"2026-06-06T05:55:04.660635Z","end":"2026-06-06T05:55:06.238826Z","steps":["trace[1454805885] 'agreement among raft nodes before linearized reading' (duration: 1.578119589s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.238849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.660614Z","time spent":"1.578229921s","remote":"127.0.0.1:35950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-06T05:55:06.238799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458393706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T05:55:06.238865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.801109207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T05:55:06.238906Z","caller":"traceutil/trace.go:171","msg":"trace[1089186656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24757; }","duration":"1.801169688s","start":"2026-06-06T05:55:04.437724Z","end":"2026-06-06T05:55:06.238894Z","steps":["trace[1089186656] 'agreement among raft nodes before linearized reading' (duration: 1.800931972s)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:06.238906Z","caller":"traceutil/trace.go:171","msg":"trace[198825723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24757; }","duration":"1.458572371s","start":"2026-06-06T05:55:04.780301Z","end":"2026-06-06T05:55:06.238874Z","steps":["trace[198825723] 'agreement among raft nodes before linearized reading' (duration: 1.458386716s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.238945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.437716Z","time spent":"1.801222229s","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-06T05:55:06.238945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.780284Z","time spent":"1.458654652s","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-06T05:55:06.239029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.802897519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-06T05:55:06.239604Z","caller":"traceutil/trace.go:171","msg":"trace[1413981692] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:24757; }","duration":"1.803432752s","start":"2026-06-06T05:55:04.436057Z","end":"2026-06-06T05:55:06.23949Z","steps":["trace[1413981692] 'agreement among raft nodes before linearized reading' (duration: 1.802448099s)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.239764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:04.436044Z","time spent":"1.803661768s","remote":"127.0.0.1:39126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"info","ts":"2026-06-06T05:55:06.25039Z","caller":"traceutil/trace.go:171","msg":"trace[1253189489] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"201.880679ms","start":"2026-06-06T05:55:06.048485Z","end":"2026-06-06T05:55:06.250366Z","steps":["trace[1253189489] 'process raft request' (duration: 201.50242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.250538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.15343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-06T05:55:06.250591Z","caller":"traceutil/trace.go:171","msg":"trace[265087490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24758; }","duration":"203.249422ms","start":"2026-06-06T05:55:06.047328Z","end":"2026-06-06T05:55:06.250578Z","steps":["trace[265087490] 'agreement among raft nodes before linearized reading' (duration: 203.130129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.878366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.898742ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-06T05:55:06.878763Z","caller":"traceutil/trace.go:171","msg":"trace[896685606] linearizableReadLoop","detail":"{readStateIndex:25571; appliedIndex:25568; }","duration":"628.592312ms","start":"2026-06-06T05:55:06.250152Z","end":"2026-06-06T05:55:06.878744Z","steps":["trace[896685606] 'read index received' (duration: 477.255049ms)","trace[896685606] 'applied index is now lower than readState.Index' (duration: 151.336243ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:06.878886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.241264Z","time spent":"637.61937ms","remote":"127.0.0.1:39126","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-06T05:55:06.879301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.962258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:06.879384Z","caller":"traceutil/trace.go:171","msg":"trace[1386244098] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"629.852972ms","start":"2026-06-06T05:55:06.249512Z","end":"2026-06-06T05:55:06.879365Z","steps":["trace[1386244098] 'process raft request' (duration: 629.189766ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:06.879417Z","caller":"traceutil/trace.go:171","msg":"trace[1041730523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24760; }","duration":"638.144982ms","start":"2026-06-06T05:55:06.241242Z","end":"2026-06-06T05:55:06.879387Z","steps":["trace[1041730523] 'agreement among raft nodes before linearized reading' (duration: 637.945567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.879466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.241219Z","time spent":"638.237434ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-06T05:55:06.879503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.249497Z","time spent":"629.922863ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:06.879633Z","caller":"traceutil/trace.go:171","msg":"trace[1419444386] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"634.542146ms","start":"2026-06-06T05:55:06.244637Z","end":"2026-06-06T05:55:06.879179Z","steps":["trace[1419444386] 'process raft request' (duration: 633.86293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:06.87975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.24461Z","time spent":"635.037567ms","remote":"127.0.0.1:39376","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-06-06T05:55:07.146467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.039198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:55:07.146858Z","caller":"traceutil/trace.go:171","msg":"trace[1718253721] linearizableReadLoop","detail":"{readStateIndex:25577; appliedIndex:25571; }","duration":"268.011291ms","start":"2026-06-06T05:55:06.878831Z","end":"2026-06-06T05:55:07.146842Z","steps":["trace[1718253721] 'read index received' (duration: 108.519352ms)","trace[1718253721] 'applied index is now lower than readState.Index' (duration: 159.491049ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:07.146913Z","caller":"traceutil/trace.go:171","msg":"trace[205182130] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"884.064031ms","start":"2026-06-06T05:55:06.262813Z","end":"2026-06-06T05:55:07.146877Z","steps":["trace[205182130] 'process raft request' (duration: 883.852616ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:07.146948Z","caller":"traceutil/trace.go:171","msg":"trace[1584395695] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"347.225689ms","start":"2026-06-06T05:55:06.799672Z","end":"2026-06-06T05:55:07.146898Z","steps":["trace[1584395695] 'process raft request' (duration: 347.065055ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:07.14695Z","caller":"traceutil/trace.go:171","msg":"trace[2145907529] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"265.689475ms","start":"2026-06-06T05:55:06.881219Z","end":"2026-06-06T05:55:07.146909Z","steps":["trace[2145907529] 'process raft request' (duration: 265.576742ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:07.147116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.799633Z","time spent":"347.413013ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:07.147268Z","caller":"traceutil/trace.go:171","msg":"trace[143122695] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"884.388639ms","start":"2026-06-06T05:55:06.262864Z","end":"2026-06-06T05:55:07.147252Z","steps":["trace[143122695] 'process raft request' (duration: 883.829525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:07.147344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.262859Z","time spent":"884.44296ms","remote":"127.0.0.1:39376","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-06-06T05:55:07.147055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.576999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:07.147435Z","caller":"traceutil/trace.go:171","msg":"trace[371160329] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"884.696077ms","start":"2026-06-06T05:55:06.262717Z","end":"2026-06-06T05:55:07.147413Z","steps":["trace[371160329] 'process raft request' (duration: 883.915987ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:07.147423Z","caller":"traceutil/trace.go:171","msg":"trace[1992046359] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"884.775768ms","start":"2026-06-06T05:55:06.262612Z","end":"2026-06-06T05:55:07.147388Z","steps":["trace[1992046359] 'process raft request' (duration: 883.949238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:07.147466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.437072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:07.147471Z","caller":"traceutil/trace.go:171","msg":"trace[549831256] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24767; }","duration":"346.02615ms","start":"2026-06-06T05:55:06.801428Z","end":"2026-06-06T05:55:07.147454Z","steps":["trace[549831256] 'agreement among raft nodes before linearized reading' (duration: 345.517247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:07.147536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.262708Z","time spent":"884.771418ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:07.147565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.668309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-06T05:55:07.147481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.305289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T05:55:07.147587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.262597Z","time spent":"884.901542ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:07.147576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.801409Z","time spent":"346.146673ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-06-06T05:55:07.147641Z","caller":"traceutil/trace.go:171","msg":"trace[60036184] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24767; }","duration":"214.511884ms","start":"2026-06-06T05:55:06.933116Z","end":"2026-06-06T05:55:07.147628Z","steps":["trace[60036184] 'agreement among raft nodes before linearized reading' (duration: 214.315479ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:07.147477Z","caller":"traceutil/trace.go:171","msg":"trace[431639014] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"896.596343ms","start":"2026-06-06T05:55:06.250846Z","end":"2026-06-06T05:55:07.147443Z","steps":["trace[431639014] 'process raft request' (duration: 736.489228ms)","trace[431639014] 'compare' (duration: 158.914895ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:07.14767Z","caller":"traceutil/trace.go:171","msg":"trace[294265568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24767; }","duration":"374.761771ms","start":"2026-06-06T05:55:06.772842Z","end":"2026-06-06T05:55:07.147604Z","steps":["trace[294265568] 'agreement among raft nodes before linearized reading' (duration: 374.572567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:07.14771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.250827Z","time spent":"896.863979ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:07.147516Z","caller":"traceutil/trace.go:171","msg":"trace[1522473283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24767; }","duration":"258.518744ms","start":"2026-06-06T05:55:06.888984Z","end":"2026-06-06T05:55:07.147503Z","steps":["trace[1522473283] 'agreement among raft nodes before linearized reading' (duration: 258.424962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:07.147792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.772813Z","time spent":"374.897605ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-06T05:55:07.147583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:06.262805Z","time spent":"884.211844ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:07.400667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.918948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-06T05:55:07.400736Z","caller":"traceutil/trace.go:171","msg":"trace[527116353] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24768; }","duration":"108.038931ms","start":"2026-06-06T05:55:07.292678Z","end":"2026-06-06T05:55:07.400717Z","steps":["trace[527116353] 'range keys from in-memory index tree' (duration: 107.675673ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:07.834742Z","caller":"traceutil/trace.go:171","msg":"trace[225153932] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"167.87048ms","start":"2026-06-06T05:55:07.666848Z","end":"2026-06-06T05:55:07.834718Z","steps":["trace[225153932] 'process raft request' (duration: 167.687286ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:08.083752Z","caller":"traceutil/trace.go:171","msg":"trace[1708501804] linearizableReadLoop","detail":"{readStateIndex:25583; appliedIndex:25579; }","duration":"303.496467ms","start":"2026-06-06T05:55:07.780223Z","end":"2026-06-06T05:55:08.08372Z","steps":["trace[1708501804] 'read index received' (duration: 54.168855ms)","trace[1708501804] 'applied index is now lower than readState.Index' (duration: 249.326372ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:08.084208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.956808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:08.084254Z","caller":"traceutil/trace.go:171","msg":"trace[386244610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24772; }","duration":"304.05298ms","start":"2026-06-06T05:55:07.78019Z","end":"2026-06-06T05:55:08.084243Z","steps":["trace[386244610] 'agreement among raft nodes before linearized reading' (duration: 303.946578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:08.084284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:07.780174Z","time spent":"304.104081ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-06T05:55:08.084343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.113279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:08.084443Z","caller":"traceutil/trace.go:171","msg":"trace[2086153675] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:24772; }","duration":"208.311514ms","start":"2026-06-06T05:55:07.876104Z","end":"2026-06-06T05:55:08.084416Z","steps":["trace[2086153675] 'agreement among raft nodes before linearized reading' (duration: 208.086459ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:08.084451Z","caller":"traceutil/trace.go:171","msg":"trace[1948851336] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"416.488756ms","start":"2026-06-06T05:55:07.66793Z","end":"2026-06-06T05:55:08.084419Z","steps":["trace[1948851336] 'process raft request' (duration: 415.691957ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:08.0845Z","caller":"traceutil/trace.go:171","msg":"trace[895571225] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"416.420945ms","start":"2026-06-06T05:55:07.668067Z","end":"2026-06-06T05:55:08.084488Z","steps":["trace[895571225] 'process raft request' (duration: 415.599085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:08.084593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:07.668026Z","time spent":"416.497207ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:08.084764Z","caller":"traceutil/trace.go:171","msg":"trace[1179143853] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"416.873905ms","start":"2026-06-06T05:55:07.667842Z","end":"2026-06-06T05:55:08.084715Z","steps":["trace[1179143853] 'process raft request' (duration: 415.568444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:08.08499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:07.667816Z","time spent":"417.065569ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:08.085139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:07.667921Z","time spent":"416.601149ms","remote":"127.0.0.1:39376","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-06-06T05:55:08.626828Z","caller":"traceutil/trace.go:171","msg":"trace[97155695] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"375.10558ms","start":"2026-06-06T05:55:08.251684Z","end":"2026-06-06T05:55:08.62679Z","steps":["trace[97155695] 'process raft request' (duration: 339.013231ms)","trace[97155695] 'compare' (duration: 35.912564ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:08.62702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:08.251652Z","time spent":"375.268124ms","remote":"127.0.0.1:39376","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-06-06T05:55:08.629094Z","caller":"traceutil/trace.go:171","msg":"trace[35556721] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"102.900076ms","start":"2026-06-06T05:55:08.526168Z","end":"2026-06-06T05:55:08.629068Z","steps":["trace[35556721] 'process raft request' (duration: 102.856185ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:08.629443Z","caller":"traceutil/trace.go:171","msg":"trace[1280210847] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"374.056415ms","start":"2026-06-06T05:55:08.255379Z","end":"2026-06-06T05:55:08.629436Z","steps":["trace[1280210847] 'process raft request' (duration: 373.526272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:08.629552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:08.255346Z","time spent":"374.126126ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:09.190573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.211555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:09.190647Z","caller":"traceutil/trace.go:171","msg":"trace[34525477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24775; }","duration":"410.331368ms","start":"2026-06-06T05:55:08.7803Z","end":"2026-06-06T05:55:09.190631Z","steps":["trace[34525477] 'range keys from in-memory index tree' (duration: 410.074091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:09.190678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:08.780279Z","time spent":"410.393179ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-06T05:55:09.190796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.514834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-06-06T05:55:09.190832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.364808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T05:55:09.190952Z","caller":"traceutil/trace.go:171","msg":"trace[1886020260] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:24775; }","duration":"184.576213ms","start":"2026-06-06T05:55:09.006345Z","end":"2026-06-06T05:55:09.190921Z","steps":["trace[1886020260] 'count revisions from in-memory index tree' (duration: 184.234685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:09.191023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.294222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T05:55:09.19113Z","caller":"traceutil/trace.go:171","msg":"trace[2117360426] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:24775; }","duration":"441.464157ms","start":"2026-06-06T05:55:08.749643Z","end":"2026-06-06T05:55:09.191107Z","steps":["trace[2117360426] 'count revisions from in-memory index tree' (duration: 441.212861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:09.191134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.470465ms","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-06-06T05:55:09.19118Z","caller":"traceutil/trace.go:171","msg":"trace[385172703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24775; }","duration":"308.544707ms","start":"2026-06-06T05:55:08.882622Z","end":"2026-06-06T05:55:09.191167Z","steps":["trace[385172703] 'range keys from in-memory index tree' (duration: 308.152657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:09.191226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:08.882605Z","time spent":"308.612808ms","remote":"127.0.0.1:39376","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-06-06T05:55:09.190878Z","caller":"traceutil/trace.go:171","msg":"trace[1092316775] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24775; }","duration":"305.648358ms","start":"2026-06-06T05:55:08.885208Z","end":"2026-06-06T05:55:09.190857Z","steps":["trace[1092316775] 'range keys from in-memory index tree' (duration: 305.399911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:09.191391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:08.885201Z","time spent":"306.17676ms","remote":"127.0.0.1:39376","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-06-06T05:55:09.191184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:08.749623Z","time spent":"441.550158ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-06-06T05:55:09.4176Z","caller":"traceutil/trace.go:171","msg":"trace[1370746168] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"221.947733ms","start":"2026-06-06T05:55:09.195629Z","end":"2026-06-06T05:55:09.417577Z","steps":["trace[1370746168] 'process raft request' (duration: 221.879061ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:09.417616Z","caller":"traceutil/trace.go:171","msg":"trace[519590914] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"222.394973ms","start":"2026-06-06T05:55:09.195179Z","end":"2026-06-06T05:55:09.417574Z","steps":["trace[519590914] 'process raft request' (duration: 134.99535ms)","trace[519590914] 'compare' (duration: 87.206429ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:09.417964Z","caller":"traceutil/trace.go:171","msg":"trace[236071297] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"222.241689ms","start":"2026-06-06T05:55:09.195707Z","end":"2026-06-06T05:55:09.417949Z","steps":["trace[236071297] 'process raft request' (duration: 222.060405ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:09.418041Z","caller":"traceutil/trace.go:171","msg":"trace[888346302] linearizableReadLoop","detail":"{readStateIndex:25591; appliedIndex:25587; }","duration":"215.217061ms","start":"2026-06-06T05:55:09.20281Z","end":"2026-06-06T05:55:09.418027Z","steps":["trace[888346302] 'read index received' (duration: 127.343266ms)","trace[888346302] 'applied index is now lower than readState.Index' (duration: 87.868215ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:09.418039Z","caller":"traceutil/trace.go:171","msg":"trace[30235959] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"210.358924ms","start":"2026-06-06T05:55:09.207646Z","end":"2026-06-06T05:55:09.418005Z","steps":["trace[30235959] 'process raft request' (duration: 210.297762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:09.41821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.513445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-06T05:55:09.418244Z","caller":"traceutil/trace.go:171","msg":"trace[77201803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24781; }","duration":"251.603427ms","start":"2026-06-06T05:55:09.166632Z","end":"2026-06-06T05:55:09.418235Z","steps":["trace[77201803] 'agreement among raft nodes before linearized reading' (duration: 251.443903ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:09.41827Z","caller":"traceutil/trace.go:171","msg":"trace[402643374] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"222.608129ms","start":"2026-06-06T05:55:09.195648Z","end":"2026-06-06T05:55:09.418256Z","steps":["trace[402643374] 'process raft request' (duration: 221.897982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:09.41821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.710853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:09.418381Z","caller":"traceutil/trace.go:171","msg":"trace[847939025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24781; }","duration":"225.907637ms","start":"2026-06-06T05:55:09.192468Z","end":"2026-06-06T05:55:09.418375Z","steps":["trace[847939025] 'agreement among raft nodes before linearized reading' (duration: 225.711793ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:09.87586Z","caller":"traceutil/trace.go:171","msg":"trace[1045077421] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"158.761272ms","start":"2026-06-06T05:55:09.717064Z","end":"2026-06-06T05:55:09.875826Z","steps":["trace[1045077421] 'process raft request' (duration: 158.469494ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:11.519589Z","caller":"traceutil/trace.go:171","msg":"trace[158778548] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"169.255004ms","start":"2026-06-06T05:55:11.350307Z","end":"2026-06-06T05:55:11.519562Z","steps":["trace[158778548] 'process raft request' (duration: 147.561172ms)","trace[158778548] 'compare' (duration: 21.378964ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:13.669341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.903398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T05:55:13.669427Z","caller":"traceutil/trace.go:171","msg":"trace[322092900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24810; }","duration":"108.023371ms","start":"2026-06-06T05:55:13.561384Z","end":"2026-06-06T05:55:13.669408Z","steps":["trace[322092900] 'range keys from in-memory index tree' (duration: 107.725014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:13.669487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.232546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-06T05:55:13.669562Z","caller":"traceutil/trace.go:171","msg":"trace[1294237001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24810; }","duration":"108.355798ms","start":"2026-06-06T05:55:13.56119Z","end":"2026-06-06T05:55:13.669546Z","steps":["trace[1294237001] 'range keys from in-memory index tree' (duration: 108.01739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:13.669487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.103653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-06-06T05:55:13.66958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.225315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-06T05:55:13.66958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.021931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-06T05:55:13.669631Z","caller":"traceutil/trace.go:171","msg":"trace[1439882628] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24810; }","duration":"108.313368ms","start":"2026-06-06T05:55:13.5613Z","end":"2026-06-06T05:55:13.669614Z","steps":["trace[1439882628] 'range keys from in-memory index tree' (duration: 107.892817ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:13.669665Z","caller":"traceutil/trace.go:171","msg":"trace[1742354598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24810; }","duration":"108.350128ms","start":"2026-06-06T05:55:13.561292Z","end":"2026-06-06T05:55:13.669642Z","steps":["trace[1742354598] 'range keys from in-memory index tree' (duration: 107.887268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:13.669713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.00417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-06T05:55:13.669665Z","caller":"traceutil/trace.go:171","msg":"trace[1845429219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24810; }","duration":"108.152503ms","start":"2026-06-06T05:55:13.561497Z","end":"2026-06-06T05:55:13.669649Z","steps":["trace[1845429219] 'range keys from in-memory index tree' (duration: 107.814356ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:13.669815Z","caller":"traceutil/trace.go:171","msg":"trace[1295103714] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24810; }","duration":"135.171544ms","start":"2026-06-06T05:55:13.534619Z","end":"2026-06-06T05:55:13.66979Z","steps":["trace[1295103714] 'range keys from in-memory index tree' (duration: 134.787375ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:13.851256Z","caller":"traceutil/trace.go:171","msg":"trace[2047886372] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"178.090288ms","start":"2026-06-06T05:55:13.67314Z","end":"2026-06-06T05:55:13.85123Z","steps":["trace[2047886372] 'process raft request' (duration: 177.934724ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:14.006099Z","caller":"traceutil/trace.go:171","msg":"trace[198651177] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"332.497624ms","start":"2026-06-06T05:55:13.673535Z","end":"2026-06-06T05:55:14.006032Z","steps":["trace[198651177] 'process raft request' (duration: 243.547203ms)","trace[198651177] 'compare' (duration: 88.662844ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:14.006208Z","caller":"traceutil/trace.go:171","msg":"trace[1858557028] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"331.005748ms","start":"2026-06-06T05:55:13.675193Z","end":"2026-06-06T05:55:14.006199Z","steps":["trace[1858557028] 'process raft request' (duration: 330.827464ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:14.006298Z","caller":"traceutil/trace.go:171","msg":"trace[1553813924] linearizableReadLoop","detail":"{readStateIndex:25629; appliedIndex:25624; }","duration":"226.088113ms","start":"2026-06-06T05:55:13.780189Z","end":"2026-06-06T05:55:14.006277Z","steps":["trace[1553813924] 'read index received' (duration: 71.398779ms)","trace[1553813924] 'applied index is now lower than readState.Index' (duration: 154.687944ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:14.006333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:13.675171Z","time spent":"331.07301ms","remote":"127.0.0.1:39376","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-06-06T05:55:14.006331Z","caller":"traceutil/trace.go:171","msg":"trace[506519534] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"330.719591ms","start":"2026-06-06T05:55:13.675559Z","end":"2026-06-06T05:55:14.006278Z","steps":["trace[506519534] 'process raft request' (duration: 330.502506ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:14.006376Z","caller":"traceutil/trace.go:171","msg":"trace[1833965143] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"332.408312ms","start":"2026-06-06T05:55:13.673938Z","end":"2026-06-06T05:55:14.006347Z","steps":["trace[1833965143] 'process raft request' (duration: 332.039864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:14.006418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:13.673509Z","time spent":"332.711059ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:14.006455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.243376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T05:55:14.006529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:13.673934Z","time spent":"332.521825ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:14.006564Z","caller":"traceutil/trace.go:171","msg":"trace[166658371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24817; }","duration":"226.38927ms","start":"2026-06-06T05:55:13.780143Z","end":"2026-06-06T05:55:14.006532Z","steps":["trace[166658371] 'agreement among raft nodes before linearized reading' (duration: 226.209705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:14.006595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:13.675548Z","time spent":"330.902416ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:14.006126Z","caller":"traceutil/trace.go:171","msg":"trace[590800992] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"332.218547ms","start":"2026-06-06T05:55:13.67387Z","end":"2026-06-06T05:55:14.006089Z","steps":["trace[590800992] 'process raft request' (duration: 332.041483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:14.006706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:13.673854Z","time spent":"332.826142ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-06T05:55:14.229687Z","caller":"traceutil/trace.go:171","msg":"trace[1943446634] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"125.858459ms","start":"2026-06-06T05:55:14.103672Z","end":"2026-06-06T05:55:14.229531Z","steps":["trace[1943446634] 'process raft request' (duration: 53.821865ms)","trace[1943446634] 'compare' (duration: 71.654755ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:14.230263Z","caller":"traceutil/trace.go:171","msg":"trace[726456154] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"122.183271ms","start":"2026-06-06T05:55:14.108064Z","end":"2026-06-06T05:55:14.230247Z","steps":["trace[726456154] 'process raft request' (duration: 122.13811ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:14.230384Z","caller":"traceutil/trace.go:171","msg":"trace[999164133] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"125.104692ms","start":"2026-06-06T05:55:14.105207Z","end":"2026-06-06T05:55:14.230312Z","steps":["trace[999164133] 'process raft request' (duration: 124.139449ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:16.081627Z","caller":"traceutil/trace.go:171","msg":"trace[604451509] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"127.784637ms","start":"2026-06-06T05:55:15.953813Z","end":"2026-06-06T05:55:16.081597Z","steps":["trace[604451509] 'process raft request' (duration: 84.443393ms)","trace[604451509] 'compare' (duration: 42.495503ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:16.254962Z","caller":"traceutil/trace.go:171","msg":"trace[82101974] transaction","detail":"{read_only:false; response_revision:24832; number_of_response:1; }","duration":"167.954263ms","start":"2026-06-06T05:55:16.086976Z","end":"2026-06-06T05:55:16.254931Z","steps":["trace[82101974] 'process raft request' (duration: 167.499211ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:16.25495Z","caller":"traceutil/trace.go:171","msg":"trace[749511187] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"167.910592ms","start":"2026-06-06T05:55:16.086999Z","end":"2026-06-06T05:55:16.25491Z","steps":["trace[749511187] 'process raft request' (duration: 167.736887ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:16.255103Z","caller":"traceutil/trace.go:171","msg":"trace[1529640207] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"167.80581ms","start":"2026-06-06T05:55:16.087279Z","end":"2026-06-06T05:55:16.255084Z","steps":["trace[1529640207] 'process raft request' (duration: 167.577604ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:16.255138Z","caller":"traceutil/trace.go:171","msg":"trace[322851057] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"167.226286ms","start":"2026-06-06T05:55:16.087907Z","end":"2026-06-06T05:55:16.255133Z","steps":["trace[322851057] 'process raft request' (duration: 167.076932ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:16.255091Z","caller":"traceutil/trace.go:171","msg":"trace[1548225590] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"119.308062ms","start":"2026-06-06T05:55:16.135763Z","end":"2026-06-06T05:55:16.255071Z","steps":["trace[1548225590] 'process raft request' (duration: 119.271522ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:22.858298Z","caller":"traceutil/trace.go:171","msg":"trace[301456240] linearizableReadLoop","detail":"{readStateIndex:25707; appliedIndex:25706; }","duration":"178.536857ms","start":"2026-06-06T05:55:22.679736Z","end":"2026-06-06T05:55:22.858273Z","steps":["trace[301456240] 'read index received' (duration: 178.514037ms)","trace[301456240] 'applied index is now lower than readState.Index' (duration: 22.14µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:22.858393Z","caller":"traceutil/trace.go:171","msg":"trace[1728896464] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"179.0528ms","start":"2026-06-06T05:55:22.679331Z","end":"2026-06-06T05:55:22.858384Z","steps":["trace[1728896464] 'process raft request' (duration: 178.806264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:22.859462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.716096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-06T05:55:22.859489Z","caller":"traceutil/trace.go:171","msg":"trace[716348539] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24892; }","duration":"179.814158ms","start":"2026-06-06T05:55:22.679668Z","end":"2026-06-06T05:55:22.859482Z","steps":["trace[716348539] 'agreement among raft nodes before linearized reading' (duration: 178.754753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:22.875367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.74242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-06T05:55:22.8754Z","caller":"traceutil/trace.go:171","msg":"trace[640786606] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24893; }","duration":"155.828651ms","start":"2026-06-06T05:55:22.719563Z","end":"2026-06-06T05:55:22.875392Z","steps":["trace[640786606] 'agreement among raft nodes before linearized reading' (duration: 155.723599ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:22.875388Z","caller":"traceutil/trace.go:171","msg":"trace[1162746358] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"194.01525ms","start":"2026-06-06T05:55:22.681334Z","end":"2026-06-06T05:55:22.875349Z","steps":["trace[1162746358] 'process raft request' (duration: 187.847912ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:48.562811Z","caller":"traceutil/trace.go:171","msg":"trace[1039866412] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"138.598626ms","start":"2026-06-06T05:55:48.424134Z","end":"2026-06-06T05:55:48.562732Z","steps":["trace[1039866412] 'process raft request' (duration: 90.676663ms)","trace[1039866412] 'compare' (duration: 47.657537ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:48.657671Z","caller":"traceutil/trace.go:171","msg":"trace[1270171012] linearizableReadLoop","detail":"{readStateIndex:25909; appliedIndex:25906; }","duration":"215.502918ms","start":"2026-06-06T05:55:48.442137Z","end":"2026-06-06T05:55:48.65764Z","steps":["trace[1270171012] 'read index received' (duration: 72.608938ms)","trace[1270171012] 'applied index is now lower than readState.Index' (duration: 142.88873ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:48.657763Z","caller":"traceutil/trace.go:171","msg":"trace[1463832401] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"230.064478ms","start":"2026-06-06T05:55:48.427671Z","end":"2026-06-06T05:55:48.657736Z","steps":["trace[1463832401] 'process raft request' (duration: 229.901164ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:48.657753Z","caller":"traceutil/trace.go:171","msg":"trace[1783693599] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"230.899697ms","start":"2026-06-06T05:55:48.426798Z","end":"2026-06-06T05:55:48.657698Z","steps":["trace[1783693599] 'process raft request' (duration: 230.551499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:48.657922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.942902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:48.657964Z","caller":"traceutil/trace.go:171","msg":"trace[581478470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25089; }","duration":"185.055194ms","start":"2026-06-06T05:55:48.472898Z","end":"2026-06-06T05:55:48.657954Z","steps":["trace[581478470] 'agreement among raft nodes before linearized reading' (duration: 184.947062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:48.657969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.806945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-06T05:55:48.658033Z","caller":"traceutil/trace.go:171","msg":"trace[361192133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25089; }","duration":"215.928258ms","start":"2026-06-06T05:55:48.442091Z","end":"2026-06-06T05:55:48.658019Z","steps":["trace[361192133] 'agreement among raft nodes before linearized reading' (duration: 215.714223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:48.658074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.345713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-06T05:55:48.658108Z","caller":"traceutil/trace.go:171","msg":"trace[230490041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25089; }","duration":"168.454406ms","start":"2026-06-06T05:55:48.489642Z","end":"2026-06-06T05:55:48.658096Z","steps":["trace[230490041] 'agreement among raft nodes before linearized reading' (duration: 168.312941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:48.658035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.947365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-06T05:55:48.658162Z","caller":"traceutil/trace.go:171","msg":"trace[381017767] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25089; }","duration":"139.115259ms","start":"2026-06-06T05:55:48.519036Z","end":"2026-06-06T05:55:48.658151Z","steps":["trace[381017767] 'agreement among raft nodes before linearized reading' (duration: 138.882412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:48.658219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.391478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-06T05:55:48.658234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.162383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T05:55:48.658258Z","caller":"traceutil/trace.go:171","msg":"trace[1014246605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25089; }","duration":"147.210033ms","start":"2026-06-06T05:55:48.51104Z","end":"2026-06-06T05:55:48.65825Z","steps":["trace[1014246605] 'agreement among raft nodes before linearized reading' (duration: 147.140712ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:48.658257Z","caller":"traceutil/trace.go:171","msg":"trace[1995927785] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25089; }","duration":"184.46714ms","start":"2026-06-06T05:55:48.47378Z","end":"2026-06-06T05:55:48.658247Z","steps":["trace[1995927785] 'agreement among raft nodes before linearized reading' (duration: 184.398689ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:48.781892Z","caller":"traceutil/trace.go:171","msg":"trace[354169186] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"118.38061ms","start":"2026-06-06T05:55:48.663492Z","end":"2026-06-06T05:55:48.781873Z","steps":["trace[354169186] 'process raft request' (duration: 118.240557ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:48.782173Z","caller":"traceutil/trace.go:171","msg":"trace[602613725] linearizableReadLoop","detail":"{readStateIndex:25913; appliedIndex:25909; }","duration":"118.269367ms","start":"2026-06-06T05:55:48.663884Z","end":"2026-06-06T05:55:48.782154Z","steps":["trace[602613725] 'read index received' (duration: 35.869443ms)","trace[602613725] 'applied index is now lower than readState.Index' (duration: 82.398694ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:48.782233Z","caller":"traceutil/trace.go:171","msg":"trace[200839556] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"118.440132ms","start":"2026-06-06T05:55:48.663782Z","end":"2026-06-06T05:55:48.782222Z","steps":["trace[200839556] 'process raft request' (duration: 118.272928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:48.782397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.492762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-06T05:55:48.782162Z","caller":"traceutil/trace.go:171","msg":"trace[1116269956] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"118.411271ms","start":"2026-06-06T05:55:48.663726Z","end":"2026-06-06T05:55:48.782137Z","steps":["trace[1116269956] 'process raft request' (duration: 118.116014ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:48.782436Z","caller":"traceutil/trace.go:171","msg":"trace[497023619] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25093; }","duration":"118.549283ms","start":"2026-06-06T05:55:48.663876Z","end":"2026-06-06T05:55:48.782425Z","steps":["trace[497023619] 'agreement among raft nodes before linearized reading' (duration: 118.40064ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:49.074366Z","caller":"traceutil/trace.go:171","msg":"trace[202064508] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"115.205643ms","start":"2026-06-06T05:55:48.959125Z","end":"2026-06-06T05:55:49.074331Z","steps":["trace[202064508] 'process raft request' (duration: 114.978148ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:49.092243Z","caller":"traceutil/trace.go:171","msg":"trace[542453946] linearizableReadLoop","detail":"{readStateIndex:25915; appliedIndex:25913; }","duration":"103.901901ms","start":"2026-06-06T05:55:48.988313Z","end":"2026-06-06T05:55:49.092215Z","steps":["trace[542453946] 'read index received' (duration: 85.834127ms)","trace[542453946] 'applied index is now lower than readState.Index' (duration: 18.066844ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:49.092314Z","caller":"traceutil/trace.go:171","msg":"trace[854779188] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"106.624467ms","start":"2026-06-06T05:55:48.985652Z","end":"2026-06-06T05:55:49.092277Z","steps":["trace[854779188] 'process raft request' (duration: 106.422002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:49.092447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.097576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-06T05:55:49.092527Z","caller":"traceutil/trace.go:171","msg":"trace[1760072305] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25095; }","duration":"104.24261ms","start":"2026-06-06T05:55:48.988266Z","end":"2026-06-06T05:55:49.092508Z","steps":["trace[1760072305] 'agreement among raft nodes before linearized reading' (duration: 104.052185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:50.096782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.594633ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-06T05:55:50.096897Z","caller":"traceutil/trace.go:171","msg":"trace[1212379815] linearizableReadLoop","detail":"{readStateIndex:25917; appliedIndex:25916; }","duration":"117.96923ms","start":"2026-06-06T05:55:49.978909Z","end":"2026-06-06T05:55:50.096878Z","steps":["trace[1212379815] 'read index received' (duration: 33.531µs)","trace[1212379815] 'applied index is now lower than readState.Index' (duration: 117.934309ms)"],"step_count":2} {"level":"warn","ts":"2026-06-06T05:55:50.097354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.431471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-06-06T05:55:50.097391Z","caller":"traceutil/trace.go:171","msg":"trace[407482680] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25096; }","duration":"118.499042ms","start":"2026-06-06T05:55:49.978879Z","end":"2026-06-06T05:55:50.097378Z","steps":["trace[407482680] 'agreement among raft nodes before linearized reading' (duration: 118.255236ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:50.53649Z","caller":"traceutil/trace.go:171","msg":"trace[627165688] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"164.554442ms","start":"2026-06-06T05:55:50.371916Z","end":"2026-06-06T05:55:50.53647Z","steps":["trace[627165688] 'process raft request' (duration: 164.438419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:50.87316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.766946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-06T05:55:50.873433Z","caller":"traceutil/trace.go:171","msg":"trace[1027284712] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"303.148727ms","start":"2026-06-06T05:55:50.57025Z","end":"2026-06-06T05:55:50.873399Z","steps":["trace[1027284712] 'process raft request' (duration: 121.020233ms)","trace[1027284712] 'compare' (duration: 181.593992ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:50.873474Z","caller":"traceutil/trace.go:171","msg":"trace[2093320056] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"208.665062ms","start":"2026-06-06T05:55:50.664779Z","end":"2026-06-06T05:55:50.873444Z","steps":["trace[2093320056] 'process raft request' (duration: 208.525819ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:50.873444Z","caller":"traceutil/trace.go:171","msg":"trace[1426038251] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25920; }","duration":"260.502451ms","start":"2026-06-06T05:55:50.612904Z","end":"2026-06-06T05:55:50.873406Z","steps":["trace[1426038251] 'read index received' (duration: 78.419788ms)","trace[1426038251] 'applied index is now lower than readState.Index' (duration: 182.079802ms)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:55:50.873584Z","caller":"traceutil/trace.go:171","msg":"trace[506393300] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"208.076699ms","start":"2026-06-06T05:55:50.665477Z","end":"2026-06-06T05:55:50.873554Z","steps":["trace[506393300] 'process raft request' (duration: 207.909825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:50.873618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-06T05:55:50.570204Z","time spent":"303.311471ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-06T05:55:50.873606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.681485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-06T05:55:50.873783Z","caller":"traceutil/trace.go:171","msg":"trace[111197869] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25102; }","duration":"260.86056ms","start":"2026-06-06T05:55:50.612843Z","end":"2026-06-06T05:55:50.873704Z","steps":["trace[111197869] 'agreement among raft nodes before linearized reading' (duration: 260.656285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:55:50.875225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.640761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T05:55:50.875284Z","caller":"traceutil/trace.go:171","msg":"trace[2044857311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25103; }","duration":"156.739703ms","start":"2026-06-06T05:55:50.718531Z","end":"2026-06-06T05:55:50.875271Z","steps":["trace[2044857311] 'agreement among raft nodes before linearized reading' (duration: 156.534378ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:55:50.876364Z","caller":"traceutil/trace.go:171","msg":"trace[1514162539] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"136.390763ms","start":"2026-06-06T05:55:50.739925Z","end":"2026-06-06T05:55:50.875121Z","steps":["trace[1514162539] 'process raft request' (duration: 135.0122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:57:21.464061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.629165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-06T05:57:21.464173Z","caller":"traceutil/trace.go:171","msg":"trace[434692280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25870; }","duration":"118.784939ms","start":"2026-06-06T05:57:21.345365Z","end":"2026-06-06T05:57:21.46415Z","steps":["trace[434692280] 'range keys from in-memory index tree' (duration: 118.432201ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:57:31.272815Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23363} {"level":"info","ts":"2026-06-06T05:57:31.406265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23363,"took":"129.278922ms","hash":2038844792,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":34328576,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-06T05:57:31.406342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2038844792,"revision":23363,"compact-revision":20127} {"level":"info","ts":"2026-06-06T05:59:20.02099Z","caller":"traceutil/trace.go:171","msg":"trace[1897098110] linearizableReadLoop","detail":"{readStateIndex:27602; appliedIndex:27601; }","duration":"101.703578ms","start":"2026-06-06T05:59:19.919259Z","end":"2026-06-06T05:59:20.020962Z","steps":["trace[1897098110] 'read index received' (duration: 101.595725ms)","trace[1897098110] 'applied index is now lower than readState.Index' (duration: 106.613µs)"],"step_count":2} {"level":"info","ts":"2026-06-06T05:59:20.021126Z","caller":"traceutil/trace.go:171","msg":"trace[1060407358] transaction","detail":"{read_only:false; response_revision:26735; number_of_response:1; }","duration":"154.118259ms","start":"2026-06-06T05:59:19.866977Z","end":"2026-06-06T05:59:20.021095Z","steps":["trace[1060407358] 'process raft request' (duration: 153.793181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:59:20.021235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.933723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-06T05:59:20.021276Z","caller":"traceutil/trace.go:171","msg":"trace[338027673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26735; }","duration":"102.054256ms","start":"2026-06-06T05:59:19.919212Z","end":"2026-06-06T05:59:20.021266Z","steps":["trace[338027673] 'agreement among raft nodes before linearized reading' (duration: 101.875102ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T05:59:20.044138Z","caller":"traceutil/trace.go:171","msg":"trace[2093413014] transaction","detail":"{read_only:false; response_revision:26736; number_of_response:1; }","duration":"116.583286ms","start":"2026-06-06T05:59:19.927505Z","end":"2026-06-06T05:59:20.044089Z","steps":["trace[2093413014] 'process raft request' (duration: 116.449972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-06T05:59:20.044439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.132724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-06T05:59:20.044513Z","caller":"traceutil/trace.go:171","msg":"trace[25316902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26736; }","duration":"118.237257ms","start":"2026-06-06T05:59:19.926261Z","end":"2026-06-06T05:59:20.044499Z","steps":["trace[25316902] 'agreement among raft nodes before linearized reading' (duration: 117.97312ms)"],"step_count":1} {"level":"info","ts":"2026-06-06T06:00:13.262482Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006dcb.wal"} {"level":"info","ts":"2026-06-06T06:02:31.279992Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25941} {"level":"info","ts":"2026-06-06T06:02:31.404779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25941,"took":"120.52309ms","hash":1601782084,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":34050048,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-06T06:02:31.404856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1601782084,"revision":25941,"compact-revision":23363} {"level":"info","ts":"2026-06-06T06:03:17.064058Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-06T06:03:17.071979Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-06T06:03:17.072601Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-06T06:07:31.284919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28598} {"level":"info","ts":"2026-06-06T06:07:31.414511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28598,"took":"125.822598ms","hash":4097272543,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":32825344,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-06T06:07:31.414584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4097272543,"revision":28598,"compact-revision":25941} {"level":"info","ts":"2026-06-06T06:12:31.289597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31133} {"level":"info","ts":"2026-06-06T06:12:31.425821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31133,"took":"132.015038ms","hash":3145571485,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":30347264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-06T06:12:31.425896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3145571485,"revision":31133,"compact-revision":28598} {"level":"info","ts":"2026-06-06T06:17:31.29785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33393} {"level":"info","ts":"2026-06-06T06:17:31.444533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33393,"took":"142.451189ms","hash":2171354043,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":30068736,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-06T06:17:31.444587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2171354043,"revision":33393,"compact-revision":31133} {"level":"info","ts":"2026-06-06T06:22:31.304609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35629} {"level":"info","ts":"2026-06-06T06:22:31.444627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35629,"took":"135.293831ms","hash":1282634020,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31698944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-06T06:22:31.444749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1282634020,"revision":35629,"compact-revision":33393} {"level":"info","ts":"2026-06-06T06:24:23.628303Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-06T06:24:23.633443Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-06T06:24:23.633682Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-06T06:27:31.31175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37931} {"level":"info","ts":"2026-06-06T06:27:31.437475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37931,"took":"121.88583ms","hash":2482440983,"current-db-size-bytes":60776448,"current-db-size":"61 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-06T06:27:31.437591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2482440983,"revision":37931,"compact-revision":35629}