{"level":"warn","ts":"2026-05-06T01:15:51.336531Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:15:51.336618Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-05-06T01:15:51.336683Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:15:51.336694Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-06T01:15:51.336712Z","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-05-06T01:15:51.337562Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-05-06T01:15:51.337649Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-05-06T01:15:51.344049Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.759203ms"} {"level":"info","ts":"2026-05-06T01:15:51.352142Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-05-06T01:15:51.352228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T01:15:51.352254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-05-06T01:15:51.352264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T01:15:51.352281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-05-06T01:15:51.352325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-05-06T01:15:51.358378Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T01:15:51.361331Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T01:15:51.365495Z","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-05-06T01:15:51.368589Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T01:15:51.368787Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T01:15:51.369102Z","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-05-06T01:15:51.369181Z","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-05-06T01:15:51.369196Z","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-05-06T01:15:51.371928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-05-06T01:15:51.372068Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-06T01:15:51.374227Z","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-05-06T01:15:51.374361Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-06T01:15:51.374444Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-06T01:15:51.3745Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T01:15:51.374529Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T01:15:51.553441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T01:15:51.553566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T01:15:51.553598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-05-06T01:15:51.553627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-05-06T01:15:51.553642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-06T01:15:51.553661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-05-06T01:15:51.553703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-06T01:15:51.556311Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T01:15:51.556353Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:15:51.556422Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:15:51.556684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:15:51.557138Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T01:15:51.557228Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T01:15:51.557407Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:15:51.557571Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:15:51.55761Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:15:51.558784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T01:15:51.563604Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-05-06T01:16:24.761491Z","caller":"traceutil/trace.go:171","msg":"trace[86744314] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"107.883499ms","start":"2026-05-06T01:16:24.653573Z","end":"2026-05-06T01:16:24.761457Z","steps":["trace[86744314] 'process raft request' (duration: 107.734646ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:24.761435Z","caller":"traceutil/trace.go:171","msg":"trace[970767122] transaction","detail":"{read_only:false; number_of_response:0; response_revision:533; }","duration":"109.594636ms","start":"2026-05-06T01:16:24.651803Z","end":"2026-05-06T01:16:24.761397Z","steps":["trace[970767122] 'process raft request' (duration: 64.982165ms)","trace[970767122] 'compare' (duration: 44.487148ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:24.768348Z","caller":"traceutil/trace.go:171","msg":"trace[1736545659] transaction","detail":"{read_only:false; number_of_response:1; response_revision:535; }","duration":"110.487515ms","start":"2026-05-06T01:16:24.65784Z","end":"2026-05-06T01:16:24.768328Z","steps":["trace[1736545659] 'process raft request' (duration: 110.25527ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:24.769126Z","caller":"traceutil/trace.go:171","msg":"trace[383566422] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"107.614674ms","start":"2026-05-06T01:16:24.661469Z","end":"2026-05-06T01:16:24.769084Z","steps":["trace[383566422] 'process raft request' (duration: 106.796597ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:24.769032Z","caller":"traceutil/trace.go:171","msg":"trace[522203009] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"110.861853ms","start":"2026-05-06T01:16:24.658036Z","end":"2026-05-06T01:16:24.768898Z","steps":["trace[522203009] 'process raft request' (duration: 110.090487ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:24.769198Z","caller":"traceutil/trace.go:171","msg":"trace[1399736791] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"111.002227ms","start":"2026-05-06T01:16:24.657623Z","end":"2026-05-06T01:16:24.768625Z","steps":["trace[1399736791] 'process raft request' (duration: 110.383654ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:24.769275Z","caller":"traceutil/trace.go:171","msg":"trace[1674182876] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"110.952886ms","start":"2026-05-06T01:16:24.658108Z","end":"2026-05-06T01:16:24.769061Z","steps":["trace[1674182876] 'process raft request' (duration: 110.055857ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:24.771888Z","caller":"traceutil/trace.go:171","msg":"trace[1863701280] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"106.041751ms","start":"2026-05-06T01:16:24.6658Z","end":"2026-05-06T01:16:24.771841Z","steps":["trace[1863701280] 'process raft request' (duration: 102.515306ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:24.76864Z","caller":"traceutil/trace.go:171","msg":"trace[1116913921] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"108.961743ms","start":"2026-05-06T01:16:24.659647Z","end":"2026-05-06T01:16:24.768609Z","steps":["trace[1116913921] 'process raft request' (duration: 108.556075ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:30.870954Z","caller":"traceutil/trace.go:171","msg":"trace[1214890584] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"107.430273ms","start":"2026-05-06T01:16:30.7635Z","end":"2026-05-06T01:16:30.87093Z","steps":["trace[1214890584] 'read index received' (duration: 76.906247ms)","trace[1214890584] 'applied index is now lower than readState.Index' (duration: 30.522986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:16:30.871156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.641217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ln59f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-06T01:16:30.871229Z","caller":"traceutil/trace.go:171","msg":"trace[1996433657] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ln59f; range_end:; response_count:1; response_revision:626; }","duration":"107.76568ms","start":"2026-05-06T01:16:30.763452Z","end":"2026-05-06T01:16:30.871218Z","steps":["trace[1996433657] 'agreement among raft nodes before linearized reading' (duration: 107.579586ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:30.871254Z","caller":"traceutil/trace.go:171","msg":"trace[1814148292] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"151.136538ms","start":"2026-05-06T01:16:30.720081Z","end":"2026-05-06T01:16:30.871218Z","steps":["trace[1814148292] 'process raft request' (duration: 120.257045ms)","trace[1814148292] 'compare' (duration: 30.484094ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:30.899128Z","caller":"traceutil/trace.go:171","msg":"trace[2102128681] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"127.029157ms","start":"2026-05-06T01:16:30.77208Z","end":"2026-05-06T01:16:30.899109Z","steps":["trace[2102128681] 'process raft request' (duration: 126.905084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:31.293651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.5679ms","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-05-06T01:16:31.29371Z","caller":"traceutil/trace.go:171","msg":"trace[1736746243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"155.681663ms","start":"2026-05-06T01:16:31.138015Z","end":"2026-05-06T01:16:31.293696Z","steps":["trace[1736746243] 'range keys from in-memory index tree' (duration: 155.444728ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:15.150853Z","caller":"traceutil/trace.go:171","msg":"trace[813266425] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"121.330507ms","start":"2026-05-06T01:17:15.029496Z","end":"2026-05-06T01:17:15.150826Z","steps":["trace[813266425] 'process raft request' (duration: 121.120513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:00.657177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.391575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:00.657338Z","caller":"traceutil/trace.go:171","msg":"trace[1887866601] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1926; }","duration":"357.727699ms","start":"2026-05-06T01:18:00.299576Z","end":"2026-05-06T01:18:00.657304Z","steps":["trace[1887866601] 'read index received' (duration: 235.443085ms)","trace[1887866601] 'applied index is now lower than readState.Index' (duration: 122.282554ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:00.657385Z","caller":"traceutil/trace.go:171","msg":"trace[1255260537] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"396.724252ms","start":"2026-05-06T01:18:00.260605Z","end":"2026-05-06T01:18:00.65733Z","steps":["trace[1255260537] 'process raft request' (duration: 274.48713ms)","trace[1255260537] 'compare' (duration: 121.273782ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:00.657525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.858885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:18:00.657514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:00.260588Z","time spent":"396.865235ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:00.657578Z","caller":"traceutil/trace.go:171","msg":"trace[299800253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"334.931806ms","start":"2026-05-06T01:18:00.322633Z","end":"2026-05-06T01:18:00.657565Z","steps":["trace[299800253] 'agreement among raft nodes before linearized reading' (duration: 334.849035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:00.657623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:00.322622Z","time spent":"334.989468ms","remote":"127.0.0.1:53604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:18:00.657633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.038995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-06T01:18:00.657713Z","caller":"traceutil/trace.go:171","msg":"trace[676921470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"358.158398ms","start":"2026-05-06T01:18:00.299536Z","end":"2026-05-06T01:18:00.657695Z","steps":["trace[676921470] 'agreement among raft nodes before linearized reading' (duration: 357.919833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:00.657754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:00.299523Z","time spent":"358.222539ms","remote":"127.0.0.1:46958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-06T01:18:00.658046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.362653ms","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-05-06T01:18:00.658111Z","caller":"traceutil/trace.go:171","msg":"trace[1398934918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1854; }","duration":"129.457585ms","start":"2026-05-06T01:18:00.528639Z","end":"2026-05-06T01:18:00.658097Z","steps":["trace[1398934918] 'agreement among raft nodes before linearized reading' (duration: 129.145899ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:00.805541Z","caller":"traceutil/trace.go:171","msg":"trace[773705561] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"134.218976ms","start":"2026-05-06T01:18:00.671299Z","end":"2026-05-06T01:18:00.805518Z","steps":["trace[773705561] 'process raft request' (duration: 97.118242ms)","trace[773705561] 'compare' (duration: 36.645644ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:01.101031Z","caller":"traceutil/trace.go:171","msg":"trace[1112278021] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"289.839725ms","start":"2026-05-06T01:18:00.811155Z","end":"2026-05-06T01:18:01.100995Z","steps":["trace[1112278021] 'process raft request' (duration: 275.726005ms)","trace[1112278021] 'compare' (duration: 13.974096ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:01.101493Z","caller":"traceutil/trace.go:171","msg":"trace[837374425] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"290.219953ms","start":"2026-05-06T01:18:00.811258Z","end":"2026-05-06T01:18:01.101478Z","steps":["trace[837374425] 'process raft request' (duration: 290.042569ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:01.101514Z","caller":"traceutil/trace.go:171","msg":"trace[1419589065] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1929; }","duration":"204.383679ms","start":"2026-05-06T01:18:00.897108Z","end":"2026-05-06T01:18:01.101492Z","steps":["trace[1419589065] 'read index received' (duration: 189.895413ms)","trace[1419589065] 'applied index is now lower than readState.Index' (duration: 14.487266ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:01.101609Z","caller":"traceutil/trace.go:171","msg":"trace[188749252] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"290.197812ms","start":"2026-05-06T01:18:00.811403Z","end":"2026-05-06T01:18:01.1016Z","steps":["trace[188749252] 'process raft request' (duration: 290.018228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:01.10166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.539152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:18:01.101732Z","caller":"traceutil/trace.go:171","msg":"trace[859456213] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1860; }","duration":"204.666965ms","start":"2026-05-06T01:18:00.897045Z","end":"2026-05-06T01:18:01.101712Z","steps":["trace[859456213] 'agreement among raft nodes before linearized reading' (duration: 204.559872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:01.101792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.481229ms","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-05-06T01:18:01.101856Z","caller":"traceutil/trace.go:171","msg":"trace[597158447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1860; }","duration":"123.588681ms","start":"2026-05-06T01:18:00.978254Z","end":"2026-05-06T01:18:01.101842Z","steps":["trace[597158447] 'agreement among raft nodes before linearized reading' (duration: 123.400937ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:01.101956Z","caller":"traceutil/trace.go:171","msg":"trace[1892176292] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"290.368596ms","start":"2026-05-06T01:18:00.811569Z","end":"2026-05-06T01:18:01.101937Z","steps":["trace[1892176292] 'process raft request' (duration: 289.888786ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:51.708568Z","caller":"traceutil/trace.go:171","msg":"trace[887324696] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"146.851475ms","start":"2026-05-06T01:18:51.561679Z","end":"2026-05-06T01:18:51.708531Z","steps":["trace[887324696] 'process raft request' (duration: 145.642609ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:39.902918Z","caller":"traceutil/trace.go:171","msg":"trace[1521097925] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"131.419146ms","start":"2026-05-06T01:19:39.771474Z","end":"2026-05-06T01:19:39.902893Z","steps":["trace[1521097925] 'process raft request' (duration: 131.216242ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:06.281573Z","caller":"traceutil/trace.go:171","msg":"trace[1841835591] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"170.075853ms","start":"2026-05-06T01:24:06.111469Z","end":"2026-05-06T01:24:06.281545Z","steps":["trace[1841835591] 'process raft request' (duration: 169.864198ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:06.289356Z","caller":"traceutil/trace.go:171","msg":"trace[1000685792] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4973; }","duration":"158.163918ms","start":"2026-05-06T01:24:06.131161Z","end":"2026-05-06T01:24:06.289325Z","steps":["trace[1000685792] 'process raft request' (duration: 158.067736ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:08.288138Z","caller":"traceutil/trace.go:171","msg":"trace[677116663] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"105.287904ms","start":"2026-05-06T01:24:08.182813Z","end":"2026-05-06T01:24:08.288101Z","steps":["trace[677116663] 'process raft request' (duration: 67.188841ms)","trace[677116663] 'compare' (duration: 37.884878ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:24:17.456989Z","caller":"traceutil/trace.go:171","msg":"trace[696223844] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"129.611932ms","start":"2026-05-06T01:24:17.327328Z","end":"2026-05-06T01:24:17.45694Z","steps":["trace[696223844] 'process raft request' (duration: 129.140031ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:17.457077Z","caller":"traceutil/trace.go:171","msg":"trace[1933869273] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"131.46255ms","start":"2026-05-06T01:24:17.325585Z","end":"2026-05-06T01:24:17.457048Z","steps":["trace[1933869273] 'process raft request' (duration: 39.954141ms)","trace[1933869273] 'compare' (duration: 90.64827ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:24:17.45723Z","caller":"traceutil/trace.go:171","msg":"trace[1316928966] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"130.927919ms","start":"2026-05-06T01:24:17.326291Z","end":"2026-05-06T01:24:17.457219Z","steps":["trace[1316928966] 'process raft request' (duration: 130.05536ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:17.457269Z","caller":"traceutil/trace.go:171","msg":"trace[406542780] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"130.915869ms","start":"2026-05-06T01:24:17.326345Z","end":"2026-05-06T01:24:17.457261Z","steps":["trace[406542780] 'process raft request' (duration: 130.06653ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:17.457763Z","caller":"traceutil/trace.go:171","msg":"trace[462432030] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"127.028476ms","start":"2026-05-06T01:24:17.330722Z","end":"2026-05-06T01:24:17.457751Z","steps":["trace[462432030] 'process raft request' (duration: 125.7946ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:18.069968Z","caller":"traceutil/trace.go:171","msg":"trace[1626858017] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"228.062018ms","start":"2026-05-06T01:24:17.841887Z","end":"2026-05-06T01:24:18.069949Z","steps":["trace[1626858017] 'process raft request' (duration: 227.941996ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:27.273788Z","caller":"traceutil/trace.go:171","msg":"trace[544498118] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"105.657171ms","start":"2026-05-06T01:24:27.168084Z","end":"2026-05-06T01:24:27.273742Z","steps":["trace[544498118] 'process raft request' (duration: 105.415416ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:27.369198Z","caller":"traceutil/trace.go:171","msg":"trace[1921964369] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"191.871036ms","start":"2026-05-06T01:24:27.177284Z","end":"2026-05-06T01:24:27.369155Z","steps":["trace[1921964369] 'process raft request' (duration: 113.053998ms)","trace[1921964369] 'compare' (duration: 78.648465ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:25:51.825813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3030} {"level":"info","ts":"2026-05-06T01:25:51.907488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3030,"took":"79.992346ms","hash":3562690079,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31846400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-06T01:25:51.907573Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3562690079,"revision":3030,"compact-revision":-1} {"level":"info","ts":"2026-05-06T01:30:36.147902Z","caller":"traceutil/trace.go:171","msg":"trace[2145417354] transaction","detail":"{read_only:false; response_revision:8230; number_of_response:1; }","duration":"188.838124ms","start":"2026-05-06T01:30:35.95904Z","end":"2026-05-06T01:30:36.147878Z","steps":["trace[2145417354] 'process raft request' (duration: 188.693171ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:30:36.308917Z","caller":"traceutil/trace.go:171","msg":"trace[79099913] transaction","detail":"{read_only:false; response_revision:8231; number_of_response:1; }","duration":"307.65193ms","start":"2026-05-06T01:30:36.001232Z","end":"2026-05-06T01:30:36.308884Z","steps":["trace[79099913] 'process raft request' (duration: 292.877197ms)","trace[79099913] 'compare' (duration: 14.672401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:30:36.309047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:30:36.001217Z","time spent":"307.753812ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:30:48.580846Z","caller":"traceutil/trace.go:171","msg":"trace[205571095] transaction","detail":"{read_only:false; response_revision:8296; number_of_response:1; }","duration":"113.167237ms","start":"2026-05-06T01:30:48.46765Z","end":"2026-05-06T01:30:48.580817Z","steps":["trace[205571095] 'process raft request' (duration: 112.925491ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:30:48.590138Z","caller":"traceutil/trace.go:171","msg":"trace[289984484] transaction","detail":"{read_only:false; response_revision:8297; number_of_response:1; }","duration":"119.195694ms","start":"2026-05-06T01:30:48.470774Z","end":"2026-05-06T01:30:48.58997Z","steps":["trace[289984484] 'process raft request' (duration: 109.964367ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:30:48.791799Z","caller":"traceutil/trace.go:171","msg":"trace[2002498924] transaction","detail":"{read_only:false; response_revision:8299; number_of_response:1; }","duration":"133.735133ms","start":"2026-05-06T01:30:48.658026Z","end":"2026-05-06T01:30:48.791761Z","steps":["trace[2002498924] 'process raft request' (duration: 115.712619ms)","trace[2002498924] 'compare' (duration: 17.504283ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:30:50.318556Z","caller":"traceutil/trace.go:171","msg":"trace[2078268100] transaction","detail":"{read_only:false; response_revision:8305; number_of_response:1; }","duration":"101.577219ms","start":"2026-05-06T01:30:50.216953Z","end":"2026-05-06T01:30:50.31853Z","steps":["trace[2078268100] 'process raft request' (duration: 101.176721ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:30:51.832349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6146} {"level":"info","ts":"2026-05-06T01:30:51.93139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6146,"took":"96.850569ms","hash":1855341351,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":23281664,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-06T01:30:51.931472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1855341351,"revision":6146,"compact-revision":3030} {"level":"info","ts":"2026-05-06T01:31:17.181526Z","caller":"traceutil/trace.go:171","msg":"trace[2007679088] linearizableReadLoop","detail":"{readStateIndex:8807; appliedIndex:8806; }","duration":"223.593873ms","start":"2026-05-06T01:31:16.957898Z","end":"2026-05-06T01:31:17.181492Z","steps":["trace[2007679088] 'read index received' (duration: 223.304787ms)","trace[2007679088] 'applied index is now lower than readState.Index' (duration: 288.066µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:31:17.181918Z","caller":"traceutil/trace.go:171","msg":"trace[1844276789] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"291.121079ms","start":"2026-05-06T01:31:16.890781Z","end":"2026-05-06T01:31:17.181902Z","steps":["trace[1844276789] 'process raft request' (duration: 290.508386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:31:17.182264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.349009ms","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-05-06T01:31:17.182581Z","caller":"traceutil/trace.go:171","msg":"trace[2117004491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8505; }","duration":"224.725167ms","start":"2026-05-06T01:31:16.957839Z","end":"2026-05-06T01:31:17.182564Z","steps":["trace[2117004491] 'agreement among raft nodes before linearized reading' (duration: 224.284568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:31:17.182502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.122393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:31:17.182915Z","caller":"traceutil/trace.go:171","msg":"trace[861506600] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8505; }","duration":"184.585404ms","start":"2026-05-06T01:31:16.998317Z","end":"2026-05-06T01:31:17.182903Z","steps":["trace[861506600] 'agreement among raft nodes before linearized reading' (duration: 184.031631ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:31:17.321902Z","caller":"traceutil/trace.go:171","msg":"trace[1121390134] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"134.956899ms","start":"2026-05-06T01:31:17.186927Z","end":"2026-05-06T01:31:17.321884Z","steps":["trace[1121390134] 'process raft request' (duration: 120.40267ms)","trace[1121390134] 'compare' (duration: 14.046888ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:31:20.637723Z","caller":"traceutil/trace.go:171","msg":"trace[954875465] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"165.200462ms","start":"2026-05-06T01:31:20.472492Z","end":"2026-05-06T01:31:20.637693Z","steps":["trace[954875465] 'process raft request' (duration: 164.968307ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:31:20.70382Z","caller":"traceutil/trace.go:171","msg":"trace[169025456] linearizableReadLoop","detail":"{readStateIndex:8824; appliedIndex:8822; }","duration":"201.333019ms","start":"2026-05-06T01:31:20.502433Z","end":"2026-05-06T01:31:20.703766Z","steps":["trace[169025456] 'read index received' (duration: 135.082811ms)","trace[169025456] 'applied index is now lower than readState.Index' (duration: 66.247668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:31:20.70413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.663746ms","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-05-06T01:31:20.704184Z","caller":"traceutil/trace.go:171","msg":"trace[7292237] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8523; }","duration":"201.77116ms","start":"2026-05-06T01:31:20.502401Z","end":"2026-05-06T01:31:20.704172Z","steps":["trace[7292237] 'agreement among raft nodes before linearized reading' (duration: 201.507424ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:32:20.904914Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249a.wal"} {"level":"info","ts":"2026-05-06T01:32:33.680981Z","caller":"traceutil/trace.go:171","msg":"trace[128871791] transaction","detail":"{read_only:false; response_revision:9287; number_of_response:1; }","duration":"118.57401ms","start":"2026-05-06T01:32:33.562368Z","end":"2026-05-06T01:32:33.680942Z","steps":["trace[128871791] 'process raft request' (duration: 118.305785ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:32:45.673642Z","caller":"traceutil/trace.go:171","msg":"trace[58269212] transaction","detail":"{read_only:false; response_revision:9359; number_of_response:1; }","duration":"120.785927ms","start":"2026-05-06T01:32:45.552831Z","end":"2026-05-06T01:32:45.673617Z","steps":["trace[58269212] 'process raft request' (duration: 120.638504ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:33:26.349064Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T01:33:26.358193Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-06T01:33:26.358323Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-06T01:35:51.838897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8321} {"level":"info","ts":"2026-05-06T01:35:51.916352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8321,"took":"75.224071ms","hash":594172531,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":24088576,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-06T01:35:51.916428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":594172531,"revision":8321,"compact-revision":6146} {"level":"info","ts":"2026-05-06T01:37:08.1767Z","caller":"traceutil/trace.go:171","msg":"trace[1818949254] transaction","detail":"{read_only:false; response_revision:11397; number_of_response:1; }","duration":"132.14882ms","start":"2026-05-06T01:37:08.044527Z","end":"2026-05-06T01:37:08.176675Z","steps":["trace[1818949254] 'process raft request' (duration: 131.537078ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:37:30.465209Z","caller":"traceutil/trace.go:171","msg":"trace[1489815476] linearizableReadLoop","detail":"{readStateIndex:11917; appliedIndex:11916; }","duration":"144.647476ms","start":"2026-05-06T01:37:30.320511Z","end":"2026-05-06T01:37:30.465159Z","steps":["trace[1489815476] 'read index received' (duration: 118.247193ms)","trace[1489815476] 'applied index is now lower than readState.Index' (duration: 26.398643ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:37:30.465244Z","caller":"traceutil/trace.go:171","msg":"trace[1280354831] transaction","detail":"{read_only:false; response_revision:11522; number_of_response:1; }","duration":"218.292119ms","start":"2026-05-06T01:37:30.246902Z","end":"2026-05-06T01:37:30.465194Z","steps":["trace[1280354831] 'process raft request' (duration: 191.877014ms)","trace[1280354831] 'compare' (duration: 26.029766ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:37:30.465404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.823682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:37:30.465482Z","caller":"traceutil/trace.go:171","msg":"trace[633889970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11522; }","duration":"144.998895ms","start":"2026-05-06T01:37:30.320461Z","end":"2026-05-06T01:37:30.46546Z","steps":["trace[633889970] 'agreement among raft nodes before linearized reading' (duration: 144.816571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:37:30.480484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.295416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:37:30.480533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.317925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:37:30.48056Z","caller":"traceutil/trace.go:171","msg":"trace[403737061] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11522; }","duration":"133.397647ms","start":"2026-05-06T01:37:30.347132Z","end":"2026-05-06T01:37:30.48053Z","steps":["trace[403737061] 'agreement among raft nodes before linearized reading' (duration: 133.283234ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:37:30.480586Z","caller":"traceutil/trace.go:171","msg":"trace[240287896] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11522; }","duration":"133.427268ms","start":"2026-05-06T01:37:30.347144Z","end":"2026-05-06T01:37:30.480572Z","steps":["trace[240287896] 'agreement among raft nodes before linearized reading' (duration: 133.281405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:37:30.480656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.023258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-05-06T01:37:30.480748Z","caller":"traceutil/trace.go:171","msg":"trace[1041164420] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11522; }","duration":"132.14581ms","start":"2026-05-06T01:37:30.348582Z","end":"2026-05-06T01:37:30.480728Z","steps":["trace[1041164420] 'agreement among raft nodes before linearized reading' (duration: 131.962466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:37:30.480712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.189094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-06T01:37:30.480827Z","caller":"traceutil/trace.go:171","msg":"trace[241301237] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11522; }","duration":"134.361227ms","start":"2026-05-06T01:37:30.346439Z","end":"2026-05-06T01:37:30.4808Z","steps":["trace[241301237] 'agreement among raft nodes before linearized reading' (duration: 134.057761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:37:30.480878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.575831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:37:30.480966Z","caller":"traceutil/trace.go:171","msg":"trace[1021769129] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11522; }","duration":"133.697653ms","start":"2026-05-06T01:37:30.347244Z","end":"2026-05-06T01:37:30.480942Z","steps":["trace[1021769129] 'agreement among raft nodes before linearized reading' (duration: 133.468859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:37:30.481167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.090282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:37:30.481225Z","caller":"traceutil/trace.go:171","msg":"trace[1358845885] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11522; }","duration":"134.179804ms","start":"2026-05-06T01:37:30.347027Z","end":"2026-05-06T01:37:30.481207Z","steps":["trace[1358845885] 'agreement among raft nodes before linearized reading' (duration: 133.647313ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:40:51.84617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10566} {"level":"info","ts":"2026-05-06T01:40:51.930519Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10566,"took":"80.708122ms","hash":3107933582,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":26591232,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-06T01:40:51.930575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3107933582,"revision":10566,"compact-revision":8321} {"level":"info","ts":"2026-05-06T01:44:26.29975Z","caller":"traceutil/trace.go:171","msg":"trace[2118537099] transaction","detail":"{read_only:false; response_revision:14257; number_of_response:1; }","duration":"103.531395ms","start":"2026-05-06T01:44:26.196197Z","end":"2026-05-06T01:44:26.299728Z","steps":["trace[2118537099] 'process raft request' (duration: 102.938332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:44:42.884419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.842794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-06T01:44:42.884553Z","caller":"traceutil/trace.go:171","msg":"trace[789608332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14356; }","duration":"168.062719ms","start":"2026-05-06T01:44:42.71646Z","end":"2026-05-06T01:44:42.884523Z","steps":["trace[789608332] 'range keys from in-memory index tree' (duration: 167.427185ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:44:43.075326Z","caller":"traceutil/trace.go:171","msg":"trace[1308910158] transaction","detail":"{read_only:false; response_revision:14357; number_of_response:1; }","duration":"185.341828ms","start":"2026-05-06T01:44:42.889954Z","end":"2026-05-06T01:44:43.075296Z","steps":["trace[1308910158] 'process raft request' (duration: 185.167455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:44:55.966203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.124944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:44:55.966444Z","caller":"traceutil/trace.go:171","msg":"trace[2001468769] transaction","detail":"{read_only:false; response_revision:14495; number_of_response:1; }","duration":"153.299484ms","start":"2026-05-06T01:44:55.813118Z","end":"2026-05-06T01:44:55.966417Z","steps":["trace[2001468769] 'process raft request' (duration: 51.822003ms)","trace[2001468769] 'compare' (duration: 100.92583ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:44:55.966723Z","caller":"traceutil/trace.go:171","msg":"trace[116023137] transaction","detail":"{read_only:false; response_revision:14496; number_of_response:1; }","duration":"150.961084ms","start":"2026-05-06T01:44:55.815705Z","end":"2026-05-06T01:44:55.966666Z","steps":["trace[116023137] 'process raft request' (duration: 150.647808ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:44:55.966719Z","caller":"traceutil/trace.go:171","msg":"trace[227628097] linearizableReadLoop","detail":"{readStateIndex:15003; appliedIndex:15001; }","duration":"150.916603ms","start":"2026-05-06T01:44:55.815745Z","end":"2026-05-06T01:44:55.966662Z","steps":["trace[227628097] 'read index received' (duration: 49.211117ms)","trace[227628097] 'applied index is now lower than readState.Index' (duration: 101.700186ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:44:55.966922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.131879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:44:55.967037Z","caller":"traceutil/trace.go:171","msg":"trace[1323153272] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:14496; }","duration":"151.302582ms","start":"2026-05-06T01:44:55.815705Z","end":"2026-05-06T01:44:55.967007Z","steps":["trace[1323153272] 'agreement among raft nodes before linearized reading' (duration: 151.076427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:44:55.968204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.614074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:88 size:949970"} {"level":"info","ts":"2026-05-06T01:44:55.968262Z","caller":"traceutil/trace.go:171","msg":"trace[1422394509] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:88; response_revision:14496; }","duration":"139.730816ms","start":"2026-05-06T01:44:55.828517Z","end":"2026-05-06T01:44:55.968248Z","steps":["trace[1422394509] 'agreement among raft nodes before linearized reading' (duration: 138.607893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:45:05.367103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.488171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-025f5224-9419-4f69-8d60-d10373a24204\" ","response":"range_response_count:1 size:3637"} {"level":"warn","ts":"2026-05-06T01:45:05.367171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.573748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T01:45:05.367231Z","caller":"traceutil/trace.go:171","msg":"trace[1033838476] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-025f5224-9419-4f69-8d60-d10373a24204; range_end:; response_count:1; response_revision:14663; }","duration":"185.687375ms","start":"2026-05-06T01:45:05.18152Z","end":"2026-05-06T01:45:05.367207Z","steps":["trace[1033838476] 'range keys from in-memory index tree' (duration: 185.250075ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:45:05.367293Z","caller":"traceutil/trace.go:171","msg":"trace[1872625271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14663; }","duration":"182.743672ms","start":"2026-05-06T01:45:05.184522Z","end":"2026-05-06T01:45:05.367266Z","steps":["trace[1872625271] 'range keys from in-memory index tree' (duration: 182.340964ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:45:51.85389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12820} {"level":"info","ts":"2026-05-06T01:45:51.933719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12820,"took":"76.319948ms","hash":2677206711,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30105600,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T01:45:51.933806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2677206711,"revision":12820,"compact-revision":10566} {"level":"info","ts":"2026-05-06T01:46:22.431384Z","caller":"traceutil/trace.go:171","msg":"trace[1594357694] linearizableReadLoop","detail":"{readStateIndex:15955; appliedIndex:15954; }","duration":"109.116008ms","start":"2026-05-06T01:46:22.322238Z","end":"2026-05-06T01:46:22.431354Z","steps":["trace[1594357694] 'read index received' (duration: 108.934914ms)","trace[1594357694] 'applied index is now lower than readState.Index' (duration: 179.934µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:46:22.431459Z","caller":"traceutil/trace.go:171","msg":"trace[1936788657] transaction","detail":"{read_only:false; response_revision:15423; number_of_response:1; }","duration":"194.9439ms","start":"2026-05-06T01:46:22.236471Z","end":"2026-05-06T01:46:22.431415Z","steps":["trace[1936788657] 'process raft request' (duration: 194.692065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:46:22.431625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.354174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:46:22.431734Z","caller":"traceutil/trace.go:171","msg":"trace[717236638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15423; }","duration":"109.478986ms","start":"2026-05-06T01:46:22.322232Z","end":"2026-05-06T01:46:22.431711Z","steps":["trace[717236638] 'agreement among raft nodes before linearized reading' (duration: 109.287282ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:46:22.520953Z","caller":"traceutil/trace.go:171","msg":"trace[1994317808] transaction","detail":"{read_only:false; response_revision:15425; number_of_response:1; }","duration":"114.968883ms","start":"2026-05-06T01:46:22.405945Z","end":"2026-05-06T01:46:22.520914Z","steps":["trace[1994317808] 'process raft request' (duration: 114.871351ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:46:22.521056Z","caller":"traceutil/trace.go:171","msg":"trace[871874564] transaction","detail":"{read_only:false; response_revision:15424; number_of_response:1; }","duration":"142.397459ms","start":"2026-05-06T01:46:22.378624Z","end":"2026-05-06T01:46:22.521021Z","steps":["trace[871874564] 'process raft request' (duration: 131.781741ms)","trace[871874564] 'compare' (duration: 10.214098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:46:22.521203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.763342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-05-06T01:46:22.521282Z","caller":"traceutil/trace.go:171","msg":"trace[1198162410] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15425; }","duration":"198.872574ms","start":"2026-05-06T01:46:22.322391Z","end":"2026-05-06T01:46:22.521264Z","steps":["trace[1198162410] 'agreement among raft nodes before linearized reading' (duration: 198.651529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:46:22.521489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.789752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-06T01:46:22.521528Z","caller":"traceutil/trace.go:171","msg":"trace[863661906] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15425; }","duration":"198.883204ms","start":"2026-05-06T01:46:22.322635Z","end":"2026-05-06T01:46:22.521518Z","steps":["trace[863661906] 'agreement among raft nodes before linearized reading' (duration: 198.776942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:46:22.521072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.23239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:46:22.521917Z","caller":"traceutil/trace.go:171","msg":"trace[150749712] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:15425; }","duration":"199.105879ms","start":"2026-05-06T01:46:22.322784Z","end":"2026-05-06T01:46:22.52189Z","steps":["trace[150749712] 'agreement among raft nodes before linearized reading' (duration: 198.174419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:46:22.521675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.991626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:46:22.522047Z","caller":"traceutil/trace.go:171","msg":"trace[435609519] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15425; }","duration":"199.408505ms","start":"2026-05-06T01:46:22.322619Z","end":"2026-05-06T01:46:22.522027Z","steps":["trace[435609519] 'agreement among raft nodes before linearized reading' (duration: 198.897454ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:50:51.863228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15242} {"level":"info","ts":"2026-05-06T01:50:51.968411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15242,"took":"101.110085ms","hash":3678827639,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31469568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-06T01:50:51.968495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3678827639,"revision":15242,"compact-revision":12820} {"level":"info","ts":"2026-05-06T01:52:30.439357Z","caller":"traceutil/trace.go:171","msg":"trace[213110443] transaction","detail":"{read_only:false; response_revision:18110; number_of_response:1; }","duration":"111.244852ms","start":"2026-05-06T01:52:30.328076Z","end":"2026-05-06T01:52:30.439321Z","steps":["trace[213110443] 'process raft request' (duration: 111.056018ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:52:30.482274Z","caller":"traceutil/trace.go:171","msg":"trace[178001315] transaction","detail":"{read_only:false; response_revision:18111; number_of_response:1; }","duration":"146.654865ms","start":"2026-05-06T01:52:30.33559Z","end":"2026-05-06T01:52:30.482245Z","steps":["trace[178001315] 'process raft request' (duration: 129.069701ms)","trace[178001315] 'compare' (duration: 16.43412ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:52:30.482586Z","caller":"traceutil/trace.go:171","msg":"trace[1419011975] transaction","detail":"{read_only:false; response_revision:18112; number_of_response:1; }","duration":"143.177461ms","start":"2026-05-06T01:52:30.3394Z","end":"2026-05-06T01:52:30.482578Z","steps":["trace[1419011975] 'process raft request' (duration: 142.566839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:52:30.807836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.622767ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-06T01:52:30.808003Z","caller":"traceutil/trace.go:171","msg":"trace[1342876020] linearizableReadLoop","detail":"{readStateIndex:18735; appliedIndex:18734; }","duration":"274.829068ms","start":"2026-05-06T01:52:30.533131Z","end":"2026-05-06T01:52:30.80796Z","steps":["trace[1342876020] 'read index received' (duration: 12.039087ms)","trace[1342876020] 'applied index is now lower than readState.Index' (duration: 262.787911ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:52:30.808317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.311061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-t7wwc\" ","response":"range_response_count:1 size:22022"} {"level":"warn","ts":"2026-05-06T01:52:30.808317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.160854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-gvh2b\" ","response":"range_response_count:1 size:20778"} {"level":"warn","ts":"2026-05-06T01:52:30.808349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.276357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-06T01:52:30.808427Z","caller":"traceutil/trace.go:171","msg":"trace[535658068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18112; }","duration":"222.419741ms","start":"2026-05-06T01:52:30.585989Z","end":"2026-05-06T01:52:30.808409Z","steps":["trace[535658068] 'agreement among raft nodes before linearized reading' (duration: 222.164275ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:52:30.808427Z","caller":"traceutil/trace.go:171","msg":"trace[383594484] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-gvh2b; range_end:; response_count:1; response_revision:18112; }","duration":"275.336679ms","start":"2026-05-06T01:52:30.533064Z","end":"2026-05-06T01:52:30.808401Z","steps":["trace[383594484] 'agreement among raft nodes before linearized reading' (duration: 275.003761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:52:30.808462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.764149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-06T01:52:30.808493Z","caller":"traceutil/trace.go:171","msg":"trace[1490262150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18112; }","duration":"268.81282ms","start":"2026-05-06T01:52:30.539672Z","end":"2026-05-06T01:52:30.808484Z","steps":["trace[1490262150] 'agreement among raft nodes before linearized reading' (duration: 268.711207ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:52:30.808359Z","caller":"traceutil/trace.go:171","msg":"trace[1427746704] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-t7wwc; range_end:; response_count:1; response_revision:18112; }","duration":"269.386412ms","start":"2026-05-06T01:52:30.53896Z","end":"2026-05-06T01:52:30.808346Z","steps":["trace[1427746704] 'agreement among raft nodes before linearized reading' (duration: 269.170597ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:52:31.233302Z","caller":"traceutil/trace.go:171","msg":"trace[1151280220] transaction","detail":"{read_only:false; response_revision:18114; number_of_response:1; }","duration":"414.032655ms","start":"2026-05-06T01:52:30.819249Z","end":"2026-05-06T01:52:31.233281Z","steps":["trace[1151280220] 'process raft request' (duration: 413.995705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:52:31.233467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:52:30.819235Z","time spent":"414.153958ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:52:31.233632Z","caller":"traceutil/trace.go:171","msg":"trace[140814523] transaction","detail":"{read_only:false; response_revision:18113; number_of_response:1; }","duration":"414.392483ms","start":"2026-05-06T01:52:30.819232Z","end":"2026-05-06T01:52:31.233625Z","steps":["trace[140814523] 'process raft request' (duration: 413.870362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:52:31.233681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:52:30.81921Z","time spent":"414.444164ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:52:31.305817Z","caller":"traceutil/trace.go:171","msg":"trace[2016776434] transaction","detail":"{read_only:false; response_revision:18115; number_of_response:1; }","duration":"486.49758ms","start":"2026-05-06T01:52:30.819299Z","end":"2026-05-06T01:52:31.305797Z","steps":["trace[2016776434] 'process raft request' (duration: 486.310096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:52:31.305924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:52:30.819296Z","time spent":"486.581672ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:52:31.306067Z","caller":"traceutil/trace.go:171","msg":"trace[175052904] linearizableReadLoop","detail":"{readStateIndex:18739; appliedIndex:18737; }","duration":"110.7618ms","start":"2026-05-06T01:52:31.195271Z","end":"2026-05-06T01:52:31.306033Z","steps":["trace[175052904] 'read index received' (duration: 38.396527ms)","trace[175052904] 'applied index is now lower than readState.Index' (duration: 72.363993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:52:31.306345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.043676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6b5dd\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-05-06T01:52:31.306388Z","caller":"traceutil/trace.go:171","msg":"trace[1088702476] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6b5dd; range_end:; response_count:1; response_revision:18116; }","duration":"111.162908ms","start":"2026-05-06T01:52:31.195213Z","end":"2026-05-06T01:52:31.306376Z","steps":["trace[1088702476] 'agreement among raft nodes before linearized reading' (duration: 110.940824ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:52:31.306511Z","caller":"traceutil/trace.go:171","msg":"trace[1119316058] transaction","detail":"{read_only:false; response_revision:18116; number_of_response:1; }","duration":"481.157245ms","start":"2026-05-06T01:52:30.82534Z","end":"2026-05-06T01:52:31.306497Z","steps":["trace[1119316058] 'process raft request' (duration: 480.380629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:52:31.306589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:52:30.825326Z","time spent":"481.227896ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20895,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:54:07.925508Z","caller":"traceutil/trace.go:171","msg":"trace[664529961] transaction","detail":"{read_only:false; response_revision:19106; number_of_response:1; }","duration":"101.802319ms","start":"2026-05-06T01:54:07.823674Z","end":"2026-05-06T01:54:07.925477Z","steps":["trace[664529961] 'process raft request' (duration: 50.632179ms)","trace[664529961] 'compare' (duration: 50.753392ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:54:47.047024Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T01:54:47.051964Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-06T01:54:47.052122Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-06T01:55:51.869744Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17445} {"level":"info","ts":"2026-05-06T01:55:51.976663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17445,"took":"101.805857ms","hash":1702626566,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":34017280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-06T01:55:51.976734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1702626566,"revision":17445,"compact-revision":15242} {"level":"warn","ts":"2026-05-06T01:57:22.823956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831618559514808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:57:23.324224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831618559514808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:57:23.794238Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.629817937s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T01:57:23.795054Z","caller":"traceutil/trace.go:171","msg":"trace[1200774371] transaction","detail":"{read_only:false; response_revision:20513; number_of_response:1; }","duration":"1.630790407s","start":"2026-05-06T01:57:22.164224Z","end":"2026-05-06T01:57:23.795014Z","steps":["trace[1200774371] 'process raft request' (duration: 1.630387939s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:23.795385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:22.164203Z","time spent":"1.630974662s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:57:23.841587Z","caller":"traceutil/trace.go:171","msg":"trace[1401495267] transaction","detail":"{read_only:false; response_revision:20515; number_of_response:1; }","duration":"642.418809ms","start":"2026-05-06T01:57:23.199136Z","end":"2026-05-06T01:57:23.841555Z","steps":["trace[1401495267] 'process raft request' (duration: 642.316457ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:57:23.841618Z","caller":"traceutil/trace.go:171","msg":"trace[1686891274] transaction","detail":"{read_only:false; response_revision:20514; number_of_response:1; }","duration":"648.495869ms","start":"2026-05-06T01:57:23.193069Z","end":"2026-05-06T01:57:23.841565Z","steps":["trace[1686891274] 'process raft request' (duration: 626.305966ms)","trace[1686891274] 'compare' (duration: 21.884886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:57:23.841775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:23.199101Z","time spent":"642.569863ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:57:23.841834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:23.193051Z","time spent":"648.661143ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:57:23.841975Z","caller":"traceutil/trace.go:171","msg":"trace[2060058919] transaction","detail":"{read_only:false; response_revision:20516; number_of_response:1; }","duration":"205.912724ms","start":"2026-05-06T01:57:23.636014Z","end":"2026-05-06T01:57:23.841927Z","steps":["trace[2060058919] 'process raft request' (duration: 205.496625ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:57:23.842019Z","caller":"traceutil/trace.go:171","msg":"trace[415732407] linearizableReadLoop","detail":"{readStateIndex:21215; appliedIndex:21212; }","duration":"1.518880574s","start":"2026-05-06T01:57:22.323101Z","end":"2026-05-06T01:57:23.841981Z","steps":["trace[415732407] 'read index received' (duration: 1.471572307s)","trace[415732407] 'applied index is now lower than readState.Index' (duration: 47.306917ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:57:23.842078Z","caller":"traceutil/trace.go:171","msg":"trace[1784789617] transaction","detail":"{read_only:false; response_revision:20517; number_of_response:1; }","duration":"160.847104ms","start":"2026-05-06T01:57:23.681217Z","end":"2026-05-06T01:57:23.842064Z","steps":["trace[1784789617] 'process raft request' (duration: 160.588379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:23.842268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5191532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T01:57:23.842285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141402855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T01:57:23.842317Z","caller":"traceutil/trace.go:171","msg":"trace[1726348176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20517; }","duration":"1.519277942s","start":"2026-05-06T01:57:22.323028Z","end":"2026-05-06T01:57:23.842306Z","steps":["trace[1726348176] 'agreement among raft nodes before linearized reading' (duration: 1.519127579s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:57:23.842345Z","caller":"traceutil/trace.go:171","msg":"trace[122435855] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:20517; }","duration":"1.141498438s","start":"2026-05-06T01:57:22.700828Z","end":"2026-05-06T01:57:23.842326Z","steps":["trace[122435855] 'agreement among raft nodes before linearized reading' (duration: 1.141407376s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:23.84236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:22.323005Z","time spent":"1.519347024s","remote":"127.0.0.1:53604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:57:23.842395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:22.700804Z","time spent":"1.141582239s","remote":"127.0.0.1:47354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:57:23.842438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.494333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-06T01:57:23.842483Z","caller":"traceutil/trace.go:171","msg":"trace[844838979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20517; }","duration":"844.613425ms","start":"2026-05-06T01:57:22.997855Z","end":"2026-05-06T01:57:23.842469Z","steps":["trace[844838979] 'agreement among raft nodes before linearized reading' (duration: 844.377201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:23.842517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:22.997836Z","time spent":"844.671626ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T01:57:23.842546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028377339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-06T01:57:23.842589Z","caller":"traceutil/trace.go:171","msg":"trace[1099463063] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20517; }","duration":"1.02842372s","start":"2026-05-06T01:57:22.814154Z","end":"2026-05-06T01:57:23.842578Z","steps":["trace[1099463063] 'agreement among raft nodes before linearized reading' (duration: 1.028133604s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:23.842561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.207555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-06T01:57:23.842594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.032876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T01:57:23.84262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:22.814071Z","time spent":"1.028541883s","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-06T01:57:23.842651Z","caller":"traceutil/trace.go:171","msg":"trace[164092608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20517; }","duration":"290.119787ms","start":"2026-05-06T01:57:23.552515Z","end":"2026-05-06T01:57:23.842634Z","steps":["trace[164092608] 'agreement among raft nodes before linearized reading' (duration: 289.731069ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:57:23.842651Z","caller":"traceutil/trace.go:171","msg":"trace[1503286404] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:20517; }","duration":"364.389519ms","start":"2026-05-06T01:57:23.478241Z","end":"2026-05-06T01:57:23.84263Z","steps":["trace[1503286404] 'agreement among raft nodes before linearized reading' (duration: 364.067302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:23.842737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:23.478212Z","time spent":"364.512412ms","remote":"127.0.0.1:53772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":207,"response size":32,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:57:23.842769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012012222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-06T01:57:23.842806Z","caller":"traceutil/trace.go:171","msg":"trace[1451338513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20517; }","duration":"1.012081092s","start":"2026-05-06T01:57:22.830714Z","end":"2026-05-06T01:57:23.842795Z","steps":["trace[1451338513] 'agreement among raft nodes before linearized reading' (duration: 1.01152359s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:23.842835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:22.830696Z","time spent":"1.012132044s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:57:23.842893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006871642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-06T01:57:23.843013Z","caller":"traceutil/trace.go:171","msg":"trace[1018797083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20517; }","duration":"1.007095916s","start":"2026-05-06T01:57:22.835887Z","end":"2026-05-06T01:57:23.842982Z","steps":["trace[1018797083] 'agreement among raft nodes before linearized reading' (duration: 1.006403811s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:23.843067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:22.835859Z","time spent":"1.007197259s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:57:58.507596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831618559515313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:57:59.008626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831618559515313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:57:59.509505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831618559515313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:57:59.544395Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.647172777s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T01:57:59.544883Z","caller":"traceutil/trace.go:171","msg":"trace[443911919] transaction","detail":"{read_only:false; response_revision:20692; number_of_response:1; }","duration":"1.647740528s","start":"2026-05-06T01:57:57.897107Z","end":"2026-05-06T01:57:59.544848Z","steps":["trace[443911919] 'process raft request' (duration: 1.647553864s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:59.545073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:57.897078Z","time spent":"1.647896491s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:57:59.569445Z","caller":"traceutil/trace.go:171","msg":"trace[1594589141] transaction","detail":"{read_only:false; response_revision:20693; number_of_response:1; }","duration":"1.582378796s","start":"2026-05-06T01:57:57.98704Z","end":"2026-05-06T01:57:59.569419Z","steps":["trace[1594589141] 'process raft request' (duration: 1.582103541s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:57:59.569444Z","caller":"traceutil/trace.go:171","msg":"trace[1405853245] linearizableReadLoop","detail":"{readStateIndex:21403; appliedIndex:21398; }","duration":"1.562555784s","start":"2026-05-06T01:57:58.006861Z","end":"2026-05-06T01:57:59.569416Z","steps":["trace[1405853245] 'read index received' (duration: 1.53793031s)","trace[1405853245] 'applied index is now lower than readState.Index' (duration: 24.624524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:57:59.56958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:57.987002Z","time spent":"1.582504109s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:57:59.569649Z","caller":"traceutil/trace.go:171","msg":"trace[1053773270] transaction","detail":"{read_only:false; response_revision:20694; number_of_response:1; }","duration":"1.561426231s","start":"2026-05-06T01:57:58.0082Z","end":"2026-05-06T01:57:59.569626Z","steps":["trace[1053773270] 'process raft request' (duration: 1.561094694s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:57:59.569639Z","caller":"traceutil/trace.go:171","msg":"trace[365724903] transaction","detail":"{read_only:false; response_revision:20696; number_of_response:1; }","duration":"1.559159302s","start":"2026-05-06T01:57:58.010418Z","end":"2026-05-06T01:57:59.569577Z","steps":["trace[365724903] 'process raft request' (duration: 1.558965718s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:59.569829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469670096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T01:57:59.569877Z","caller":"traceutil/trace.go:171","msg":"trace[50290474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20696; }","duration":"1.469737508s","start":"2026-05-06T01:57:58.100125Z","end":"2026-05-06T01:57:59.569863Z","steps":["trace[50290474] 'agreement among raft nodes before linearized reading' (duration: 1.469658186s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:59.569876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.008185Z","time spent":"1.561572504s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:57:59.569914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.100113Z","time spent":"1.469793569s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T01:57:59.569894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511462526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-06T01:57:59.569984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.436217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-06T01:57:59.570018Z","caller":"traceutil/trace.go:171","msg":"trace[1777979793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20696; }","duration":"1.51163372s","start":"2026-05-06T01:57:58.05836Z","end":"2026-05-06T01:57:59.569993Z","steps":["trace[1777979793] 'agreement among raft nodes before linearized reading' (duration: 1.511295223s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:57:59.570037Z","caller":"traceutil/trace.go:171","msg":"trace[1353742132] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:20696; }","duration":"848.530449ms","start":"2026-05-06T01:57:58.72149Z","end":"2026-05-06T01:57:59.57002Z","steps":["trace[1353742132] 'agreement among raft nodes before linearized reading' (duration: 848.360115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:59.570073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.058352Z","time spent":"1.511711752s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:57:59.570078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.721455Z","time spent":"848.614891ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1873,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-05-06T01:57:59.570093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246117215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T01:57:59.57013Z","caller":"traceutil/trace.go:171","msg":"trace[735249817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20696; }","duration":"1.246214218s","start":"2026-05-06T01:57:58.323906Z","end":"2026-05-06T01:57:59.57012Z","steps":["trace[735249817] 'agreement among raft nodes before linearized reading' (duration: 1.246134536s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:59.570168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.323867Z","time spent":"1.246295639s","remote":"127.0.0.1:53604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:57:59.570171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470156727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-06T01:57:59.570244Z","caller":"traceutil/trace.go:171","msg":"trace[5096213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20696; }","duration":"1.470238178s","start":"2026-05-06T01:57:58.099991Z","end":"2026-05-06T01:57:59.570229Z","steps":["trace[5096213] 'agreement among raft nodes before linearized reading' (duration: 1.469992493s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:59.570338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473279914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-06T01:57:59.570381Z","caller":"traceutil/trace.go:171","msg":"trace[1992255003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20696; }","duration":"1.473372095s","start":"2026-05-06T01:57:58.096997Z","end":"2026-05-06T01:57:59.570369Z","steps":["trace[1992255003] 'agreement among raft nodes before linearized reading' (duration: 1.473277763s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:59.570399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.099984Z","time spent":"1.470404862s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T01:57:59.570409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.09697Z","time spent":"1.473433746s","remote":"127.0.0.1:53846","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-05-06T01:57:59.569936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.010396Z","time spent":"1.559407267s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:57:59.569754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562879691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-06T01:57:59.570794Z","caller":"traceutil/trace.go:171","msg":"trace[2142016746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20696; }","duration":"1.563973624s","start":"2026-05-06T01:57:58.006807Z","end":"2026-05-06T01:57:59.570781Z","steps":["trace[2142016746] 'agreement among raft nodes before linearized reading' (duration: 1.562750728s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:57:59.569589Z","caller":"traceutil/trace.go:171","msg":"trace[671050758] transaction","detail":"{read_only:false; response_revision:20695; number_of_response:1; }","duration":"1.561112683s","start":"2026-05-06T01:57:58.008431Z","end":"2026-05-06T01:57:59.569543Z","steps":["trace[671050758] 'process raft request' (duration: 1.560897829s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:57:59.570854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.006794Z","time spent":"1.564034225s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:57:59.571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:57:58.008404Z","time spent":"1.562483402s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:58:07.77057Z","caller":"traceutil/trace.go:171","msg":"trace[901125175] transaction","detail":"{read_only:false; response_revision:20735; number_of_response:1; }","duration":"106.061659ms","start":"2026-05-06T01:58:07.664473Z","end":"2026-05-06T01:58:07.770535Z","steps":["trace[901125175] 'process raft request' (duration: 105.971497ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:58:07.770943Z","caller":"traceutil/trace.go:171","msg":"trace[471335136] transaction","detail":"{read_only:false; response_revision:20734; number_of_response:1; }","duration":"120.633149ms","start":"2026-05-06T01:58:07.650275Z","end":"2026-05-06T01:58:07.770908Z","steps":["trace[471335136] 'process raft request' (duration: 94.670076ms)","trace[471335136] 'compare' (duration: 25.053584ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:58:07.954582Z","caller":"traceutil/trace.go:171","msg":"trace[403185740] transaction","detail":"{read_only:false; response_revision:20741; number_of_response:1; }","duration":"162.672194ms","start":"2026-05-06T01:58:07.791884Z","end":"2026-05-06T01:58:07.954556Z","steps":["trace[403185740] 'process raft request' (duration: 162.638423ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:58:07.954891Z","caller":"traceutil/trace.go:171","msg":"trace[725396176] transaction","detail":"{read_only:false; response_revision:20738; number_of_response:1; }","duration":"165.147697ms","start":"2026-05-06T01:58:07.789737Z","end":"2026-05-06T01:58:07.954884Z","steps":["trace[725396176] 'process raft request' (duration: 132.44986ms)","trace[725396176] 'compare' (duration: 32.125315ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:58:07.954973Z","caller":"traceutil/trace.go:171","msg":"trace[305002429] transaction","detail":"{read_only:false; response_revision:20739; number_of_response:1; }","duration":"164.393281ms","start":"2026-05-06T01:58:07.790575Z","end":"2026-05-06T01:58:07.954968Z","steps":["trace[305002429] 'process raft request' (duration: 163.856099ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:58:07.955039Z","caller":"traceutil/trace.go:171","msg":"trace[1507712308] transaction","detail":"{read_only:false; response_revision:20740; number_of_response:1; }","duration":"164.38253ms","start":"2026-05-06T01:58:07.790652Z","end":"2026-05-06T01:58:07.955034Z","steps":["trace[1507712308] 'process raft request' (duration: 163.804868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:58:28.066237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.453219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T01:58:28.066504Z","caller":"traceutil/trace.go:171","msg":"trace[1457144380] linearizableReadLoop","detail":"{readStateIndex:21556; appliedIndex:21553; }","duration":"149.407411ms","start":"2026-05-06T01:58:27.917075Z","end":"2026-05-06T01:58:28.066483Z","steps":["trace[1457144380] 'read index received' (duration: 1.801778ms)","trace[1457144380] 'applied index is now lower than readState.Index' (duration: 147.604503ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:58:28.066717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.649306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-06T01:58:28.066754Z","caller":"traceutil/trace.go:171","msg":"trace[104069924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20842; }","duration":"149.734128ms","start":"2026-05-06T01:58:27.91701Z","end":"2026-05-06T01:58:28.066744Z","steps":["trace[104069924] 'agreement among raft nodes before linearized reading' (duration: 149.538874ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:58:28.067086Z","caller":"traceutil/trace.go:171","msg":"trace[445537565] transaction","detail":"{read_only:false; response_revision:20840; number_of_response:1; }","duration":"222.028709ms","start":"2026-05-06T01:58:27.845043Z","end":"2026-05-06T01:58:28.067071Z","steps":["trace[445537565] 'process raft request' (duration: 73.627649ms)","trace[445537565] 'compare' (duration: 147.298376ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:58:28.067233Z","caller":"traceutil/trace.go:171","msg":"trace[2123972311] transaction","detail":"{read_only:false; response_revision:20841; number_of_response:1; }","duration":"220.867093ms","start":"2026-05-06T01:58:27.846353Z","end":"2026-05-06T01:58:28.06722Z","steps":["trace[2123972311] 'process raft request' (duration: 220.024974ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:58:28.067348Z","caller":"traceutil/trace.go:171","msg":"trace[2051386701] transaction","detail":"{read_only:false; response_revision:20842; number_of_response:1; }","duration":"203.989954ms","start":"2026-05-06T01:58:27.86335Z","end":"2026-05-06T01:58:28.06734Z","steps":["trace[2051386701] 'process raft request' (duration: 203.090874ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:58:28.352897Z","caller":"traceutil/trace.go:171","msg":"trace[601764369] linearizableReadLoop","detail":"{readStateIndex:21558; appliedIndex:21557; }","duration":"225.656256ms","start":"2026-05-06T01:58:28.127209Z","end":"2026-05-06T01:58:28.352865Z","steps":["trace[601764369] 'read index received' (duration: 204.27055ms)","trace[601764369] 'applied index is now lower than readState.Index' (duration: 21.383826ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:58:28.353038Z","caller":"traceutil/trace.go:171","msg":"trace[223420150] transaction","detail":"{read_only:false; response_revision:20844; number_of_response:1; }","duration":"244.771393ms","start":"2026-05-06T01:58:28.10822Z","end":"2026-05-06T01:58:28.352991Z","steps":["trace[223420150] 'process raft request' (duration: 223.365546ms)","trace[223420150] 'compare' (duration: 20.893695ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:58:28.353207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.959072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T01:58:28.353256Z","caller":"traceutil/trace.go:171","msg":"trace[1736089494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20844; }","duration":"226.072254ms","start":"2026-05-06T01:58:28.127171Z","end":"2026-05-06T01:58:28.353244Z","steps":["trace[1736089494] 'agreement among raft nodes before linearized reading' (duration: 225.85857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:58:28.353569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.238618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T01:58:28.353622Z","caller":"traceutil/trace.go:171","msg":"trace[1952011753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20844; }","duration":"226.356801ms","start":"2026-05-06T01:58:28.127251Z","end":"2026-05-06T01:58:28.353608Z","steps":["trace[1952011753] 'agreement among raft nodes before linearized reading' (duration: 226.174047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:58:28.353714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.171847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-06T01:58:28.353761Z","caller":"traceutil/trace.go:171","msg":"trace[1298248042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20844; }","duration":"226.267949ms","start":"2026-05-06T01:58:28.127483Z","end":"2026-05-06T01:58:28.353751Z","steps":["trace[1298248042] 'agreement among raft nodes before linearized reading' (duration: 226.153946ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:58:28.555781Z","caller":"traceutil/trace.go:171","msg":"trace[1748448476] transaction","detail":"{read_only:false; response_revision:20846; number_of_response:1; }","duration":"196.961674ms","start":"2026-05-06T01:58:28.358792Z","end":"2026-05-06T01:58:28.555754Z","steps":["trace[1748448476] 'process raft request' (duration: 196.871302ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:58:28.555831Z","caller":"traceutil/trace.go:171","msg":"trace[2117027142] transaction","detail":"{read_only:false; response_revision:20845; number_of_response:1; }","duration":"197.780502ms","start":"2026-05-06T01:58:28.358009Z","end":"2026-05-06T01:58:28.555789Z","steps":["trace[2117027142] 'process raft request' (duration: 157.592386ms)","trace[2117027142] 'compare' (duration: 39.91726ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:58:28.555952Z","caller":"traceutil/trace.go:171","msg":"trace[575220296] transaction","detail":"{read_only:false; response_revision:20847; number_of_response:1; }","duration":"196.575156ms","start":"2026-05-06T01:58:28.359353Z","end":"2026-05-06T01:58:28.555928Z","steps":["trace[575220296] 'process raft request' (duration: 196.362301ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:00:17.343701Z","caller":"traceutil/trace.go:171","msg":"trace[296143798] transaction","detail":"{read_only:false; response_revision:21839; number_of_response:1; }","duration":"174.753572ms","start":"2026-05-06T02:00:17.16892Z","end":"2026-05-06T02:00:17.343673Z","steps":["trace[296143798] 'process raft request' (duration: 136.893735ms)","trace[296143798] 'compare' (duration: 37.512569ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:00:17.344579Z","caller":"traceutil/trace.go:171","msg":"trace[1050599500] transaction","detail":"{read_only:false; response_revision:21840; number_of_response:1; }","duration":"175.245641ms","start":"2026-05-06T02:00:17.169316Z","end":"2026-05-06T02:00:17.344562Z","steps":["trace[1050599500] 'process raft request' (duration: 174.307742ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:00:51.882515Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19769} {"level":"info","ts":"2026-05-06T02:00:52.010749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19769,"took":"123.603162ms","hash":3894623412,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":29741056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T02:00:52.010831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3894623412,"revision":19769,"compact-revision":17445} {"level":"info","ts":"2026-05-06T02:02:30.25977Z","caller":"traceutil/trace.go:171","msg":"trace[587090967] linearizableReadLoop","detail":"{readStateIndex:24199; appliedIndex:24198; }","duration":"275.921036ms","start":"2026-05-06T02:02:29.983792Z","end":"2026-05-06T02:02:30.259713Z","steps":["trace[587090967] 'read index received' (duration: 192.566891ms)","trace[587090967] 'applied index is now lower than readState.Index' (duration: 83.351835ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:02:30.25982Z","caller":"traceutil/trace.go:171","msg":"trace[1420111603] transaction","detail":"{read_only:false; response_revision:23409; number_of_response:1; }","duration":"375.970747ms","start":"2026-05-06T02:02:29.883791Z","end":"2026-05-06T02:02:30.259762Z","steps":["trace[1420111603] 'process raft request' (duration: 292.126922ms)","trace[1420111603] 'compare' (duration: 82.994796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:02:30.260195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.382326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-06T02:02:30.260243Z","caller":"traceutil/trace.go:171","msg":"trace[1964997729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23409; }","duration":"276.499759ms","start":"2026-05-06T02:02:29.983729Z","end":"2026-05-06T02:02:30.260229Z","steps":["trace[1964997729] 'agreement among raft nodes before linearized reading' (duration: 276.249584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:02:30.26054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.383691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:02:30.26061Z","caller":"traceutil/trace.go:171","msg":"trace[644693920] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:23409; }","duration":"118.499503ms","start":"2026-05-06T02:02:30.142092Z","end":"2026-05-06T02:02:30.260592Z","steps":["trace[644693920] 'agreement among raft nodes before linearized reading' (duration: 118.33418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:02:30.260593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.838193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T02:02:30.260735Z","caller":"traceutil/trace.go:171","msg":"trace[1730266861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23409; }","duration":"151.029357ms","start":"2026-05-06T02:02:30.109677Z","end":"2026-05-06T02:02:30.260706Z","steps":["trace[1730266861] 'agreement among raft nodes before linearized reading' (duration: 150.68884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:02:30.260748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.561105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T02:02:30.260861Z","caller":"traceutil/trace.go:171","msg":"trace[1782364756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23409; }","duration":"149.745338ms","start":"2026-05-06T02:02:30.111085Z","end":"2026-05-06T02:02:30.26083Z","steps":["trace[1782364756] 'agreement among raft nodes before linearized reading' (duration: 149.34963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:02:30.261274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:02:29.88376Z","time spent":"376.177042ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:05:51.88851Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22063} {"level":"info","ts":"2026-05-06T02:05:51.994505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22063,"took":"101.054643ms","hash":1453129928,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":43995136,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-05-06T02:05:51.994658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1453129928,"revision":22063,"compact-revision":19769} {"level":"warn","ts":"2026-05-06T02:06:01.785906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.972466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:01.786115Z","caller":"traceutil/trace.go:171","msg":"trace[1855747609] linearizableReadLoop","detail":"{readStateIndex:26418; appliedIndex:26417; }","duration":"256.66946ms","start":"2026-05-06T02:06:01.529427Z","end":"2026-05-06T02:06:01.786096Z","steps":["trace[1855747609] 'read index received' (duration: 114.372898ms)","trace[1855747609] 'applied index is now lower than readState.Index' (duration: 142.295272ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:01.786203Z","caller":"traceutil/trace.go:171","msg":"trace[1315698953] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"261.898311ms","start":"2026-05-06T02:06:01.524246Z","end":"2026-05-06T02:06:01.786144Z","steps":["trace[1315698953] 'process raft request' (duration: 119.590529ms)","trace[1315698953] 'compare' (duration: 141.831942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:01.786298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.867364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-05-06T02:06:01.786326Z","caller":"traceutil/trace.go:171","msg":"trace[1104819089] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25571; }","duration":"256.910365ms","start":"2026-05-06T02:06:01.529408Z","end":"2026-05-06T02:06:01.786318Z","steps":["trace[1104819089] 'agreement among raft nodes before linearized reading' (duration: 256.736851ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:01.791699Z","caller":"traceutil/trace.go:171","msg":"trace[188926220] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"139.213877ms","start":"2026-05-06T02:06:01.652455Z","end":"2026-05-06T02:06:01.791669Z","steps":["trace[188926220] 'process raft request' (duration: 139.016492ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:02.599532Z","caller":"traceutil/trace.go:171","msg":"trace[680200626] linearizableReadLoop","detail":"{readStateIndex:26424; appliedIndex:26423; }","duration":"109.743978ms","start":"2026-05-06T02:06:02.48976Z","end":"2026-05-06T02:06:02.599503Z","steps":["trace[680200626] 'read index received' (duration: 109.478903ms)","trace[680200626] 'applied index is now lower than readState.Index' (duration: 263.625µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:02.599664Z","caller":"traceutil/trace.go:171","msg":"trace[1585062909] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"157.991625ms","start":"2026-05-06T02:06:02.441622Z","end":"2026-05-06T02:06:02.599613Z","steps":["trace[1585062909] 'process raft request' (duration: 157.644828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:02.59992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.009237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-06T02:06:02.599973Z","caller":"traceutil/trace.go:171","msg":"trace[1222451950] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25577; }","duration":"105.097539ms","start":"2026-05-06T02:06:02.494862Z","end":"2026-05-06T02:06:02.599959Z","steps":["trace[1222451950] 'agreement among raft nodes before linearized reading' (duration: 104.823593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:02.6015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.70279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-06T02:06:02.601618Z","caller":"traceutil/trace.go:171","msg":"trace[1834585425] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25577; }","duration":"111.888814ms","start":"2026-05-06T02:06:02.489708Z","end":"2026-05-06T02:06:02.601597Z","steps":["trace[1834585425] 'agreement among raft nodes before linearized reading' (duration: 109.982973ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:02.779269Z","caller":"traceutil/trace.go:171","msg":"trace[1910417713] linearizableReadLoop","detail":"{readStateIndex:26425; appliedIndex:26424; }","duration":"115.44023ms","start":"2026-05-06T02:06:02.6638Z","end":"2026-05-06T02:06:02.77924Z","steps":["trace[1910417713] 'read index received' (duration: 87.30194ms)","trace[1910417713] 'applied index is now lower than readState.Index' (duration: 28.13697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:02.779579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.766007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-06T02:06:02.779614Z","caller":"traceutil/trace.go:171","msg":"trace[1102691199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25578; }","duration":"115.856599ms","start":"2026-05-06T02:06:02.663746Z","end":"2026-05-06T02:06:02.779603Z","steps":["trace[1102691199] 'agreement among raft nodes before linearized reading' (duration: 115.589113ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:02.779812Z","caller":"traceutil/trace.go:171","msg":"trace[848926726] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"167.823356ms","start":"2026-05-06T02:06:02.611949Z","end":"2026-05-06T02:06:02.779772Z","steps":["trace[848926726] 'process raft request' (duration: 139.211456ms)","trace[848926726] 'compare' (duration: 27.938755ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:02.927367Z","caller":"traceutil/trace.go:171","msg":"trace[1062718157] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"142.378774ms","start":"2026-05-06T02:06:02.784937Z","end":"2026-05-06T02:06:02.927316Z","steps":["trace[1062718157] 'process raft request' (duration: 131.501233ms)","trace[1062718157] 'compare' (duration: 10.502843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:07.056741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.398663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-06T02:06:07.056857Z","caller":"traceutil/trace.go:171","msg":"trace[1290802010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25614; }","duration":"110.606277ms","start":"2026-05-06T02:06:06.946226Z","end":"2026-05-06T02:06:07.056832Z","steps":["trace[1290802010] 'range keys from in-memory index tree' (duration: 110.115736ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:07.05687Z","caller":"traceutil/trace.go:171","msg":"trace[335732539] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"108.740997ms","start":"2026-05-06T02:06:06.948087Z","end":"2026-05-06T02:06:07.056828Z","steps":["trace[335732539] 'process raft request' (duration: 82.820914ms)","trace[335732539] 'compare' (duration: 25.714158ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:07.205034Z","caller":"traceutil/trace.go:171","msg":"trace[194652350] linearizableReadLoop","detail":"{readStateIndex:26464; appliedIndex:26463; }","duration":"131.288857ms","start":"2026-05-06T02:06:07.073693Z","end":"2026-05-06T02:06:07.204982Z","steps":["trace[194652350] 'read index received' (duration: 125.32633ms)","trace[194652350] 'applied index is now lower than readState.Index' (duration: 5.959647ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:07.205306Z","caller":"traceutil/trace.go:171","msg":"trace[976987918] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"143.290002ms","start":"2026-05-06T02:06:07.061917Z","end":"2026-05-06T02:06:07.205207Z","steps":["trace[976987918] 'process raft request' (duration: 137.178893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:07.205521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.795887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-06T02:06:07.205589Z","caller":"traceutil/trace.go:171","msg":"trace[1280846287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25616; }","duration":"131.92849ms","start":"2026-05-06T02:06:07.073642Z","end":"2026-05-06T02:06:07.20557Z","steps":["trace[1280846287] 'agreement among raft nodes before linearized reading' (duration: 131.598663ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:07.369869Z","caller":"traceutil/trace.go:171","msg":"trace[617235854] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"159.329245ms","start":"2026-05-06T02:06:07.210516Z","end":"2026-05-06T02:06:07.369846Z","steps":["trace[617235854] 'process raft request' (duration: 95.434543ms)","trace[617235854] 'compare' (duration: 63.442832ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:07.749314Z","caller":"traceutil/trace.go:171","msg":"trace[1245943483] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"134.336192ms","start":"2026-05-06T02:06:07.614956Z","end":"2026-05-06T02:06:07.749292Z","steps":["trace[1245943483] 'process raft request' (duration: 134.174128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:08.041186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.967078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:08.041503Z","caller":"traceutil/trace.go:171","msg":"trace[793259442] linearizableReadLoop","detail":"{readStateIndex:26469; appliedIndex:26466; }","duration":"214.830317ms","start":"2026-05-06T02:06:07.826635Z","end":"2026-05-06T02:06:08.041465Z","steps":["trace[793259442] 'read index received' (duration: 62.45042ms)","trace[793259442] 'applied index is now lower than readState.Index' (duration: 152.378307ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:08.041594Z","caller":"traceutil/trace.go:171","msg":"trace[1334766688] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"426.327374ms","start":"2026-05-06T02:06:07.615226Z","end":"2026-05-06T02:06:08.041553Z","steps":["trace[1334766688] 'process raft request' (duration: 273.895726ms)","trace[1334766688] 'compare' (duration: 151.682892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:08.041661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.023421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-06T02:06:08.041711Z","caller":"traceutil/trace.go:171","msg":"trace[1599841665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25621; }","duration":"215.106803ms","start":"2026-05-06T02:06:07.826594Z","end":"2026-05-06T02:06:08.0417Z","steps":["trace[1599841665] 'agreement among raft nodes before linearized reading' (duration: 214.952ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:08.041719Z","caller":"traceutil/trace.go:171","msg":"trace[1096951761] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"229.49594ms","start":"2026-05-06T02:06:07.812191Z","end":"2026-05-06T02:06:08.041687Z","steps":["trace[1096951761] 'process raft request' (duration: 229.113152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:08.041775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:07.615213Z","time spent":"426.466197ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:08.041972Z","caller":"traceutil/trace.go:171","msg":"trace[1151517728] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"220.668362ms","start":"2026-05-06T02:06:07.821257Z","end":"2026-05-06T02:06:08.041925Z","steps":["trace[1151517728] 'process raft request' (duration: 220.164511ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:08.200571Z","caller":"traceutil/trace.go:171","msg":"trace[1723605550] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"266.958349ms","start":"2026-05-06T02:06:07.933577Z","end":"2026-05-06T02:06:08.200535Z","steps":["trace[1723605550] 'process raft request' (duration: 266.683794ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:08.200922Z","caller":"traceutil/trace.go:171","msg":"trace[2003156458] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"262.627137ms","start":"2026-05-06T02:06:07.938265Z","end":"2026-05-06T02:06:08.200892Z","steps":["trace[2003156458] 'process raft request' (duration: 262.219748ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:08.200996Z","caller":"traceutil/trace.go:171","msg":"trace[731469915] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"199.261375ms","start":"2026-05-06T02:06:08.001712Z","end":"2026-05-06T02:06:08.200973Z","steps":["trace[731469915] 'process raft request' (duration: 199.207344ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:08.201112Z","caller":"traceutil/trace.go:171","msg":"trace[1224400387] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"251.168712ms","start":"2026-05-06T02:06:07.94993Z","end":"2026-05-06T02:06:08.201099Z","steps":["trace[1224400387] 'process raft request' (duration: 250.819415ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:08.201278Z","caller":"traceutil/trace.go:171","msg":"trace[1600307360] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"250.434197ms","start":"2026-05-06T02:06:07.950766Z","end":"2026-05-06T02:06:08.2012Z","steps":["trace[1600307360] 'process raft request' (duration: 250.127921ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:08.486532Z","caller":"traceutil/trace.go:171","msg":"trace[1705584977] linearizableReadLoop","detail":"{readStateIndex:26475; appliedIndex:26469; }","duration":"376.106245ms","start":"2026-05-06T02:06:08.110396Z","end":"2026-05-06T02:06:08.486502Z","steps":["trace[1705584977] 'read index received' (duration: 89.838806ms)","trace[1705584977] 'applied index is now lower than readState.Index' (duration: 286.266669ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:08.486927Z","caller":"traceutil/trace.go:171","msg":"trace[538163838] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"439.713309ms","start":"2026-05-06T02:06:08.047199Z","end":"2026-05-06T02:06:08.486912Z","steps":["trace[538163838] 'process raft request' (duration: 439.126407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:08.487035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:08.047172Z","time spent":"439.782241ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:08.487075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.239616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:08.487158Z","caller":"traceutil/trace.go:171","msg":"trace[1690212527] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25627; }","duration":"345.372349ms","start":"2026-05-06T02:06:08.141769Z","end":"2026-05-06T02:06:08.487141Z","steps":["trace[1690212527] 'agreement among raft nodes before linearized reading' (duration: 345.202215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:08.487182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.903965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-06T02:06:08.487201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:08.141759Z","time spent":"345.42922ms","remote":"127.0.0.1:53670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-05-06T02:06:08.487239Z","caller":"traceutil/trace.go:171","msg":"trace[1943861159] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25627; }","duration":"166.020367ms","start":"2026-05-06T02:06:08.321199Z","end":"2026-05-06T02:06:08.48722Z","steps":["trace[1943861159] 'agreement among raft nodes before linearized reading' (duration: 165.882525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:08.487271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.403318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:08.487345Z","caller":"traceutil/trace.go:171","msg":"trace[2065551790] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25627; }","duration":"252.505771ms","start":"2026-05-06T02:06:08.234821Z","end":"2026-05-06T02:06:08.487327Z","steps":["trace[2065551790] 'agreement among raft nodes before linearized reading' (duration: 252.289066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:08.487384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.994394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T02:06:08.487413Z","caller":"traceutil/trace.go:171","msg":"trace[843918318] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25627; }","duration":"377.059755ms","start":"2026-05-06T02:06:08.110343Z","end":"2026-05-06T02:06:08.487403Z","steps":["trace[843918318] 'agreement among raft nodes before linearized reading' (duration: 376.923862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:08.487432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:08.110321Z","time spent":"377.105736ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":74,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-06T02:06:08.912882Z","caller":"traceutil/trace.go:171","msg":"trace[1745516526] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"107.905419ms","start":"2026-05-06T02:06:08.804935Z","end":"2026-05-06T02:06:08.912841Z","steps":["trace[1745516526] 'process raft request' (duration: 98.158771ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:09.212448Z","caller":"traceutil/trace.go:171","msg":"trace[71879355] linearizableReadLoop","detail":"{readStateIndex:26478; appliedIndex:26477; }","duration":"122.266076ms","start":"2026-05-06T02:06:09.090154Z","end":"2026-05-06T02:06:09.21242Z","steps":["trace[71879355] 'read index received' (duration: 122.015651ms)","trace[71879355] 'applied index is now lower than readState.Index' (duration: 249.415µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:09.212573Z","caller":"traceutil/trace.go:171","msg":"trace[1601350932] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"148.956984ms","start":"2026-05-06T02:06:09.063572Z","end":"2026-05-06T02:06:09.212529Z","steps":["trace[1601350932] 'process raft request' (duration: 148.670458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:09.212785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.563582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:09.212885Z","caller":"traceutil/trace.go:171","msg":"trace[1167176084] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25630; }","duration":"122.765666ms","start":"2026-05-06T02:06:09.090095Z","end":"2026-05-06T02:06:09.212861Z","steps":["trace[1167176084] 'agreement among raft nodes before linearized reading' (duration: 122.556132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:09.470816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.381326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T02:06:09.470985Z","caller":"traceutil/trace.go:171","msg":"trace[1193298838] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25631; }","duration":"105.645111ms","start":"2026-05-06T02:06:09.365303Z","end":"2026-05-06T02:06:09.470948Z","steps":["trace[1193298838] 'count revisions from in-memory index tree' (duration: 105.263073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:09.471224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.685956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:09.47131Z","caller":"traceutil/trace.go:171","msg":"trace[1573190726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25631; }","duration":"147.81035ms","start":"2026-05-06T02:06:09.323479Z","end":"2026-05-06T02:06:09.471289Z","steps":["trace[1573190726] 'range keys from in-memory index tree' (duration: 147.541883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:09.471379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.763951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:09.471495Z","caller":"traceutil/trace.go:171","msg":"trace[116630278] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25631; }","duration":"111.947315ms","start":"2026-05-06T02:06:09.359519Z","end":"2026-05-06T02:06:09.471466Z","steps":["trace[116630278] 'count revisions from in-memory index tree' (duration: 111.594358ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:09.738562Z","caller":"traceutil/trace.go:171","msg":"trace[62529961] linearizableReadLoop","detail":"{readStateIndex:26480; appliedIndex:26479; }","duration":"109.550474ms","start":"2026-05-06T02:06:09.62896Z","end":"2026-05-06T02:06:09.73851Z","steps":["trace[62529961] 'read index received' (duration: 109.35999ms)","trace[62529961] 'applied index is now lower than readState.Index' (duration: 188.984µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:09.738652Z","caller":"traceutil/trace.go:171","msg":"trace[1540043298] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"259.99716ms","start":"2026-05-06T02:06:09.478615Z","end":"2026-05-06T02:06:09.738613Z","steps":["trace[1540043298] 'process raft request' (duration: 259.710944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:09.738752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.764039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T02:06:09.738798Z","caller":"traceutil/trace.go:171","msg":"trace[1020926174] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25632; }","duration":"109.892392ms","start":"2026-05-06T02:06:09.62889Z","end":"2026-05-06T02:06:09.738783Z","steps":["trace[1020926174] 'agreement among raft nodes before linearized reading' (duration: 109.758079ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:10.24171Z","caller":"traceutil/trace.go:171","msg":"trace[139711030] linearizableReadLoop","detail":"{readStateIndex:26484; appliedIndex:26481; }","duration":"188.021206ms","start":"2026-05-06T02:06:10.053659Z","end":"2026-05-06T02:06:10.24168Z","steps":["trace[139711030] 'read index received' (duration: 67.455647ms)","trace[139711030] 'applied index is now lower than readState.Index' (duration: 120.563929ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:10.241783Z","caller":"traceutil/trace.go:171","msg":"trace[821837879] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"188.624708ms","start":"2026-05-06T02:06:10.053125Z","end":"2026-05-06T02:06:10.24175Z","steps":["trace[821837879] 'process raft request' (duration: 188.501126ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:10.241813Z","caller":"traceutil/trace.go:171","msg":"trace[467083230] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"190.663103ms","start":"2026-05-06T02:06:10.051066Z","end":"2026-05-06T02:06:10.241729Z","steps":["trace[467083230] 'process raft request' (duration: 190.361616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.24203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.334902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-06T02:06:10.242072Z","caller":"traceutil/trace.go:171","msg":"trace[786474113] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25636; }","duration":"188.458255ms","start":"2026-05-06T02:06:10.053602Z","end":"2026-05-06T02:06:10.24206Z","steps":["trace[786474113] 'agreement among raft nodes before linearized reading' (duration: 188.223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.584529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.641036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:10.584922Z","caller":"traceutil/trace.go:171","msg":"trace[809445280] transaction","detail":"{read_only:false; response_revision:25637; number_of_response:1; }","duration":"375.401279ms","start":"2026-05-06T02:06:10.209487Z","end":"2026-05-06T02:06:10.584888Z","steps":["trace[809445280] 'process raft request' (duration: 189.278543ms)","trace[809445280] 'compare' (duration: 185.423832ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:10.585114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.209453Z","time spent":"375.554932ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:10.586099Z","caller":"traceutil/trace.go:171","msg":"trace[555051302] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"376.464141ms","start":"2026-05-06T02:06:10.209609Z","end":"2026-05-06T02:06:10.586073Z","steps":["trace[555051302] 'process raft request' (duration: 375.085042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.586218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.209601Z","time spent":"376.565183ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:10.586689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.089702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:10.586729Z","caller":"traceutil/trace.go:171","msg":"trace[1233448058] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25639; }","duration":"505.207605ms","start":"2026-05-06T02:06:10.081513Z","end":"2026-05-06T02:06:10.586721Z","steps":["trace[1233448058] 'agreement among raft nodes before linearized reading' (duration: 504.926579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.586753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.081487Z","time spent":"505.258635ms","remote":"127.0.0.1:52156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-05-06T02:06:10.58638Z","caller":"traceutil/trace.go:171","msg":"trace[1830186343] linearizableReadLoop","detail":"{readStateIndex:26487; appliedIndex:26484; }","duration":"344.567432ms","start":"2026-05-06T02:06:10.241791Z","end":"2026-05-06T02:06:10.586358Z","steps":["trace[1830186343] 'read index received' (duration: 156.992115ms)","trace[1830186343] 'applied index is now lower than readState.Index' (duration: 187.574647ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:10.58694Z","caller":"traceutil/trace.go:171","msg":"trace[1172542606] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"337.862139ms","start":"2026-05-06T02:06:10.249071Z","end":"2026-05-06T02:06:10.586933Z","steps":["trace[1172542606] 'process raft request' (duration: 337.203445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.58704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.249043Z","time spent":"337.915481ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:10.587272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.00212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418399"} {"level":"info","ts":"2026-05-06T02:06:10.587321Z","caller":"traceutil/trace.go:171","msg":"trace[904082008] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25640; }","duration":"313.096622ms","start":"2026-05-06T02:06:10.274209Z","end":"2026-05-06T02:06:10.587306Z","steps":["trace[904082008] 'agreement among raft nodes before linearized reading' (duration: 312.822396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.58736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.274194Z","time spent":"313.158883ms","remote":"127.0.0.1:43294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418422,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-05-06T02:06:10.58736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.161137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:10.587445Z","caller":"traceutil/trace.go:171","msg":"trace[818577828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25640; }","duration":"263.30683ms","start":"2026-05-06T02:06:10.32412Z","end":"2026-05-06T02:06:10.587427Z","steps":["trace[818577828] 'agreement among raft nodes before linearized reading' (duration: 263.119656ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:10.58725Z","caller":"traceutil/trace.go:171","msg":"trace[1415731023] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"377.30838ms","start":"2026-05-06T02:06:10.20966Z","end":"2026-05-06T02:06:10.586968Z","steps":["trace[1415731023] 'process raft request' (duration: 376.357839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.58771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.406517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T02:06:10.587825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.209649Z","time spent":"377.964623ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:10.587871Z","caller":"traceutil/trace.go:171","msg":"trace[1766875436] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25640; }","duration":"282.621422ms","start":"2026-05-06T02:06:10.305176Z","end":"2026-05-06T02:06:10.587797Z","steps":["trace[1766875436] 'agreement among raft nodes before linearized reading' (duration: 282.278305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.588634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.551751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:10.58871Z","caller":"traceutil/trace.go:171","msg":"trace[1317404367] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25640; }","duration":"221.663983ms","start":"2026-05-06T02:06:10.36703Z","end":"2026-05-06T02:06:10.588694Z","steps":["trace[1317404367] 'agreement among raft nodes before linearized reading' (duration: 220.944128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:10.915321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.509681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:10.915488Z","caller":"traceutil/trace.go:171","msg":"trace[1301590472] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"323.051044ms","start":"2026-05-06T02:06:10.592411Z","end":"2026-05-06T02:06:10.915462Z","steps":["trace[1301590472] 'process raft request' (duration: 139.308938ms)","trace[1301590472] 'compare' (duration: 183.367518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:10.915552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.592393Z","time spent":"323.131066ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:11.220231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.800595ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-06T02:06:11.220767Z","caller":"traceutil/trace.go:171","msg":"trace[7181888] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"580.152622ms","start":"2026-05-06T02:06:10.640533Z","end":"2026-05-06T02:06:11.220685Z","steps":["trace[7181888] 'process raft request' (duration: 579.823135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:11.220952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.640504Z","time spent":"580.348256ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:11.235483Z","caller":"traceutil/trace.go:171","msg":"trace[2105464958] transaction","detail":"{read_only:false; response_revision:25643; number_of_response:1; }","duration":"542.410718ms","start":"2026-05-06T02:06:10.693032Z","end":"2026-05-06T02:06:11.235443Z","steps":["trace[2105464958] 'process raft request' (duration: 527.635243ms)","trace[2105464958] 'compare' (duration: 14.502299ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:11.235565Z","caller":"traceutil/trace.go:171","msg":"trace[1268758441] linearizableReadLoop","detail":"{readStateIndex:26491; appliedIndex:26488; }","duration":"579.302924ms","start":"2026-05-06T02:06:10.656226Z","end":"2026-05-06T02:06:11.235529Z","steps":["trace[1268758441] 'read index received' (duration: 75.524409ms)","trace[1268758441] 'applied index is now lower than readState.Index' (duration: 503.773414ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:11.236088Z","caller":"traceutil/trace.go:171","msg":"trace[612382439] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"316.671168ms","start":"2026-05-06T02:06:10.919383Z","end":"2026-05-06T02:06:11.236054Z","steps":["trace[612382439] 'process raft request' (duration: 315.997783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:11.23625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.919349Z","time spent":"316.79537ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:11.236273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.875892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T02:06:11.236324Z","caller":"traceutil/trace.go:171","msg":"trace[1025449448] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"226.986386ms","start":"2026-05-06T02:06:11.00929Z","end":"2026-05-06T02:06:11.236276Z","steps":["trace[1025449448] 'process raft request' (duration: 226.342993ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:11.236375Z","caller":"traceutil/trace.go:171","msg":"trace[843740985] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25646; }","duration":"294.049366ms","start":"2026-05-06T02:06:10.942303Z","end":"2026-05-06T02:06:11.236352Z","steps":["trace[843740985] 'agreement among raft nodes before linearized reading' (duration: 293.862472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:11.236082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.824285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-06T02:06:11.236641Z","caller":"traceutil/trace.go:171","msg":"trace[883344708] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25644; }","duration":"580.412368ms","start":"2026-05-06T02:06:10.656212Z","end":"2026-05-06T02:06:11.236624Z","steps":["trace[883344708] 'agreement among raft nodes before linearized reading' (duration: 579.638771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:11.236583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.693007Z","time spent":"542.733015ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:11.236684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:10.656148Z","time spent":"580.52352ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-06T02:06:11.567161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.573662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-06T02:06:11.567271Z","caller":"traceutil/trace.go:171","msg":"trace[1969116578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25647; }","duration":"222.713075ms","start":"2026-05-06T02:06:11.344531Z","end":"2026-05-06T02:06:11.567244Z","steps":["trace[1969116578] 'range keys from in-memory index tree' (duration: 222.315677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:11.567636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.978962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-06T02:06:11.567732Z","caller":"traceutil/trace.go:171","msg":"trace[719582906] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25647; }","duration":"139.131915ms","start":"2026-05-06T02:06:11.428577Z","end":"2026-05-06T02:06:11.567709Z","steps":["trace[719582906] 'range keys from in-memory index tree' (duration: 138.782208ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:11.736445Z","caller":"traceutil/trace.go:171","msg":"trace[727508878] linearizableReadLoop","detail":"{readStateIndex:26497; appliedIndex:26496; }","duration":"142.718191ms","start":"2026-05-06T02:06:11.593694Z","end":"2026-05-06T02:06:11.736412Z","steps":["trace[727508878] 'read index received' (duration: 142.431044ms)","trace[727508878] 'applied index is now lower than readState.Index' (duration: 285.386µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:11.73658Z","caller":"traceutil/trace.go:171","msg":"trace[1762591094] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"162.666226ms","start":"2026-05-06T02:06:11.573872Z","end":"2026-05-06T02:06:11.736539Z","steps":["trace[1762591094] 'process raft request' (duration: 162.295048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:11.736718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.993507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-05-06T02:06:11.736762Z","caller":"traceutil/trace.go:171","msg":"trace[1161610167] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25648; }","duration":"143.11356ms","start":"2026-05-06T02:06:11.593636Z","end":"2026-05-06T02:06:11.736749Z","steps":["trace[1161610167] 'agreement among raft nodes before linearized reading' (duration: 142.897085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:12.088612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.266495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:12.088764Z","caller":"traceutil/trace.go:171","msg":"trace[33818157] linearizableReadLoop","detail":"{readStateIndex:26498; appliedIndex:26497; }","duration":"325.451665ms","start":"2026-05-06T02:06:11.76329Z","end":"2026-05-06T02:06:12.088742Z","steps":["trace[33818157] 'read index received' (duration: 125.821761ms)","trace[33818157] 'applied index is now lower than readState.Index' (duration: 199.628294ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:12.088866Z","caller":"traceutil/trace.go:171","msg":"trace[1069203099] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"340.760201ms","start":"2026-05-06T02:06:11.748067Z","end":"2026-05-06T02:06:12.088827Z","steps":["trace[1069203099] 'process raft request' (duration: 141.136607ms)","trace[1069203099] 'compare' (duration: 198.834926ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:12.08894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.502765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T02:06:12.088969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.681981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T02:06:12.089012Z","caller":"traceutil/trace.go:171","msg":"trace[926373788] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25649; }","duration":"156.671818ms","start":"2026-05-06T02:06:11.932322Z","end":"2026-05-06T02:06:12.088994Z","steps":["trace[926373788] 'agreement among raft nodes before linearized reading' (duration: 156.553126ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:12.089019Z","caller":"traceutil/trace.go:171","msg":"trace[427136029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25649; }","duration":"325.776443ms","start":"2026-05-06T02:06:11.763229Z","end":"2026-05-06T02:06:12.089005Z","steps":["trace[427136029] 'agreement among raft nodes before linearized reading' (duration: 325.599959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:12.089031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:11.748054Z","time spent":"340.885534ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:12.089055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:11.763207Z","time spent":"325.839313ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T02:06:12.262604Z","caller":"traceutil/trace.go:171","msg":"trace[1113277193] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"169.848259ms","start":"2026-05-06T02:06:12.092728Z","end":"2026-05-06T02:06:12.262576Z","steps":["trace[1113277193] 'process raft request' (duration: 138.516861ms)","trace[1113277193] 'compare' (duration: 31.116154ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:12.26303Z","caller":"traceutil/trace.go:171","msg":"trace[332883580] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"134.280601ms","start":"2026-05-06T02:06:12.128719Z","end":"2026-05-06T02:06:12.262999Z","steps":["trace[332883580] 'process raft request' (duration: 133.813161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:12.558145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.653163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:12.558446Z","caller":"traceutil/trace.go:171","msg":"trace[1070366147] linearizableReadLoop","detail":"{readStateIndex:26502; appliedIndex:26500; }","duration":"236.10938ms","start":"2026-05-06T02:06:12.322311Z","end":"2026-05-06T02:06:12.558421Z","steps":["trace[1070366147] 'read index received' (duration: 60.064069ms)","trace[1070366147] 'applied index is now lower than readState.Index' (duration: 176.044111ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:12.558625Z","caller":"traceutil/trace.go:171","msg":"trace[368747854] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"308.458532ms","start":"2026-05-06T02:06:12.250114Z","end":"2026-05-06T02:06:12.558573Z","steps":["trace[368747854] 'process raft request' (duration: 132.249728ms)","trace[368747854] 'compare' (duration: 175.238634ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:12.558673Z","caller":"traceutil/trace.go:171","msg":"trace[585913796] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"291.004831ms","start":"2026-05-06T02:06:12.267623Z","end":"2026-05-06T02:06:12.558628Z","steps":["trace[585913796] 'process raft request' (duration: 290.731485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:12.558783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.437577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T02:06:12.558826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:12.250089Z","time spent":"308.615906ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:12.558843Z","caller":"traceutil/trace.go:171","msg":"trace[1503877240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25653; }","duration":"236.55606ms","start":"2026-05-06T02:06:12.32227Z","end":"2026-05-06T02:06:12.558826Z","steps":["trace[1503877240] 'agreement among raft nodes before linearized reading' (duration: 236.322515ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:12.91032Z","caller":"traceutil/trace.go:171","msg":"trace[741346235] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"319.321004ms","start":"2026-05-06T02:06:12.59096Z","end":"2026-05-06T02:06:12.910281Z","steps":["trace[741346235] 'process raft request' (duration: 319.072669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:12.910551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:12.590931Z","time spent":"319.483288ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:13.027292Z","caller":"traceutil/trace.go:171","msg":"trace[918341796] linearizableReadLoop","detail":"{readStateIndex:26505; appliedIndex:26502; }","duration":"291.539032ms","start":"2026-05-06T02:06:12.735724Z","end":"2026-05-06T02:06:13.027263Z","steps":["trace[918341796] 'read index received' (duration: 174.362895ms)","trace[918341796] 'applied index is now lower than readState.Index' (duration: 117.174727ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:13.02735Z","caller":"traceutil/trace.go:171","msg":"trace[962473390] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"434.521018ms","start":"2026-05-06T02:06:12.592765Z","end":"2026-05-06T02:06:13.027286Z","steps":["trace[962473390] 'process raft request' (duration: 431.536675ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:13.027396Z","caller":"traceutil/trace.go:171","msg":"trace[1671728915] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"434.372846ms","start":"2026-05-06T02:06:12.592977Z","end":"2026-05-06T02:06:13.02735Z","steps":["trace[1671728915] 'process raft request' (duration: 434.229083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:13.027541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.857113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1021"} {"level":"info","ts":"2026-05-06T02:06:13.027591Z","caller":"traceutil/trace.go:171","msg":"trace[864807633] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:25656; }","duration":"218.973316ms","start":"2026-05-06T02:06:12.808605Z","end":"2026-05-06T02:06:13.027579Z","steps":["trace[864807633] 'agreement among raft nodes before linearized reading' (duration: 218.856243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:13.027658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.223845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-06T02:06:13.027658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:12.592968Z","time spent":"434.556969ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:13.027658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.870579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:13.027706Z","caller":"traceutil/trace.go:171","msg":"trace[1799176276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25656; }","duration":"107.274786ms","start":"2026-05-06T02:06:12.920418Z","end":"2026-05-06T02:06:13.027693Z","steps":["trace[1799176276] 'agreement among raft nodes before linearized reading' (duration: 107.088222ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:13.027835Z","caller":"traceutil/trace.go:171","msg":"trace[757216623] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25656; }","duration":"292.104193ms","start":"2026-05-06T02:06:12.73566Z","end":"2026-05-06T02:06:13.027764Z","steps":["trace[757216623] 'agreement among raft nodes before linearized reading' (duration: 291.798627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:13.02793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:12.592741Z","time spent":"434.714513ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:13.512443Z","caller":"traceutil/trace.go:171","msg":"trace[714117662] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"270.204288ms","start":"2026-05-06T02:06:13.242209Z","end":"2026-05-06T02:06:13.512413Z","steps":["trace[714117662] 'process raft request' (duration: 270.002114ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:13.829241Z","caller":"traceutil/trace.go:171","msg":"trace[534588557] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"585.094247ms","start":"2026-05-06T02:06:13.24411Z","end":"2026-05-06T02:06:13.829205Z","steps":["trace[534588557] 'process raft request' (duration: 487.943237ms)","trace[534588557] 'compare' (duration: 96.869494ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:13.829262Z","caller":"traceutil/trace.go:171","msg":"trace[1372337803] linearizableReadLoop","detail":"{readStateIndex:26510; appliedIndex:26507; }","duration":"574.867239ms","start":"2026-05-06T02:06:13.254369Z","end":"2026-05-06T02:06:13.829236Z","steps":["trace[1372337803] 'read index received' (duration: 257.945096ms)","trace[1372337803] 'applied index is now lower than readState.Index' (duration: 316.920633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:13.829435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:13.244081Z","time spent":"585.244571ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:13.829501Z","caller":"traceutil/trace.go:171","msg":"trace[289353900] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"585.071117ms","start":"2026-05-06T02:06:13.244416Z","end":"2026-05-06T02:06:13.829487Z","steps":["trace[289353900] 'process raft request' (duration: 584.692349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:13.829649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:13.244409Z","time spent":"585.137858ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:13.829846Z","caller":"traceutil/trace.go:171","msg":"trace[1920926852] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"478.591377ms","start":"2026-05-06T02:06:13.351243Z","end":"2026-05-06T02:06:13.829835Z","steps":["trace[1920926852] 'process raft request' (duration: 477.937674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:13.829907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:13.351213Z","time spent":"478.661679ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:13.829931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.456833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T02:06:13.830019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.646185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-06T02:06:13.830036Z","caller":"traceutil/trace.go:171","msg":"trace[126402048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25662; }","duration":"507.596195ms","start":"2026-05-06T02:06:13.32242Z","end":"2026-05-06T02:06:13.830016Z","steps":["trace[126402048] 'agreement among raft nodes before linearized reading' (duration: 507.432112ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:13.830062Z","caller":"traceutil/trace.go:171","msg":"trace[242056626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25662; }","duration":"575.729967ms","start":"2026-05-06T02:06:13.254321Z","end":"2026-05-06T02:06:13.830051Z","steps":["trace[242056626] 'agreement among raft nodes before linearized reading' (duration: 575.566874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:13.830085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:13.322408Z","time spent":"507.665977ms","remote":"127.0.0.1:53604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T02:06:13.830099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:13.254307Z","time spent":"575.780558ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T02:06:14.070176Z","caller":"traceutil/trace.go:171","msg":"trace[338173377] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"233.985056ms","start":"2026-05-06T02:06:13.836153Z","end":"2026-05-06T02:06:14.070138Z","steps":["trace[338173377] 'process raft request' (duration: 139.359839ms)","trace[338173377] 'compare' (duration: 94.215548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:14.369615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.838559ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:14.369789Z","caller":"traceutil/trace.go:171","msg":"trace[520165122] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25663; }","duration":"154.071364ms","start":"2026-05-06T02:06:14.215693Z","end":"2026-05-06T02:06:14.369765Z","steps":["trace[520165122] 'range keys from in-memory index tree' (duration: 153.772396ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:14.371009Z","caller":"traceutil/trace.go:171","msg":"trace[1594285872] linearizableReadLoop","detail":"{readStateIndex:26513; appliedIndex:26512; }","duration":"103.425984ms","start":"2026-05-06T02:06:14.267556Z","end":"2026-05-06T02:06:14.370982Z","steps":["trace[1594285872] 'read index received' (duration: 103.318872ms)","trace[1594285872] 'applied index is now lower than readState.Index' (duration: 105.342µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:14.371102Z","caller":"traceutil/trace.go:171","msg":"trace[704873568] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"273.042987ms","start":"2026-05-06T02:06:14.098035Z","end":"2026-05-06T02:06:14.371078Z","steps":["trace[704873568] 'process raft request' (duration: 272.780331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:14.371402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.837752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T02:06:14.371449Z","caller":"traceutil/trace.go:171","msg":"trace[1187144196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25664; }","duration":"103.945424ms","start":"2026-05-06T02:06:14.267491Z","end":"2026-05-06T02:06:14.371436Z","steps":["trace[1187144196] 'agreement among raft nodes before linearized reading' (duration: 103.658838ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:14.371848Z","caller":"traceutil/trace.go:171","msg":"trace[76811684] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"101.967403ms","start":"2026-05-06T02:06:14.269866Z","end":"2026-05-06T02:06:14.371833Z","steps":["trace[76811684] 'process raft request' (duration: 101.789339ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:19.058438Z","caller":"traceutil/trace.go:171","msg":"trace[1079048222] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"129.705635ms","start":"2026-05-06T02:06:18.928713Z","end":"2026-05-06T02:06:19.058419Z","steps":["trace[1079048222] 'process raft request' (duration: 129.583752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:19.344691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.745425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:19.344805Z","caller":"traceutil/trace.go:171","msg":"trace[953073474] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25700; }","duration":"267.907349ms","start":"2026-05-06T02:06:19.076868Z","end":"2026-05-06T02:06:19.344775Z","steps":["trace[953073474] 'range keys from in-memory index tree' (duration: 267.644014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:19.344862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.041161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-06T02:06:19.345007Z","caller":"traceutil/trace.go:171","msg":"trace[298735164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25700; }","duration":"260.253316ms","start":"2026-05-06T02:06:19.084706Z","end":"2026-05-06T02:06:19.344959Z","steps":["trace[298735164] 'range keys from in-memory index tree' (duration: 259.811147ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:19.65805Z","caller":"traceutil/trace.go:171","msg":"trace[193778265] linearizableReadLoop","detail":"{readStateIndex:26551; appliedIndex:26550; }","duration":"490.01129ms","start":"2026-05-06T02:06:19.168002Z","end":"2026-05-06T02:06:19.658013Z","steps":["trace[193778265] 'read index received' (duration: 489.846456ms)","trace[193778265] 'applied index is now lower than readState.Index' (duration: 162.994µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:19.658101Z","caller":"traceutil/trace.go:171","msg":"trace[305103942] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"562.802792ms","start":"2026-05-06T02:06:19.095258Z","end":"2026-05-06T02:06:19.658061Z","steps":["trace[305103942] 'process raft request' (duration: 562.520006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:19.658289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.237126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T02:06:19.65831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:19.095237Z","time spent":"562.961245ms","remote":"127.0.0.1:37428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7516,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:19.658342Z","caller":"traceutil/trace.go:171","msg":"trace[586062838] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25701; }","duration":"490.351677ms","start":"2026-05-06T02:06:19.167971Z","end":"2026-05-06T02:06:19.658323Z","steps":["trace[586062838] 'agreement among raft nodes before linearized reading' (duration: 490.218295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:19.658387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:19.167959Z","time spent":"490.416359ms","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"info","ts":"2026-05-06T02:06:19.679096Z","caller":"traceutil/trace.go:171","msg":"trace[29917232] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"144.057019ms","start":"2026-05-06T02:06:19.534999Z","end":"2026-05-06T02:06:19.679056Z","steps":["trace[29917232] 'process raft request' (duration: 143.967467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:19.679167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.724891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:19.679264Z","caller":"traceutil/trace.go:171","msg":"trace[1011493450] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25703; }","duration":"311.862654ms","start":"2026-05-06T02:06:19.367376Z","end":"2026-05-06T02:06:19.679238Z","steps":["trace[1011493450] 'agreement among raft nodes before linearized reading' (duration: 311.6841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:19.679322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:19.367364Z","time spent":"311.945556ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"info","ts":"2026-05-06T02:06:19.679319Z","caller":"traceutil/trace.go:171","msg":"trace[801488680] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"329.033121ms","start":"2026-05-06T02:06:19.350244Z","end":"2026-05-06T02:06:19.679277Z","steps":["trace[801488680] 'process raft request' (duration: 328.575891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:19.67963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:19.350212Z","time spent":"329.276775ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:19.679329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.984119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:19.679758Z","caller":"traceutil/trace.go:171","msg":"trace[271240723] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25703; }","duration":"328.478299ms","start":"2026-05-06T02:06:19.351242Z","end":"2026-05-06T02:06:19.679721Z","steps":["trace[271240723] 'agreement among raft nodes before linearized reading' (duration: 327.947908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:19.679856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:19.351224Z","time spent":"328.609341ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-05-06T02:06:20.09762Z","caller":"traceutil/trace.go:171","msg":"trace[1124083182] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"234.429436ms","start":"2026-05-06T02:06:19.863161Z","end":"2026-05-06T02:06:20.09759Z","steps":["trace[1124083182] 'process raft request' (duration: 234.159479ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:20.098157Z","caller":"traceutil/trace.go:171","msg":"trace[787104134] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"234.239291ms","start":"2026-05-06T02:06:19.863861Z","end":"2026-05-06T02:06:20.0981Z","steps":["trace[787104134] 'process raft request' (duration: 234.056387ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:26.285882Z","caller":"traceutil/trace.go:171","msg":"trace[1843232243] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"166.980289ms","start":"2026-05-06T02:06:26.118866Z","end":"2026-05-06T02:06:26.285846Z","steps":["trace[1843232243] 'process raft request' (duration: 166.800794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:26.511374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.580408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:26.511604Z","caller":"traceutil/trace.go:171","msg":"trace[79447341] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"389.883017ms","start":"2026-05-06T02:06:26.121674Z","end":"2026-05-06T02:06:26.511557Z","steps":["trace[79447341] 'process raft request' (duration: 269.974712ms)","trace[79447341] 'compare' (duration: 119.228811ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:26.511542Z","caller":"traceutil/trace.go:171","msg":"trace[1511904735] linearizableReadLoop","detail":"{readStateIndex:26662; appliedIndex:26660; }","duration":"387.496777ms","start":"2026-05-06T02:06:26.124023Z","end":"2026-05-06T02:06:26.51152Z","steps":["trace[1511904735] 'read index received' (duration: 161.655474ms)","trace[1511904735] 'applied index is now lower than readState.Index' (duration: 225.839043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:26.5118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:26.121646Z","time spent":"390.050031ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:06:26.512016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.978266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-06T02:06:26.512105Z","caller":"traceutil/trace.go:171","msg":"trace[710072345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25810; }","duration":"388.116759ms","start":"2026-05-06T02:06:26.123968Z","end":"2026-05-06T02:06:26.512085Z","steps":["trace[710072345] 'agreement among raft nodes before linearized reading' (duration: 387.846784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:26.512148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:26.123951Z","time spent":"388.184241ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T02:06:26.537574Z","caller":"traceutil/trace.go:171","msg":"trace[1195434068] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"109.910952ms","start":"2026-05-06T02:06:26.427637Z","end":"2026-05-06T02:06:26.537548Z","steps":["trace[1195434068] 'process raft request' (duration: 109.551114ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:26.75226Z","caller":"traceutil/trace.go:171","msg":"trace[578238411] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"235.289083ms","start":"2026-05-06T02:06:26.516931Z","end":"2026-05-06T02:06:26.75222Z","steps":["trace[578238411] 'process raft request' (duration: 135.151299ms)","trace[578238411] 'compare' (duration: 99.664205ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:26.752258Z","caller":"traceutil/trace.go:171","msg":"trace[341746979] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"209.78664ms","start":"2026-05-06T02:06:26.542432Z","end":"2026-05-06T02:06:26.752218Z","steps":["trace[341746979] 'process raft request' (duration: 209.611977ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:26.752477Z","caller":"traceutil/trace.go:171","msg":"trace[1889030917] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"209.475634ms","start":"2026-05-06T02:06:26.54299Z","end":"2026-05-06T02:06:26.752465Z","steps":["trace[1889030917] 'process raft request' (duration: 209.174167ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:26.752453Z","caller":"traceutil/trace.go:171","msg":"trace[1067331919] linearizableReadLoop","detail":"{readStateIndex:26667; appliedIndex:26663; }","duration":"134.68562ms","start":"2026-05-06T02:06:26.617733Z","end":"2026-05-06T02:06:26.752419Z","steps":["trace[1067331919] 'read index received' (duration: 34.365292ms)","trace[1067331919] 'applied index is now lower than readState.Index' (duration: 100.319028ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:26.75263Z","caller":"traceutil/trace.go:171","msg":"trace[1322753723] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"147.312968ms","start":"2026-05-06T02:06:26.605263Z","end":"2026-05-06T02:06:26.752576Z","steps":["trace[1322753723] 'process raft request' (duration: 147.033512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:26.752776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.024007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-06T02:06:26.752847Z","caller":"traceutil/trace.go:171","msg":"trace[1595185189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25815; }","duration":"135.17578ms","start":"2026-05-06T02:06:26.617655Z","end":"2026-05-06T02:06:26.75283Z","steps":["trace[1595185189] 'agreement among raft nodes before linearized reading' (duration: 134.959236ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:27.059476Z","caller":"traceutil/trace.go:171","msg":"trace[547718277] linearizableReadLoop","detail":"{readStateIndex:26669; appliedIndex:26667; }","duration":"293.433703ms","start":"2026-05-06T02:06:26.766013Z","end":"2026-05-06T02:06:27.059446Z","steps":["trace[547718277] 'read index received' (duration: 44.118241ms)","trace[547718277] 'applied index is now lower than readState.Index' (duration: 249.314362ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:27.059566Z","caller":"traceutil/trace.go:171","msg":"trace[1165302147] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"300.250248ms","start":"2026-05-06T02:06:26.759273Z","end":"2026-05-06T02:06:27.059524Z","steps":["trace[1165302147] 'process raft request' (duration: 299.955802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:27.059771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.739769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-05-06T02:06:27.059769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:06:26.759248Z","time spent":"300.417711ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T02:06:27.059821Z","caller":"traceutil/trace.go:171","msg":"trace[1837704395] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25817; }","duration":"293.865892ms","start":"2026-05-06T02:06:26.765942Z","end":"2026-05-06T02:06:27.059808Z","steps":["trace[1837704395] 'agreement among raft nodes before linearized reading' (duration: 293.649827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:27.059879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.334795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-339fa757-43d0-472f-9f78-a6b7f8afabce\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-06T02:06:27.059955Z","caller":"traceutil/trace.go:171","msg":"trace[1866380952] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-339fa757-43d0-472f-9f78-a6b7f8afabce; range_end:; response_count:1; response_revision:25817; }","duration":"166.483698ms","start":"2026-05-06T02:06:26.893455Z","end":"2026-05-06T02:06:27.059939Z","steps":["trace[1866380952] 'agreement among raft nodes before linearized reading' (duration: 166.180312ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:27.227877Z","caller":"traceutil/trace.go:171","msg":"trace[968855941] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"158.248552ms","start":"2026-05-06T02:06:27.069603Z","end":"2026-05-06T02:06:27.227852Z","steps":["trace[968855941] 'process raft request' (duration: 158.078109ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:27.240422Z","caller":"traceutil/trace.go:171","msg":"trace[831159870] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"153.557603ms","start":"2026-05-06T02:06:27.086807Z","end":"2026-05-06T02:06:27.240364Z","steps":["trace[831159870] 'process raft request' (duration: 153.193105ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:27.240666Z","caller":"traceutil/trace.go:171","msg":"trace[86759578] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"147.616814ms","start":"2026-05-06T02:06:27.093018Z","end":"2026-05-06T02:06:27.240634Z","steps":["trace[86759578] 'process raft request' (duration: 147.140774ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:33.965667Z","caller":"traceutil/trace.go:171","msg":"trace[2134698156] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"143.843374ms","start":"2026-05-06T02:06:33.821792Z","end":"2026-05-06T02:06:33.965636Z","steps":["trace[2134698156] 'process raft request' (duration: 51.756702ms)","trace[2134698156] 'compare' (duration: 91.861797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:34.220649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.799742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:34.220794Z","caller":"traceutil/trace.go:171","msg":"trace[1163315106] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25889; }","duration":"150.042077ms","start":"2026-05-06T02:06:34.070726Z","end":"2026-05-06T02:06:34.220768Z","steps":["trace[1163315106] 'count revisions from in-memory index tree' (duration: 149.604187ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:42.146127Z","caller":"traceutil/trace.go:171","msg":"trace[1880503297] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"107.411168ms","start":"2026-05-06T02:06:42.038672Z","end":"2026-05-06T02:06:42.146083Z","steps":["trace[1880503297] 'process raft request' (duration: 107.144862ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:42.303245Z","caller":"traceutil/trace.go:171","msg":"trace[2008235258] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"152.356316ms","start":"2026-05-06T02:06:42.150859Z","end":"2026-05-06T02:06:42.303215Z","steps":["trace[2008235258] 'process raft request' (duration: 152.175952ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:42.304075Z","caller":"traceutil/trace.go:171","msg":"trace[625034819] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"140.948804ms","start":"2026-05-06T02:06:42.16311Z","end":"2026-05-06T02:06:42.304059Z","steps":["trace[625034819] 'process raft request' (duration: 140.861841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:42.720824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.798599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-06T02:06:42.720912Z","caller":"traceutil/trace.go:171","msg":"trace[361652298] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25961; }","duration":"162.934762ms","start":"2026-05-06T02:06:42.557947Z","end":"2026-05-06T02:06:42.720882Z","steps":["trace[361652298] 'range keys from in-memory index tree' (duration: 162.574005ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:42.720906Z","caller":"traceutil/trace.go:171","msg":"trace[450798862] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"153.250975ms","start":"2026-05-06T02:06:42.567622Z","end":"2026-05-06T02:06:42.720873Z","steps":["trace[450798862] 'process raft request' (duration: 136.60473ms)","trace[450798862] 'compare' (duration: 16.293337ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:42.746542Z","caller":"traceutil/trace.go:171","msg":"trace[1083246118] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"154.793398ms","start":"2026-05-06T02:06:42.591723Z","end":"2026-05-06T02:06:42.746516Z","steps":["trace[1083246118] 'process raft request' (duration: 154.616024ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:42.994043Z","caller":"traceutil/trace.go:171","msg":"trace[20061551] linearizableReadLoop","detail":"{readStateIndex:26819; appliedIndex:26818; }","duration":"159.55717ms","start":"2026-05-06T02:06:42.834463Z","end":"2026-05-06T02:06:42.99402Z","steps":["trace[20061551] 'read index received' (duration: 159.417047ms)","trace[20061551] 'applied index is now lower than readState.Index' (duration: 138.813µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:42.994172Z","caller":"traceutil/trace.go:171","msg":"trace[868563116] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"178.110145ms","start":"2026-05-06T02:06:42.816007Z","end":"2026-05-06T02:06:42.994117Z","steps":["trace[868563116] 'process raft request' (duration: 177.835889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:42.994409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.913277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-06T02:06:42.994456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.131761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T02:06:42.994519Z","caller":"traceutil/trace.go:171","msg":"trace[1189836419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25964; }","duration":"160.091001ms","start":"2026-05-06T02:06:42.834404Z","end":"2026-05-06T02:06:42.994495Z","steps":["trace[1189836419] 'agreement among raft nodes before linearized reading' (duration: 159.750604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:42.994536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.710793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-06T02:06:42.994555Z","caller":"traceutil/trace.go:171","msg":"trace[639392522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25964; }","duration":"159.302905ms","start":"2026-05-06T02:06:42.835226Z","end":"2026-05-06T02:06:42.994529Z","steps":["trace[639392522] 'agreement among raft nodes before linearized reading' (duration: 158.990998ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:42.994581Z","caller":"traceutil/trace.go:171","msg":"trace[1771629525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25964; }","duration":"113.770915ms","start":"2026-05-06T02:06:42.880796Z","end":"2026-05-06T02:06:42.994567Z","steps":["trace[1771629525] 'agreement among raft nodes before linearized reading' (duration: 113.611291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:42.994725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.290864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-06T02:06:42.994841Z","caller":"traceutil/trace.go:171","msg":"trace[336743189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25964; }","duration":"159.435488ms","start":"2026-05-06T02:06:42.835371Z","end":"2026-05-06T02:06:42.994807Z","steps":["trace[336743189] 'agreement among raft nodes before linearized reading' (duration: 159.044249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:57.657882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.367346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:57.658052Z","caller":"traceutil/trace.go:171","msg":"trace[23106252] transaction","detail":"{read_only:false; response_revision:26085; number_of_response:1; }","duration":"230.677265ms","start":"2026-05-06T02:06:57.427348Z","end":"2026-05-06T02:06:57.658026Z","steps":["trace[23106252] 'process raft request' (duration: 86.045943ms)","trace[23106252] 'compare' (duration: 144.229423ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:57.671744Z","caller":"traceutil/trace.go:171","msg":"trace[831142018] transaction","detail":"{read_only:false; response_revision:26086; number_of_response:1; }","duration":"139.981892ms","start":"2026-05-06T02:06:57.531727Z","end":"2026-05-06T02:06:57.671709Z","steps":["trace[831142018] 'process raft request' (duration: 139.826939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:06:58.500333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.821727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:06:58.500468Z","caller":"traceutil/trace.go:171","msg":"trace[204249251] linearizableReadLoop","detail":"{readStateIndex:26948; appliedIndex:26947; }","duration":"177.639565ms","start":"2026-05-06T02:06:58.32281Z","end":"2026-05-06T02:06:58.500449Z","steps":["trace[204249251] 'read index received' (duration: 31.585713ms)","trace[204249251] 'applied index is now lower than readState.Index' (duration: 146.052382ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:06:58.500532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.731657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:06:58.500518Z","caller":"traceutil/trace.go:171","msg":"trace[2069389819] transaction","detail":"{read_only:false; response_revision:26089; number_of_response:1; }","duration":"210.060126ms","start":"2026-05-06T02:06:58.290425Z","end":"2026-05-06T02:06:58.500485Z","steps":["trace[2069389819] 'process raft request' (duration: 63.983083ms)","trace[2069389819] 'compare' (duration: 144.907418ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:06:58.500565Z","caller":"traceutil/trace.go:171","msg":"trace[1758236941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26089; }","duration":"177.789327ms","start":"2026-05-06T02:06:58.322769Z","end":"2026-05-06T02:06:58.500558Z","steps":["trace[1758236941] 'agreement among raft nodes before linearized reading' (duration: 177.727126ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:06:58.960183Z","caller":"traceutil/trace.go:171","msg":"trace[475148855] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"153.661894ms","start":"2026-05-06T02:06:58.806485Z","end":"2026-05-06T02:06:58.960147Z","steps":["trace[475148855] 'process raft request' (duration: 141.088907ms)","trace[475148855] 'compare' (duration: 12.436474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:07:00.495061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.919896ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-06T02:07:00.495193Z","caller":"traceutil/trace.go:171","msg":"trace[162581212] linearizableReadLoop","detail":"{readStateIndex:26962; appliedIndex:26960; }","duration":"171.717518ms","start":"2026-05-06T02:07:00.323453Z","end":"2026-05-06T02:07:00.495171Z","steps":["trace[162581212] 'read index received' (duration: 27.337532ms)","trace[162581212] 'applied index is now lower than readState.Index' (duration: 144.378076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:07:00.495324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.898073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:07:00.495364Z","caller":"traceutil/trace.go:171","msg":"trace[1667191487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26102; }","duration":"171.970144ms","start":"2026-05-06T02:07:00.323375Z","end":"2026-05-06T02:07:00.495345Z","steps":["trace[1667191487] 'agreement among raft nodes before linearized reading' (duration: 171.847131ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:07:01.001055Z","caller":"traceutil/trace.go:171","msg":"trace[989905254] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"143.693842ms","start":"2026-05-06T02:07:00.857317Z","end":"2026-05-06T02:07:01.001011Z","steps":["trace[989905254] 'process raft request' (duration: 143.356115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:07:11.998522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.375648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-vc6lc\" ","response":"range_response_count:1 size:3397"} {"level":"info","ts":"2026-05-06T02:07:11.99864Z","caller":"traceutil/trace.go:171","msg":"trace[498696040] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-vc6lc; range_end:; response_count:1; response_revision:26191; }","duration":"169.567983ms","start":"2026-05-06T02:07:11.829048Z","end":"2026-05-06T02:07:11.998616Z","steps":["trace[498696040] 'range keys from in-memory index tree' (duration: 169.040081ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:07:23.199904Z","caller":"traceutil/trace.go:171","msg":"trace[739384166] transaction","detail":"{read_only:false; response_revision:26277; number_of_response:1; }","duration":"110.243919ms","start":"2026-05-06T02:07:23.089627Z","end":"2026-05-06T02:07:23.199871Z","steps":["trace[739384166] 'process raft request' (duration: 110.004214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:07:23.539696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.373112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T02:07:23.539848Z","caller":"traceutil/trace.go:171","msg":"trace[465708119] linearizableReadLoop","detail":"{readStateIndex:27142; appliedIndex:27141; }","duration":"315.527413ms","start":"2026-05-06T02:07:23.224266Z","end":"2026-05-06T02:07:23.539793Z","steps":["trace[465708119] 'read index received' (duration: 119.984227ms)","trace[465708119] 'applied index is now lower than readState.Index' (duration: 195.541996ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T02:07:23.539927Z","caller":"traceutil/trace.go:171","msg":"trace[2069288385] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"428.261884ms","start":"2026-05-06T02:07:23.111651Z","end":"2026-05-06T02:07:23.539913Z","steps":["trace[2069288385] 'process raft request' (duration: 232.583696ms)","trace[2069288385] 'compare' (duration: 195.010305ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T02:07:23.539987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:07:23.111607Z","time spent":"428.343396ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T02:07:23.540122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.85085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:07:23.540646Z","caller":"traceutil/trace.go:171","msg":"trace[1117063442] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:26278; }","duration":"316.421872ms","start":"2026-05-06T02:07:23.224208Z","end":"2026-05-06T02:07:23.54063Z","steps":["trace[1117063442] 'agreement among raft nodes before linearized reading' (duration: 315.87493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:07:23.540694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T02:07:23.224184Z","time spent":"316.494493ms","remote":"127.0.0.1:52300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-06T02:07:23.570191Z","caller":"traceutil/trace.go:171","msg":"trace[588296773] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"290.50244ms","start":"2026-05-06T02:07:23.27964Z","end":"2026-05-06T02:07:23.570142Z","steps":["trace[588296773] 'process raft request' (duration: 290.132042ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:07:23.570219Z","caller":"traceutil/trace.go:171","msg":"trace[1321924471] transaction","detail":"{read_only:false; response_revision:26281; number_of_response:1; }","duration":"233.065986ms","start":"2026-05-06T02:07:23.337118Z","end":"2026-05-06T02:07:23.570184Z","steps":["trace[1321924471] 'process raft request' (duration: 232.989394ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:07:23.570288Z","caller":"traceutil/trace.go:171","msg":"trace[1821778022] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"233.711599ms","start":"2026-05-06T02:07:23.336542Z","end":"2026-05-06T02:07:23.570254Z","steps":["trace[1821778022] 'process raft request' (duration: 233.429693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:07:23.580341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.327814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T02:07:23.580446Z","caller":"traceutil/trace.go:171","msg":"trace[1153471998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26282; }","duration":"257.497857ms","start":"2026-05-06T02:07:23.322925Z","end":"2026-05-06T02:07:23.580423Z","steps":["trace[1153471998] 'agreement among raft nodes before linearized reading' (duration: 257.308923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:07:23.580476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.24281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-06T02:07:23.580533Z","caller":"traceutil/trace.go:171","msg":"trace[1167724275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26282; }","duration":"218.353322ms","start":"2026-05-06T02:07:23.362166Z","end":"2026-05-06T02:07:23.580519Z","steps":["trace[1167724275] 'agreement among raft nodes before linearized reading' (duration: 218.083547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:07:23.580618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.947009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-06T02:07:23.580631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.381277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-06T02:07:23.580714Z","caller":"traceutil/trace.go:171","msg":"trace[1126228144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26282; }","duration":"252.074581ms","start":"2026-05-06T02:07:23.328617Z","end":"2026-05-06T02:07:23.580691Z","steps":["trace[1126228144] 'agreement among raft nodes before linearized reading' (duration: 251.816545ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:07:23.58076Z","caller":"traceutil/trace.go:171","msg":"trace[1978818105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26282; }","duration":"221.579011ms","start":"2026-05-06T02:07:23.359159Z","end":"2026-05-06T02:07:23.580738Z","steps":["trace[1978818105] 'agreement among raft nodes before linearized reading' (duration: 221.299905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T02:07:23.580935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.771888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T02:07:23.580969Z","caller":"traceutil/trace.go:171","msg":"trace[1353224620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26282; }","duration":"223.845239ms","start":"2026-05-06T02:07:23.357114Z","end":"2026-05-06T02:07:23.580959Z","steps":["trace[1353224620] 'agreement among raft nodes before linearized reading' (duration: 223.257647ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:07:36.466638Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006aa2.wal"} {"level":"info","ts":"2026-05-06T02:10:51.897839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25466} {"level":"info","ts":"2026-05-06T02:10:52.05463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25466,"took":"151.052399ms","hash":874763838,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":35729408,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-06T02:10:52.054717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":874763838,"revision":25466,"compact-revision":22063} {"level":"info","ts":"2026-05-06T02:11:26.75323Z","caller":"traceutil/trace.go:171","msg":"trace[402146032] transaction","detail":"{read_only:false; response_revision:28727; number_of_response:1; }","duration":"101.595725ms","start":"2026-05-06T02:11:26.651599Z","end":"2026-05-06T02:11:26.753195Z","steps":["trace[402146032] 'process raft request' (duration: 101.201247ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T02:12:10.737596Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T02:12:10.753431Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-06T02:12:10.753534Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-06T02:15:51.904628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28368} {"level":"info","ts":"2026-05-06T02:15:52.090027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28368,"took":"166.638262ms","hash":1857322183,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":32018432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-06T02:15:52.090118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1857322183,"revision":28368,"compact-revision":25466} {"level":"info","ts":"2026-05-06T02:20:51.91123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30720} {"level":"info","ts":"2026-05-06T02:20:52.048674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30720,"took":"133.524764ms","hash":4053698494,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30670848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T02:20:52.049012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4053698494,"revision":30720,"compact-revision":28368} {"level":"info","ts":"2026-05-06T02:25:51.918384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32979} {"level":"info","ts":"2026-05-06T02:25:52.066693Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32979,"took":"144.168992ms","hash":3266968096,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30437376,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T02:25:52.066767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3266968096,"revision":32979,"compact-revision":30720} {"level":"info","ts":"2026-05-06T02:30:51.927024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35276} {"level":"info","ts":"2026-05-06T02:30:52.078402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35276,"took":"144.843967ms","hash":1760912123,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31428608,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T02:30:52.078523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1760912123,"revision":35276,"compact-revision":32979} {"level":"info","ts":"2026-05-06T02:33:40.664114Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T02:33:40.667891Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-06T02:33:40.667987Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-06T02:35:51.936647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37509} {"level":"info","ts":"2026-05-06T02:35:52.061731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37509,"took":"120.15386ms","hash":131080744,"current-db-size-bytes":58548224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31805440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-06T02:35:52.061811Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":131080744,"revision":37509,"compact-revision":35276}