{"level":"warn","ts":"2026-04-20T05:51:07.332374Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T05:51:07.332499Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.209: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.19.213.209:2380","--initial-cluster=instance=https://199.19.213.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.209: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-04-20T05:51:07.332578Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T05:51:07.332596Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.209:2380"]} {"level":"info","ts":"2026-04-20T05:51:07.332622Z","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-04-20T05:51:07.333289Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"]} {"level":"info","ts":"2026-04-20T05:51:07.333388Z","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.19.213.209:2380"],"listen-peer-urls":["https://199.19.213.209:2380"],"advertise-client-urls":["https://199.19.213.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.209: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-04-20T05:51:07.337538Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.866829ms"} {"level":"info","ts":"2026-04-20T05:51:07.348891Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9409e054be1d0d72","cluster-id":"ff5f9c0688884e7d"} {"level":"info","ts":"2026-04-20T05:51:07.349154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T05:51:07.349224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became follower at term 0"} {"level":"info","ts":"2026-04-20T05:51:07.349264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9409e054be1d0d72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T05:51:07.349279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became follower at term 1"} {"level":"info","ts":"2026-04-20T05:51:07.349338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=(10667303846975180146)"} {"level":"warn","ts":"2026-04-20T05:51:07.356572Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T05:51:07.358061Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T05:51:07.359254Z","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-04-20T05:51:07.362286Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9409e054be1d0d72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T05:51:07.362426Z","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-04-20T05:51:07.362527Z","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-04-20T05:51:07.362541Z","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-04-20T05:51:07.362484Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9409e054be1d0d72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T05:51:07.364484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=(10667303846975180146)"} {"level":"info","ts":"2026-04-20T05:51:07.364701Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff5f9c0688884e7d","local-member-id":"9409e054be1d0d72","added-peer-id":"9409e054be1d0d72","added-peer-peer-urls":["https://199.19.213.209:2380"]} {"level":"info","ts":"2026-04-20T05:51:07.366296Z","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-04-20T05:51:07.366372Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.209:2380"} {"level":"info","ts":"2026-04-20T05:51:07.366415Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.209:2380"} {"level":"info","ts":"2026-04-20T05:51:07.36655Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9409e054be1d0d72","initial-advertise-peer-urls":["https://199.19.213.209:2380"],"listen-peer-urls":["https://199.19.213.209:2380"],"advertise-client-urls":["https://199.19.213.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T05:51:07.366596Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T05:51:08.050368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T05:51:08.050488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T05:51:08.050513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 received MsgPreVoteResp from 9409e054be1d0d72 at term 1"} {"level":"info","ts":"2026-04-20T05:51:08.050542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became candidate at term 2"} {"level":"info","ts":"2026-04-20T05:51:08.05055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 received MsgVoteResp from 9409e054be1d0d72 at term 2"} {"level":"info","ts":"2026-04-20T05:51:08.050564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became leader at term 2"} {"level":"info","ts":"2026-04-20T05:51:08.050575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9409e054be1d0d72 elected leader 9409e054be1d0d72 at term 2"} {"level":"info","ts":"2026-04-20T05:51:08.051869Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9409e054be1d0d72","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.209:2379]}","request-path":"/0/members/9409e054be1d0d72/attributes","cluster-id":"ff5f9c0688884e7d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T05:51:08.051887Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:51:08.051904Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:51:08.051987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:51:08.052195Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T05:51:08.052308Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T05:51:08.053107Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff5f9c0688884e7d","local-member-id":"9409e054be1d0d72","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:51:08.053338Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:51:08.053401Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:51:08.055191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T05:51:08.05728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.209:2379"} {"level":"info","ts":"2026-04-20T05:51:19.771537Z","caller":"traceutil/trace.go:171","msg":"trace[708433382] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"167.158524ms","start":"2026-04-20T05:51:19.604353Z","end":"2026-04-20T05:51:19.771511Z","steps":["trace[708433382] 'process raft request' (duration: 166.9903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:20.21567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.346309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:51:20.21587Z","caller":"traceutil/trace.go:171","msg":"trace[887530502] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"331.393944ms","start":"2026-04-20T05:51:19.884452Z","end":"2026-04-20T05:51:20.215846Z","steps":["trace[887530502] 'process raft request' (duration: 180.069122ms)","trace[887530502] 'compare' (duration: 150.147365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:51:20.215954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:19.884421Z","time spent":"331.497537ms","remote":"127.0.0.1:39446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:51:20.404345Z","caller":"traceutil/trace.go:171","msg":"trace[1912363336] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"177.040222ms","start":"2026-04-20T05:51:20.227269Z","end":"2026-04-20T05:51:20.404309Z","steps":["trace[1912363336] 'process raft request' (duration: 175.943887ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:40.596623Z","caller":"traceutil/trace.go:171","msg":"trace[1423538268] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"149.03299ms","start":"2026-04-20T05:51:40.447539Z","end":"2026-04-20T05:51:40.596572Z","steps":["trace[1423538268] 'process raft request' (duration: 148.16996ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:40.602696Z","caller":"traceutil/trace.go:171","msg":"trace[198389648] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"101.412292ms","start":"2026-04-20T05:51:40.501254Z","end":"2026-04-20T05:51:40.602666Z","steps":["trace[198389648] 'process raft request' (duration: 101.184946ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:40.91868Z","caller":"traceutil/trace.go:171","msg":"trace[1424311002] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"101.165606ms","start":"2026-04-20T05:51:40.817495Z","end":"2026-04-20T05:51:40.918661Z","steps":["trace[1424311002] 'read index received' (duration: 100.769997ms)","trace[1424311002] 'applied index is now lower than readState.Index' (duration: 372.299µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:40.918752Z","caller":"traceutil/trace.go:171","msg":"trace[1862847607] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"105.464709ms","start":"2026-04-20T05:51:40.81326Z","end":"2026-04-20T05:51:40.918725Z","steps":["trace[1862847607] 'process raft request' (duration: 105.039119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:40.91897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.446893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-04-20T05:51:40.919046Z","caller":"traceutil/trace.go:171","msg":"trace[1687109398] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:574; }","duration":"101.594426ms","start":"2026-04-20T05:51:40.817436Z","end":"2026-04-20T05:51:40.91903Z","steps":["trace[1687109398] 'agreement among raft nodes before linearized reading' (duration: 101.318749ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:40.936471Z","caller":"traceutil/trace.go:171","msg":"trace[227231824] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"116.418866ms","start":"2026-04-20T05:51:40.820009Z","end":"2026-04-20T05:51:40.936428Z","steps":["trace[227231824] 'process raft request' (duration: 116.314814ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:44.863274Z","caller":"traceutil/trace.go:171","msg":"trace[2004459090] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"281.410775ms","start":"2026-04-20T05:51:44.581822Z","end":"2026-04-20T05:51:44.863233Z","steps":["trace[2004459090] 'process raft request' (duration: 276.528759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:44.863535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.369818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:51:44.86366Z","caller":"traceutil/trace.go:171","msg":"trace[1538427295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"279.501521ms","start":"2026-04-20T05:51:44.584131Z","end":"2026-04-20T05:51:44.863632Z","steps":["trace[1538427295] 'agreement among raft nodes before linearized reading' (duration: 279.246875ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:44.863771Z","caller":"traceutil/trace.go:171","msg":"trace[427927519] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"279.076191ms","start":"2026-04-20T05:51:44.584145Z","end":"2026-04-20T05:51:44.863222Z","steps":["trace[427927519] 'read index received' (duration: 274.225585ms)","trace[427927519] 'applied index is now lower than readState.Index' (duration: 4.850056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:51:44.863932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.679869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T05:51:44.863978Z","caller":"traceutil/trace.go:171","msg":"trace[354476678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"188.760281ms","start":"2026-04-20T05:51:44.67519Z","end":"2026-04-20T05:51:44.863951Z","steps":["trace[354476678] 'agreement among raft nodes before linearized reading' (duration: 188.677569ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:47.010752Z","caller":"traceutil/trace.go:171","msg":"trace[1837568914] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"134.809503ms","start":"2026-04-20T05:51:46.875919Z","end":"2026-04-20T05:51:47.010728Z","steps":["trace[1837568914] 'read index received' (duration: 134.610048ms)","trace[1837568914] 'applied index is now lower than readState.Index' (duration: 198.405µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:47.010853Z","caller":"traceutil/trace.go:171","msg":"trace[1717252020] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"135.09728ms","start":"2026-04-20T05:51:46.875732Z","end":"2026-04-20T05:51:47.01083Z","steps":["trace[1717252020] 'process raft request' (duration: 134.836304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:47.011028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.0923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T05:51:47.011088Z","caller":"traceutil/trace.go:171","msg":"trace[2135741350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"135.215803ms","start":"2026-04-20T05:51:46.875852Z","end":"2026-04-20T05:51:47.011068Z","steps":["trace[2135741350] 'agreement among raft nodes before linearized reading' (duration: 134.992617ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:48.196443Z","caller":"traceutil/trace.go:171","msg":"trace[392458888] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"348.624268ms","start":"2026-04-20T05:51:47.847804Z","end":"2026-04-20T05:51:48.196429Z","steps":["trace[392458888] 'read index received' (duration: 348.503385ms)","trace[392458888] 'applied index is now lower than readState.Index' (duration: 120.203µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:48.196541Z","caller":"traceutil/trace.go:171","msg":"trace[175115822] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"413.730351ms","start":"2026-04-20T05:51:47.782776Z","end":"2026-04-20T05:51:48.196506Z","steps":["trace[175115822] 'process raft request' (duration: 413.535136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:48.196625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.802792ms","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-04-20T05:51:48.196645Z","caller":"traceutil/trace.go:171","msg":"trace[1456238752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:643; }","duration":"348.865754ms","start":"2026-04-20T05:51:47.847773Z","end":"2026-04-20T05:51:48.196639Z","steps":["trace[1456238752] 'agreement among raft nodes before linearized reading' (duration: 348.732031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:48.196668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:47.847757Z","time spent":"348.907095ms","remote":"127.0.0.1:39532","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-04-20T05:51:48.1968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:47.782745Z","time spent":"413.890315ms","remote":"127.0.0.1:39532","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-04-20T05:51:48.196811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.653825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T05:51:48.196928Z","caller":"traceutil/trace.go:171","msg":"trace[465894961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"160.812919ms","start":"2026-04-20T05:51:48.036102Z","end":"2026-04-20T05:51:48.196915Z","steps":["trace[465894961] 'agreement among raft nodes before linearized reading' (duration: 160.510762ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:48.309925Z","caller":"traceutil/trace.go:171","msg":"trace[721443351] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"109.206716ms","start":"2026-04-20T05:51:48.200698Z","end":"2026-04-20T05:51:48.309905Z","steps":["trace[721443351] 'process raft request' (duration: 106.305448ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:48.317028Z","caller":"traceutil/trace.go:171","msg":"trace[374622633] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"116.139671ms","start":"2026-04-20T05:51:48.200859Z","end":"2026-04-20T05:51:48.316999Z","steps":["trace[374622633] 'process raft request' (duration: 115.521397ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:04.396811Z","caller":"traceutil/trace.go:171","msg":"trace[1312208141] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"124.091599ms","start":"2026-04-20T05:52:04.272693Z","end":"2026-04-20T05:52:04.396785Z","steps":["trace[1312208141] 'process raft request' (duration: 123.741091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:37.051346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010220913811109,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T05:52:37.107772Z","caller":"traceutil/trace.go:171","msg":"trace[547784335] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"557.461408ms","start":"2026-04-20T05:52:36.550291Z","end":"2026-04-20T05:52:37.107752Z","steps":["trace[547784335] 'read index received' (duration: 550.932392ms)","trace[547784335] 'applied index is now lower than readState.Index' (duration: 6.528156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:52:37.107941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.380246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T05:52:37.107964Z","caller":"traceutil/trace.go:171","msg":"trace[263990919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"557.439598ms","start":"2026-04-20T05:52:36.550518Z","end":"2026-04-20T05:52:37.107958Z","steps":["trace[263990919] 'agreement among raft nodes before linearized reading' (duration: 557.326435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:37.107984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:36.550509Z","time spent":"557.470858ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T05:52:37.10805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.480803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T05:52:37.108047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.720804ms","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-04-20T05:52:37.108131Z","caller":"traceutil/trace.go:171","msg":"trace[297501938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"553.582306ms","start":"2026-04-20T05:52:36.554529Z","end":"2026-04-20T05:52:37.108111Z","steps":["trace[297501938] 'agreement among raft nodes before linearized reading' (duration: 553.366441ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:37.108158Z","caller":"traceutil/trace.go:171","msg":"trace[1694912755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"557.874028ms","start":"2026-04-20T05:52:36.550255Z","end":"2026-04-20T05:52:37.108129Z","steps":["trace[1694912755] 'agreement among raft nodes before linearized reading' (duration: 557.580521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:37.108178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:36.554522Z","time spent":"553.647868ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T05:52:37.108233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:36.550235Z","time spent":"557.98048ms","remote":"127.0.0.1:39532","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-04-20T05:52:37.108507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.852567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:1 size:1905"} {"level":"info","ts":"2026-04-20T05:52:37.108565Z","caller":"traceutil/trace.go:171","msg":"trace[2142106371] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:1; response_revision:1216; }","duration":"159.96642ms","start":"2026-04-20T05:52:36.948583Z","end":"2026-04-20T05:52:37.108549Z","steps":["trace[2142106371] 'agreement among raft nodes before linearized reading' (duration: 159.812586ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:53:16.251225Z","caller":"traceutil/trace.go:171","msg":"trace[431334633] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1869; }","duration":"262.80166ms","start":"2026-04-20T05:53:15.988402Z","end":"2026-04-20T05:53:16.251203Z","steps":["trace[431334633] 'read index received' (duration: 262.615156ms)","trace[431334633] 'applied index is now lower than readState.Index' (duration: 185.404µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:53:16.251366Z","caller":"traceutil/trace.go:171","msg":"trace[534901756] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"315.546556ms","start":"2026-04-20T05:53:15.935788Z","end":"2026-04-20T05:53:16.251334Z","steps":["trace[534901756] 'process raft request' (duration: 315.222178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:16.251413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.996754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8632b991-7a13-45a5-a41c-31ffadcb3cba\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T05:53:16.251441Z","caller":"traceutil/trace.go:171","msg":"trace[1449317656] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8632b991-7a13-45a5-a41c-31ffadcb3cba; range_end:; response_count:1; response_revision:1803; }","duration":"263.075416ms","start":"2026-04-20T05:53:15.988358Z","end":"2026-04-20T05:53:16.251433Z","steps":["trace[1449317656] 'agreement among raft nodes before linearized reading' (duration: 262.937293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:16.251523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:53:15.935763Z","time spent":"315.674329ms","remote":"127.0.0.1:39532","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-04-20T05:53:16.765449Z","caller":"traceutil/trace.go:171","msg":"trace[53124609] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"179.334551ms","start":"2026-04-20T05:53:16.586085Z","end":"2026-04-20T05:53:16.765419Z","steps":["trace[53124609] 'read index received' (duration: 133.851048ms)","trace[53124609] 'applied index is now lower than readState.Index' (duration: 45.481293ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:53:16.765684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.569407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:53:16.765768Z","caller":"traceutil/trace.go:171","msg":"trace[1037687132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1804; }","duration":"179.69586ms","start":"2026-04-20T05:53:16.586052Z","end":"2026-04-20T05:53:16.765748Z","steps":["trace[1037687132] 'agreement among raft nodes before linearized reading' (duration: 179.512456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:06.132281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.107114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:54:06.132445Z","caller":"traceutil/trace.go:171","msg":"trace[252471176] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"127.809768ms","start":"2026-04-20T05:54:06.004609Z","end":"2026-04-20T05:54:06.132419Z","steps":["trace[252471176] 'process raft request' (duration: 13.456748ms)","trace[252471176] 'compare' (duration: 113.948619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:54:06.568273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.07076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:54:06.568433Z","caller":"traceutil/trace.go:171","msg":"trace[444914433] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2131; }","duration":"143.271034ms","start":"2026-04-20T05:54:06.425138Z","end":"2026-04-20T05:54:06.568409Z","steps":["trace[444914433] 'count revisions from in-memory index tree' (duration: 142.938686ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:54:06.568483Z","caller":"traceutil/trace.go:171","msg":"trace[1799158226] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"121.77432ms","start":"2026-04-20T05:54:06.446677Z","end":"2026-04-20T05:54:06.568451Z","steps":["trace[1799158226] 'process raft request' (duration: 121.178576ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:55:29.686304Z","caller":"traceutil/trace.go:171","msg":"trace[1872050675] linearizableReadLoop","detail":"{readStateIndex:2880; appliedIndex:2879; }","duration":"100.256726ms","start":"2026-04-20T05:55:29.586022Z","end":"2026-04-20T05:55:29.686279Z","steps":["trace[1872050675] 'read index received' (duration: 100.02196ms)","trace[1872050675] 'applied index is now lower than readState.Index' (duration: 233.695µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:55:29.686373Z","caller":"traceutil/trace.go:171","msg":"trace[1512387248] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"141.693007ms","start":"2026-04-20T05:55:29.544636Z","end":"2026-04-20T05:55:29.686329Z","steps":["trace[1512387248] 'process raft request' (duration: 141.460351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:55:29.686568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.526942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:55:29.686654Z","caller":"traceutil/trace.go:171","msg":"trace[1141884436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2775; }","duration":"100.657545ms","start":"2026-04-20T05:55:29.585975Z","end":"2026-04-20T05:55:29.686632Z","steps":["trace[1141884436] 'agreement among raft nodes before linearized reading' (duration: 100.394899ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:19.722173Z","caller":"traceutil/trace.go:171","msg":"trace[1360246306] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4934; }","duration":"132.104376ms","start":"2026-04-20T05:59:19.590052Z","end":"2026-04-20T05:59:19.722156Z","steps":["trace[1360246306] 'read index received' (duration: 132.051724ms)","trace[1360246306] 'applied index is now lower than readState.Index' (duration: 51.792µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:59:19.722275Z","caller":"traceutil/trace.go:171","msg":"trace[1572186195] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"147.839957ms","start":"2026-04-20T05:59:19.574409Z","end":"2026-04-20T05:59:19.722249Z","steps":["trace[1572186195] 'process raft request' (duration: 147.616691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:59:19.722348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.29017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:59:19.722386Z","caller":"traceutil/trace.go:171","msg":"trace[1697948521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4754; }","duration":"132.366642ms","start":"2026-04-20T05:59:19.59001Z","end":"2026-04-20T05:59:19.722377Z","steps":["trace[1697948521] 'agreement among raft nodes before linearized reading' (duration: 132.240749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:59:19.753397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.407512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196043"} {"level":"info","ts":"2026-04-20T05:59:19.753489Z","caller":"traceutil/trace.go:171","msg":"trace[2059511648] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4755; }","duration":"101.542335ms","start":"2026-04-20T05:59:19.651927Z","end":"2026-04-20T05:59:19.753469Z","steps":["trace[2059511648] 'agreement among raft nodes before linearized reading' (duration: 99.638237ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:25.949915Z","caller":"traceutil/trace.go:171","msg":"trace[52783466] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"152.886723ms","start":"2026-04-20T05:59:25.797Z","end":"2026-04-20T05:59:25.949887Z","steps":["trace[52783466] 'process raft request' (duration: 152.660907ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:43.308304Z","caller":"traceutil/trace.go:171","msg":"trace[1224348479] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"162.05494ms","start":"2026-04-20T05:59:43.146209Z","end":"2026-04-20T05:59:43.308264Z","steps":["trace[1224348479] 'process raft request' (duration: 106.031447ms)","trace[1224348479] 'compare' (duration: 55.87939ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:59:43.30838Z","caller":"traceutil/trace.go:171","msg":"trace[456314678] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5531; }","duration":"155.926566ms","start":"2026-04-20T05:59:43.152429Z","end":"2026-04-20T05:59:43.308356Z","steps":["trace[456314678] 'read index received' (duration: 50.995967ms)","trace[456314678] 'applied index is now lower than readState.Index' (duration: 104.927689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:59:43.308558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.119142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-20T05:59:43.308626Z","caller":"traceutil/trace.go:171","msg":"trace[579530456] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5338; }","duration":"156.223224ms","start":"2026-04-20T05:59:43.152388Z","end":"2026-04-20T05:59:43.308612Z","steps":["trace[579530456] 'agreement among raft nodes before linearized reading' (duration: 156.041959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:59:43.628092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.153507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:59:43.628207Z","caller":"traceutil/trace.go:171","msg":"trace[1456077794] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"251.286379ms","start":"2026-04-20T05:59:43.376904Z","end":"2026-04-20T05:59:43.62819Z","steps":["trace[1456077794] 'read index received' (duration: 87.960947ms)","trace[1456077794] 'applied index is now lower than readState.Index' (duration: 163.323952ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:59:43.628334Z","caller":"traceutil/trace.go:171","msg":"trace[180709452] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"262.640182ms","start":"2026-04-20T05:59:43.365656Z","end":"2026-04-20T05:59:43.628297Z","steps":["trace[180709452] 'process raft request' (duration: 99.183716ms)","trace[180709452] 'compare' (duration: 162.966163ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:59:43.628459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.551055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-04-20T05:59:43.628458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.362199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-20T05:59:43.628473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.203705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-20T05:59:43.628474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.170007ms","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-04-20T05:59:43.628515Z","caller":"traceutil/trace.go:171","msg":"trace[279430031] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5339; }","duration":"251.663629ms","start":"2026-04-20T05:59:43.376838Z","end":"2026-04-20T05:59:43.628501Z","steps":["trace[279430031] 'agreement among raft nodes before linearized reading' (duration: 251.476394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:59:43.628525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.880753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T05:59:43.628529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.59692ms","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-04-20T05:59:43.628569Z","caller":"traceutil/trace.go:171","msg":"trace[282434127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5339; }","duration":"177.28205ms","start":"2026-04-20T05:59:43.451253Z","end":"2026-04-20T05:59:43.628535Z","steps":["trace[282434127] 'agreement among raft nodes before linearized reading' (duration: 177.083945ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:43.628615Z","caller":"traceutil/trace.go:171","msg":"trace[1614977169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5339; }","duration":"153.687262ms","start":"2026-04-20T05:59:43.474909Z","end":"2026-04-20T05:59:43.628596Z","steps":["trace[1614977169] 'agreement among raft nodes before linearized reading' (duration: 153.491137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:43.628524Z","caller":"traceutil/trace.go:171","msg":"trace[327781502] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5339; }","duration":"152.458202ms","start":"2026-04-20T05:59:43.476051Z","end":"2026-04-20T05:59:43.628509Z","steps":["trace[327781502] 'agreement among raft nodes before linearized reading' (duration: 152.285138ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:43.62862Z","caller":"traceutil/trace.go:171","msg":"trace[2078224004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5339; }","duration":"148.982466ms","start":"2026-04-20T05:59:43.479604Z","end":"2026-04-20T05:59:43.628586Z","steps":["trace[2078224004] 'agreement among raft nodes before linearized reading' (duration: 148.792121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:59:43.628631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.254953ms","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-04-20T05:59:43.628544Z","caller":"traceutil/trace.go:171","msg":"trace[1299482345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5339; }","duration":"148.327608ms","start":"2026-04-20T05:59:43.480199Z","end":"2026-04-20T05:59:43.628527Z","steps":["trace[1299482345] 'agreement among raft nodes before linearized reading' (duration: 148.134594ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:43.628719Z","caller":"traceutil/trace.go:171","msg":"trace[1134433642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5339; }","duration":"147.334275ms","start":"2026-04-20T05:59:43.481369Z","end":"2026-04-20T05:59:43.628703Z","steps":["trace[1134433642] 'agreement among raft nodes before linearized reading' (duration: 147.202031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:59:52.956044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.010636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xwsr7\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T05:59:52.956163Z","caller":"traceutil/trace.go:171","msg":"trace[1312242994] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xwsr7; range_end:; response_count:1; response_revision:5484; }","duration":"102.176861ms","start":"2026-04-20T05:59:52.85396Z","end":"2026-04-20T05:59:52.956137Z","steps":["trace[1312242994] 'range keys from in-memory index tree' (duration: 101.75134ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:53.335161Z","caller":"traceutil/trace.go:171","msg":"trace[438772182] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"114.541578ms","start":"2026-04-20T05:59:53.220595Z","end":"2026-04-20T05:59:53.335137Z","steps":["trace[438772182] 'process raft request' (duration: 79.988728ms)","trace[438772182] 'compare' (duration: 34.438607ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:59:53.343702Z","caller":"traceutil/trace.go:171","msg":"trace[1228809875] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"122.558828ms","start":"2026-04-20T05:59:53.221136Z","end":"2026-04-20T05:59:53.343694Z","steps":["trace[1228809875] 'process raft request' (duration: 122.476606ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:53.343878Z","caller":"traceutil/trace.go:171","msg":"trace[1611090935] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"122.477706ms","start":"2026-04-20T05:59:53.221365Z","end":"2026-04-20T05:59:53.343843Z","steps":["trace[1611090935] 'process raft request' (duration: 122.303402ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:53.343982Z","caller":"traceutil/trace.go:171","msg":"trace[672118105] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"122.589299ms","start":"2026-04-20T05:59:53.221378Z","end":"2026-04-20T05:59:53.343967Z","steps":["trace[672118105] 'process raft request' (duration: 122.409125ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:01:08.077919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-04-20T06:01:08.153543Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"74.084233ms","hash":163756660,"current-db-size-bytes":58753024,"current-db-size":"59 MB","current-db-size-in-use-bytes":33787904,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T06:01:08.153603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":163756660,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-04-20T06:02:06.705575Z","caller":"traceutil/trace.go:171","msg":"trace[25924966] transaction","detail":"{read_only:false; response_revision:6462; number_of_response:1; }","duration":"211.650275ms","start":"2026-04-20T06:02:06.49388Z","end":"2026-04-20T06:02:06.70553Z","steps":["trace[25924966] 'process raft request' (duration: 211.308556ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:02:06.705824Z","caller":"traceutil/trace.go:171","msg":"trace[66050266] linearizableReadLoop","detail":"{readStateIndex:6695; appliedIndex:6692; }","duration":"195.640637ms","start":"2026-04-20T06:02:06.510158Z","end":"2026-04-20T06:02:06.705798Z","steps":["trace[66050266] 'read index received' (duration: 77.133208ms)","trace[66050266] 'applied index is now lower than readState.Index' (duration: 118.504639ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:02:06.705578Z","caller":"traceutil/trace.go:171","msg":"trace[1177573538] transaction","detail":"{read_only:false; response_revision:6463; number_of_response:1; }","duration":"211.534202ms","start":"2026-04-20T06:02:06.494017Z","end":"2026-04-20T06:02:06.705551Z","steps":["trace[1177573538] 'process raft request' (duration: 211.4384ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:02:06.705954Z","caller":"traceutil/trace.go:171","msg":"trace[1211720808] transaction","detail":"{read_only:false; response_revision:6464; number_of_response:1; }","duration":"206.703233ms","start":"2026-04-20T06:02:06.499193Z","end":"2026-04-20T06:02:06.705896Z","steps":["trace[1211720808] 'process raft request' (duration: 206.308772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:02:06.706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.826731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:02:06.706063Z","caller":"traceutil/trace.go:171","msg":"trace[1787440090] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6464; }","duration":"195.944144ms","start":"2026-04-20T06:02:06.510103Z","end":"2026-04-20T06:02:06.706047Z","steps":["trace[1787440090] 'agreement among raft nodes before linearized reading' (duration: 195.815341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:02:06.980812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.95019ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T06:02:06.980958Z","caller":"traceutil/trace.go:171","msg":"trace[1388627122] linearizableReadLoop","detail":"{readStateIndex:6696; appliedIndex:6695; }","duration":"275.050412ms","start":"2026-04-20T06:02:06.70588Z","end":"2026-04-20T06:02:06.98093Z","steps":["trace[1388627122] 'read index received' (duration: 32.900488ms)","trace[1388627122] 'applied index is now lower than readState.Index' (duration: 242.147994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:02:06.981156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.306703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T06:02:06.981212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.812445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T06:02:06.981279Z","caller":"traceutil/trace.go:171","msg":"trace[1516395023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6464; }","duration":"395.489198ms","start":"2026-04-20T06:02:06.585763Z","end":"2026-04-20T06:02:06.981252Z","steps":["trace[1516395023] 'agreement among raft nodes before linearized reading' (duration: 395.240242ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:02:06.981288Z","caller":"traceutil/trace.go:171","msg":"trace[1903952666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6464; }","duration":"448.921488ms","start":"2026-04-20T06:02:06.532347Z","end":"2026-04-20T06:02:06.981269Z","steps":["trace[1903952666] 'agreement among raft nodes before linearized reading' (duration: 448.675862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:02:06.981322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.118811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T06:02:06.981338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:02:06.532333Z","time spent":"448.996869ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T06:02:06.981338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:02:06.585753Z","time spent":"395.57492ms","remote":"127.0.0.1:39262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T06:02:06.981389Z","caller":"traceutil/trace.go:171","msg":"trace[583294141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6464; }","duration":"457.226804ms","start":"2026-04-20T06:02:06.52414Z","end":"2026-04-20T06:02:06.981367Z","steps":["trace[583294141] 'agreement among raft nodes before linearized reading' (duration: 456.973168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:02:06.981435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:02:06.524121Z","time spent":"457.302696ms","remote":"127.0.0.1:39532","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-04-20T06:02:06.981764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.583911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:02:06.98205Z","caller":"traceutil/trace.go:171","msg":"trace[1019297366] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6464; }","duration":"272.741454ms","start":"2026-04-20T06:02:06.709123Z","end":"2026-04-20T06:02:06.981864Z","steps":["trace[1019297366] 'agreement among raft nodes before linearized reading' (duration: 272.596651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:05:22.976429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.418448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xwsr7\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T06:05:22.976503Z","caller":"traceutil/trace.go:171","msg":"trace[1499842666] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xwsr7; range_end:; response_count:1; response_revision:7905; }","duration":"164.52846ms","start":"2026-04-20T06:05:22.811961Z","end":"2026-04-20T06:05:22.976489Z","steps":["trace[1499842666] 'range keys from in-memory index tree' (duration: 164.258814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:05:22.976664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.945153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/barbican-api-5bc857bdb8-5mm2x\" ","response":"range_response_count:1 size:8323"} {"level":"info","ts":"2026-04-20T06:05:22.976689Z","caller":"traceutil/trace.go:171","msg":"trace[2078613904] range","detail":"{range_begin:/registry/pods/openstack/barbican-api-5bc857bdb8-5mm2x; range_end:; response_count:1; response_revision:7905; }","duration":"144.999684ms","start":"2026-04-20T06:05:22.831683Z","end":"2026-04-20T06:05:22.976683Z","steps":["trace[2078613904] 'range keys from in-memory index tree' (duration: 144.851321ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:05:33.151438Z","caller":"traceutil/trace.go:171","msg":"trace[358317378] transaction","detail":"{read_only:false; response_revision:8098; number_of_response:1; }","duration":"379.920103ms","start":"2026-04-20T06:05:32.771489Z","end":"2026-04-20T06:05:33.151409Z","steps":["trace[358317378] 'process raft request' (duration: 379.636936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:05:33.151623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:05:32.771465Z","time spent":"380.048706ms","remote":"127.0.0.1:39532","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-04-20T06:05:33.152285Z","caller":"traceutil/trace.go:171","msg":"trace[1281860598] linearizableReadLoop","detail":"{readStateIndex:8381; appliedIndex:8380; }","duration":"111.679359ms","start":"2026-04-20T06:05:33.040574Z","end":"2026-04-20T06:05:33.152253Z","steps":["trace[1281860598] 'read index received' (duration: 110.492191ms)","trace[1281860598] 'applied index is now lower than readState.Index' (duration: 1.184018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:05:33.152453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.880815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xwsr7\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T06:05:33.152517Z","caller":"traceutil/trace.go:171","msg":"trace[39215292] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xwsr7; range_end:; response_count:1; response_revision:8098; }","duration":"111.976366ms","start":"2026-04-20T06:05:33.04053Z","end":"2026-04-20T06:05:33.152507Z","steps":["trace[39215292] 'agreement among raft nodes before linearized reading' (duration: 111.796212ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:05:45.882451Z","caller":"traceutil/trace.go:171","msg":"trace[1845969593] transaction","detail":"{read_only:false; response_revision:8163; number_of_response:1; }","duration":"131.580085ms","start":"2026-04-20T06:05:45.750836Z","end":"2026-04-20T06:05:45.882416Z","steps":["trace[1845969593] 'process raft request' (duration: 131.299638ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:05:45.915927Z","caller":"traceutil/trace.go:171","msg":"trace[2133574996] linearizableReadLoop","detail":"{readStateIndex:8449; appliedIndex:8447; }","duration":"161.689068ms","start":"2026-04-20T06:05:45.754217Z","end":"2026-04-20T06:05:45.915906Z","steps":["trace[2133574996] 'read index received' (duration: 127.915655ms)","trace[2133574996] 'applied index is now lower than readState.Index' (duration: 33.772433ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:05:45.916015Z","caller":"traceutil/trace.go:171","msg":"trace[272603623] transaction","detail":"{read_only:false; response_revision:8164; number_of_response:1; }","duration":"163.00644ms","start":"2026-04-20T06:05:45.752961Z","end":"2026-04-20T06:05:45.915967Z","steps":["trace[272603623] 'process raft request' (duration: 141.027105ms)","trace[272603623] 'compare' (duration: 21.813381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:05:45.916136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.909843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T06:05:45.916169Z","caller":"traceutil/trace.go:171","msg":"trace[1876858996] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8164; }","duration":"162.011825ms","start":"2026-04-20T06:05:45.754148Z","end":"2026-04-20T06:05:45.91616Z","steps":["trace[1876858996] 'agreement among raft nodes before linearized reading' (duration: 161.871911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:05:46.657643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.551785ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T06:05:47.723956Z","caller":"traceutil/trace.go:171","msg":"trace[56400472] linearizableReadLoop","detail":"{readStateIndex:8456; appliedIndex:8455; }","duration":"225.819721ms","start":"2026-04-20T06:05:47.498111Z","end":"2026-04-20T06:05:47.723931Z","steps":["trace[56400472] 'read index received' (duration: 225.650117ms)","trace[56400472] 'applied index is now lower than readState.Index' (duration: 168.614µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:05:47.724072Z","caller":"traceutil/trace.go:171","msg":"trace[426707007] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"249.580619ms","start":"2026-04-20T06:05:47.47448Z","end":"2026-04-20T06:05:47.724061Z","steps":["trace[426707007] 'process raft request' (duration: 249.289832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:05:47.724217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.086127ms","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-04-20T06:05:47.724296Z","caller":"traceutil/trace.go:171","msg":"trace[3886618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8170; }","duration":"226.21174ms","start":"2026-04-20T06:05:47.498067Z","end":"2026-04-20T06:05:47.724279Z","steps":["trace[3886618] 'agreement among raft nodes before linearized reading' (duration: 226.003975ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:05:47.769548Z","caller":"traceutil/trace.go:171","msg":"trace[79495566] transaction","detail":"{read_only:false; response_revision:8171; number_of_response:1; }","duration":"268.011888ms","start":"2026-04-20T06:05:47.501526Z","end":"2026-04-20T06:05:47.769538Z","steps":["trace[79495566] 'process raft request' (duration: 267.929616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:05:47.769637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.308979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:05:47.769735Z","caller":"traceutil/trace.go:171","msg":"trace[2145550524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8171; }","duration":"184.417742ms","start":"2026-04-20T06:05:47.585299Z","end":"2026-04-20T06:05:47.769717Z","steps":["trace[2145550524] 'agreement among raft nodes before linearized reading' (duration: 184.247408ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:05:47.992173Z","caller":"traceutil/trace.go:171","msg":"trace[1434551573] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"101.197975ms","start":"2026-04-20T06:05:47.890951Z","end":"2026-04-20T06:05:47.992149Z","steps":["trace[1434551573] 'process raft request' (duration: 101.022461ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:05:49.390024Z","caller":"traceutil/trace.go:171","msg":"trace[444828019] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"127.993327ms","start":"2026-04-20T06:05:49.261973Z","end":"2026-04-20T06:05:49.389966Z","steps":["trace[444828019] 'process raft request' (duration: 127.762511ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:08.084864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6113} {"level":"info","ts":"2026-04-20T06:06:08.187029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6113,"took":"99.997394ms","hash":2274831302,"current-db-size-bytes":58753024,"current-db-size":"59 MB","current-db-size-in-use-bytes":23027712,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-20T06:06:08.187077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2274831302,"revision":6113,"compact-revision":2984} {"level":"info","ts":"2026-04-20T06:06:13.352465Z","caller":"traceutil/trace.go:171","msg":"trace[842972178] transaction","detail":"{read_only:false; response_revision:8366; number_of_response:1; }","duration":"112.89387ms","start":"2026-04-20T06:06:13.239557Z","end":"2026-04-20T06:06:13.352451Z","steps":["trace[842972178] 'process raft request' (duration: 112.687125ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:16.116881Z","caller":"traceutil/trace.go:171","msg":"trace[694532399] linearizableReadLoop","detail":"{readStateIndex:8674; appliedIndex:8673; }","duration":"199.650732ms","start":"2026-04-20T06:06:15.917194Z","end":"2026-04-20T06:06:16.116845Z","steps":["trace[694532399] 'read index received' (duration: 199.465018ms)","trace[694532399] 'applied index is now lower than readState.Index' (duration: 184.644µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:16.116926Z","caller":"traceutil/trace.go:171","msg":"trace[803770533] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"277.464747ms","start":"2026-04-20T06:06:15.839421Z","end":"2026-04-20T06:06:16.116885Z","steps":["trace[803770533] 'process raft request' (duration: 277.210511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:16.117146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.935089ms","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-04-20T06:06:16.117225Z","caller":"traceutil/trace.go:171","msg":"trace[638306662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8379; }","duration":"200.027061ms","start":"2026-04-20T06:06:15.91718Z","end":"2026-04-20T06:06:16.117207Z","steps":["trace[638306662] 'agreement among raft nodes before linearized reading' (duration: 199.793585ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:16.354334Z","caller":"traceutil/trace.go:171","msg":"trace[258140001] transaction","detail":"{read_only:false; response_revision:8381; number_of_response:1; }","duration":"134.022274ms","start":"2026-04-20T06:06:16.220278Z","end":"2026-04-20T06:06:16.3543Z","steps":["trace[258140001] 'process raft request' (duration: 133.882831ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:16.3544Z","caller":"traceutil/trace.go:171","msg":"trace[591294721] linearizableReadLoop","detail":"{readStateIndex:8675; appliedIndex:8674; }","duration":"227.639413ms","start":"2026-04-20T06:06:16.126732Z","end":"2026-04-20T06:06:16.354371Z","steps":["trace[591294721] 'read index received' (duration: 222.975949ms)","trace[591294721] 'applied index is now lower than readState.Index' (duration: 4.660454ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:16.354418Z","caller":"traceutil/trace.go:171","msg":"trace[1543015394] transaction","detail":"{read_only:false; response_revision:8380; number_of_response:1; }","duration":"232.82486ms","start":"2026-04-20T06:06:16.121533Z","end":"2026-04-20T06:06:16.354358Z","steps":["trace[1543015394] 'process raft request' (duration: 228.156236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:16.354648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.868579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T06:06:16.35467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.834092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T06:06:16.354673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.91114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T06:06:16.354692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.831702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T06:06:16.354739Z","caller":"traceutil/trace.go:171","msg":"trace[907446888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8381; }","duration":"106.952395ms","start":"2026-04-20T06:06:16.247772Z","end":"2026-04-20T06:06:16.354724Z","steps":["trace[907446888] 'agreement among raft nodes before linearized reading' (duration: 106.74448ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:16.354759Z","caller":"traceutil/trace.go:171","msg":"trace[1043159250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8381; }","duration":"228.031933ms","start":"2026-04-20T06:06:16.126706Z","end":"2026-04-20T06:06:16.354738Z","steps":["trace[1043159250] 'agreement among raft nodes before linearized reading' (duration: 227.732826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:16.354728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.517457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:06:16.354789Z","caller":"traceutil/trace.go:171","msg":"trace[2113235293] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:8381; }","duration":"162.015536ms","start":"2026-04-20T06:06:16.192752Z","end":"2026-04-20T06:06:16.354767Z","steps":["trace[2113235293] 'agreement among raft nodes before linearized reading' (duration: 161.79853ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:16.354698Z","caller":"traceutil/trace.go:171","msg":"trace[390883539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8381; }","duration":"227.956271ms","start":"2026-04-20T06:06:16.126728Z","end":"2026-04-20T06:06:16.354684Z","steps":["trace[390883539] 'agreement among raft nodes before linearized reading' (duration: 227.785267ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:16.354866Z","caller":"traceutil/trace.go:171","msg":"trace[1523618169] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8381; }","duration":"226.6887ms","start":"2026-04-20T06:06:16.128142Z","end":"2026-04-20T06:06:16.354831Z","steps":["trace[1523618169] 'agreement among raft nodes before linearized reading' (duration: 226.468715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:16.355024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.382539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-20T06:06:16.355152Z","caller":"traceutil/trace.go:171","msg":"trace[1246171575] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8381; }","duration":"117.513112ms","start":"2026-04-20T06:06:16.237612Z","end":"2026-04-20T06:06:16.355125Z","steps":["trace[1246171575] 'agreement among raft nodes before linearized reading' (duration: 117.051301ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:17.615554Z","caller":"traceutil/trace.go:171","msg":"trace[881164252] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"130.248302ms","start":"2026-04-20T06:06:17.485271Z","end":"2026-04-20T06:06:17.615519Z","steps":["trace[881164252] 'process raft request' (duration: 129.989296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:18.002009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.919806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:06:18.002111Z","caller":"traceutil/trace.go:171","msg":"trace[1315887918] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8387; }","duration":"188.07801ms","start":"2026-04-20T06:06:17.814003Z","end":"2026-04-20T06:06:18.002081Z","steps":["trace[1315887918] 'count revisions from in-memory index tree' (duration: 187.826174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:29.878777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.787338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T06:06:29.878963Z","caller":"traceutil/trace.go:171","msg":"trace[353275430] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8494; }","duration":"285.234877ms","start":"2026-04-20T06:06:29.59367Z","end":"2026-04-20T06:06:29.878905Z","steps":["trace[353275430] 'process raft request' (duration: 133.175953ms)","trace[353275430] 'compare' (duration: 151.313265ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:29.884088Z","caller":"traceutil/trace.go:171","msg":"trace[53324476] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"288.146378ms","start":"2026-04-20T06:06:29.595922Z","end":"2026-04-20T06:06:29.884069Z","steps":["trace[53324476] 'process raft request' (duration: 287.975103ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:29.884298Z","caller":"traceutil/trace.go:171","msg":"trace[553179338] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"230.894054ms","start":"2026-04-20T06:06:29.653377Z","end":"2026-04-20T06:06:29.884271Z","steps":["trace[553179338] 'process raft request' (duration: 230.641187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:29.884781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.236593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xwsr7\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T06:06:29.884848Z","caller":"traceutil/trace.go:171","msg":"trace[1132073345] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xwsr7; range_end:; response_count:1; response_revision:8496; }","duration":"208.354165ms","start":"2026-04-20T06:06:29.676483Z","end":"2026-04-20T06:06:29.884837Z","steps":["trace[1132073345] 'agreement among raft nodes before linearized reading' (duration: 208.16076ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:29.884534Z","caller":"traceutil/trace.go:171","msg":"trace[877245006] linearizableReadLoop","detail":"{readStateIndex:8797; appliedIndex:8794; }","duration":"207.974196ms","start":"2026-04-20T06:06:29.676531Z","end":"2026-04-20T06:06:29.884506Z","steps":["trace[877245006] 'read index received' (duration: 50.330035ms)","trace[877245006] 'applied index is now lower than readState.Index' (duration: 157.641951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:06:30.843082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.003589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T06:06:30.84328Z","caller":"traceutil/trace.go:171","msg":"trace[1783951822] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"408.195542ms","start":"2026-04-20T06:06:30.435068Z","end":"2026-04-20T06:06:30.843263Z","steps":["trace[1783951822] 'process raft request' (duration: 408.10635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:30.843349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:30.43506Z","time spent":"408.259973ms","remote":"127.0.0.1:39532","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-04-20T06:06:30.843449Z","caller":"traceutil/trace.go:171","msg":"trace[333781314] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"408.456478ms","start":"2026-04-20T06:06:30.434966Z","end":"2026-04-20T06:06:30.843423Z","steps":["trace[333781314] 'process raft request' (duration: 66.038449ms)","trace[333781314] 'compare' (duration: 341.870326ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:30.843545Z","caller":"traceutil/trace.go:171","msg":"trace[995942911] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"408.416107ms","start":"2026-04-20T06:06:30.435116Z","end":"2026-04-20T06:06:30.843533Z","steps":["trace[995942911] 'process raft request' (duration: 408.11147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:30.843577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:30.434921Z","time spent":"408.586261ms","remote":"127.0.0.1:39532","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-04-20T06:06:30.843609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:30.43511Z","time spent":"408.466729ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:06:30.843652Z","caller":"traceutil/trace.go:171","msg":"trace[1329671094] linearizableReadLoop","detail":"{readStateIndex:8807; appliedIndex:8802; }","duration":"345.175677ms","start":"2026-04-20T06:06:30.49845Z","end":"2026-04-20T06:06:30.843626Z","steps":["trace[1329671094] 'read index received' (duration: 2.559533ms)","trace[1329671094] 'applied index is now lower than readState.Index' (duration: 342.615194ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:30.843694Z","caller":"traceutil/trace.go:171","msg":"trace[1845526062] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"408.440548ms","start":"2026-04-20T06:06:30.435223Z","end":"2026-04-20T06:06:30.843664Z","steps":["trace[1845526062] 'process raft request' (duration: 408.238483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:30.843802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:30.435214Z","time spent":"408.53575ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:06:30.843842Z","caller":"traceutil/trace.go:171","msg":"trace[101637036] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"408.511259ms","start":"2026-04-20T06:06:30.435272Z","end":"2026-04-20T06:06:30.843783Z","steps":["trace[101637036] 'process raft request' (duration: 408.305715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:30.843911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.446913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-20T06:06:30.843951Z","caller":"traceutil/trace.go:171","msg":"trace[211887358] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8506; }","duration":"345.526805ms","start":"2026-04-20T06:06:30.498412Z","end":"2026-04-20T06:06:30.843939Z","steps":["trace[211887358] 'agreement among raft nodes before linearized reading' (duration: 345.364171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:30.844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:30.498398Z","time spent":"345.593216ms","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5170,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-04-20T06:06:30.844007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.76616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T06:06:30.844048Z","caller":"traceutil/trace.go:171","msg":"trace[1862605246] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8506; }","duration":"113.863382ms","start":"2026-04-20T06:06:30.730172Z","end":"2026-04-20T06:06:30.844035Z","steps":["trace[1862605246] 'agreement among raft nodes before linearized reading' (duration: 113.76401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:30.844032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:30.435265Z","time spent":"408.668403ms","remote":"127.0.0.1:39532","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-04-20T06:06:30.844125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.055495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:06:30.844271Z","caller":"traceutil/trace.go:171","msg":"trace[1691234972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8506; }","duration":"258.363732ms","start":"2026-04-20T06:06:30.585879Z","end":"2026-04-20T06:06:30.844243Z","steps":["trace[1691234972] 'agreement among raft nodes before linearized reading' (duration: 257.941382ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:31.009095Z","caller":"traceutil/trace.go:171","msg":"trace[1697854595] linearizableReadLoop","detail":"{readStateIndex:8808; appliedIndex:8807; }","duration":"129.370601ms","start":"2026-04-20T06:06:30.879698Z","end":"2026-04-20T06:06:31.009069Z","steps":["trace[1697854595] 'read index received' (duration: 72.998578ms)","trace[1697854595] 'applied index is now lower than readState.Index' (duration: 56.370692ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:31.009149Z","caller":"traceutil/trace.go:171","msg":"trace[1104533346] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"153.458938ms","start":"2026-04-20T06:06:30.855658Z","end":"2026-04-20T06:06:31.009117Z","steps":["trace[1104533346] 'process raft request' (duration: 97.139836ms)","trace[1104533346] 'compare' (duration: 56.119337ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:06:31.009328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.614476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T06:06:31.009399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.866556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5bc857bdb8-5mm2x.18a7fb85fc5916b7\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T06:06:31.009429Z","caller":"traceutil/trace.go:171","msg":"trace[568968770] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8507; }","duration":"129.728309ms","start":"2026-04-20T06:06:30.879648Z","end":"2026-04-20T06:06:31.009377Z","steps":["trace[568968770] 'agreement among raft nodes before linearized reading' (duration: 129.540744ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:31.009478Z","caller":"traceutil/trace.go:171","msg":"trace[550565223] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5bc857bdb8-5mm2x.18a7fb85fc5916b7; range_end:; response_count:1; response_revision:8507; }","duration":"102.974868ms","start":"2026-04-20T06:06:30.906483Z","end":"2026-04-20T06:06:31.009457Z","steps":["trace[550565223] 'agreement among raft nodes before linearized reading' (duration: 102.764453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:43.244855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.027713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-glance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:06:43.244977Z","caller":"traceutil/trace.go:171","msg":"trace[847623919] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-glance; range_end:; response_count:0; response_revision:8614; }","duration":"105.198777ms","start":"2026-04-20T06:06:43.139751Z","end":"2026-04-20T06:06:43.244949Z","steps":["trace[847623919] 'range keys from in-memory index tree' (duration: 104.852499ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:12.766459Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241a.wal"} {"level":"info","ts":"2026-04-20T06:07:28.234769Z","caller":"traceutil/trace.go:171","msg":"trace[1537823483] linearizableReadLoop","detail":"{readStateIndex:9436; appliedIndex:9434; }","duration":"278.201357ms","start":"2026-04-20T06:07:27.95654Z","end":"2026-04-20T06:07:28.234742Z","steps":["trace[1537823483] 'read index received' (duration: 25.054467ms)","trace[1537823483] 'applied index is now lower than readState.Index' (duration: 253.14526ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:07:28.234837Z","caller":"traceutil/trace.go:171","msg":"trace[154439482] transaction","detail":"{read_only:false; response_revision:9119; number_of_response:1; }","duration":"309.378921ms","start":"2026-04-20T06:07:27.925427Z","end":"2026-04-20T06:07:28.234806Z","steps":["trace[154439482] 'process raft request' (duration: 308.889699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:28.235014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:07:27.925406Z","time spent":"309.500955ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1407,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:07:28.235061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.494763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-20T06:07:28.235106Z","caller":"traceutil/trace.go:171","msg":"trace[2056900518] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9119; }","duration":"278.609696ms","start":"2026-04-20T06:07:27.956482Z","end":"2026-04-20T06:07:28.235091Z","steps":["trace[2056900518] 'agreement among raft nodes before linearized reading' (duration: 278.380201ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:28.300488Z","caller":"traceutil/trace.go:171","msg":"trace[588861385] transaction","detail":"{read_only:false; response_revision:9120; number_of_response:1; }","duration":"131.572835ms","start":"2026-04-20T06:07:28.168894Z","end":"2026-04-20T06:07:28.300467Z","steps":["trace[588861385] 'process raft request' (duration: 123.337336ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:08:44.598142Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9409e054be1d0d72","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T06:08:44.604328Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T06:08:44.604416Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T06:11:08.090945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8285} {"level":"info","ts":"2026-04-20T06:11:08.170537Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8285,"took":"77.191594ms","hash":3619638048,"current-db-size-bytes":58753024,"current-db-size":"59 MB","current-db-size-in-use-bytes":24834048,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T06:11:08.170602Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3619638048,"revision":8285,"compact-revision":6113} {"level":"info","ts":"2026-04-20T06:16:08.098699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10682} {"level":"info","ts":"2026-04-20T06:16:08.179504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10682,"took":"78.676737ms","hash":2705780839,"current-db-size-bytes":58753024,"current-db-size":"59 MB","current-db-size-in-use-bytes":20258816,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-20T06:16:08.179604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2705780839,"revision":10682,"compact-revision":8285}