{"level":"warn","ts":"2026-04-07T01:24:06.902586Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T01:24:06.90268Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T01:24:06.902743Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T01:24:06.902754Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-04-07T01:24:06.90278Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T01:24:06.903382Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-04-07T01:24:06.903467Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T01:24:06.907679Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.99956ms"} {"level":"info","ts":"2026-04-07T01:24:06.915417Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-04-07T01:24:06.915554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T01:24:06.915589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-04-07T01:24:06.915609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T01:24:06.915623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-04-07T01:24:06.91568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-04-07T01:24:06.922204Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T01:24:06.92352Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T01:24:06.924778Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T01:24:06.929105Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T01:24:06.929315Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T01:24:06.929432Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T01:24:06.929446Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T01:24:06.929413Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T01:24:06.930889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-04-07T01:24:06.931346Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-04-07T01:24:06.934503Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T01:24:06.934642Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-04-07T01:24:06.934681Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-04-07T01:24:06.934874Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T01:24:06.934941Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T01:24:07.516403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T01:24:07.516483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T01:24:07.51654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-04-07T01:24:07.516566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-04-07T01:24:07.516591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-04-07T01:24:07.516617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-04-07T01:24:07.516633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-04-07T01:24:07.518078Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T01:24:07.51848Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:07.519355Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:24:07.519684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:24:07.519852Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T01:24:07.519868Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T01:24:07.519902Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:07.519966Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:07.519988Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:07.521508Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T01:24:07.521808Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-04-07T01:24:11.415504Z","caller":"traceutil/trace.go:171","msg":"trace[1750758937] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"172.459771ms","start":"2026-04-07T01:24:11.243009Z","end":"2026-04-07T01:24:11.415469Z","steps":["trace[1750758937] 'process raft request' (duration: 172.278846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:21.778002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.098412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"warn","ts":"2026-04-07T01:24:21.778061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.074447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T01:24:21.778142Z","caller":"traceutil/trace.go:171","msg":"trace[1573006230] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:360; }","duration":"183.312318ms","start":"2026-04-07T01:24:21.594801Z","end":"2026-04-07T01:24:21.778113Z","steps":["trace[1573006230] 'range keys from in-memory index tree' (duration: 182.917948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:21.778108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.351712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T01:24:21.778158Z","caller":"traceutil/trace.go:171","msg":"trace[259752007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:360; }","duration":"432.234863ms","start":"2026-04-07T01:24:21.345892Z","end":"2026-04-07T01:24:21.778127Z","steps":["trace[259752007] 'range keys from in-memory index tree' (duration: 431.942443ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:21.778205Z","caller":"traceutil/trace.go:171","msg":"trace[838657992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:360; }","duration":"274.490265ms","start":"2026-04-07T01:24:21.503698Z","end":"2026-04-07T01:24:21.778189Z","steps":["trace[838657992] 'range keys from in-memory index tree' (duration: 274.250438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:21.778215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:21.345844Z","time spent":"432.361635ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T01:24:22.25038Z","caller":"traceutil/trace.go:171","msg":"trace[503703384] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"467.07384ms","start":"2026-04-07T01:24:21.783275Z","end":"2026-04-07T01:24:22.250349Z","steps":["trace[503703384] 'process raft request' (duration: 376.180181ms)","trace[503703384] 'compare' (duration: 90.679653ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:22.250527Z","caller":"traceutil/trace.go:171","msg":"trace[1107860509] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:373; }","duration":"270.714781ms","start":"2026-04-07T01:24:21.979788Z","end":"2026-04-07T01:24:22.250503Z","steps":["trace[1107860509] 'read index received' (duration: 179.367011ms)","trace[1107860509] 'applied index is now lower than readState.Index' (duration: 91.34591ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:22.250606Z","caller":"traceutil/trace.go:171","msg":"trace[837692031] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"462.488774ms","start":"2026-04-07T01:24:21.788092Z","end":"2026-04-07T01:24:22.25058Z","steps":["trace[837692031] 'process raft request' (duration: 462.185775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:22.250739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.535116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-07T01:24:22.250738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.926738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:24:22.250803Z","caller":"traceutil/trace.go:171","msg":"trace[1191879555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:363; }","duration":"270.63783ms","start":"2026-04-07T01:24:21.980148Z","end":"2026-04-07T01:24:22.250786Z","steps":["trace[1191879555] 'agreement among raft nodes before linearized reading' (duration: 270.513376ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:22.250834Z","caller":"traceutil/trace.go:171","msg":"trace[971343351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:363; }","duration":"271.095192ms","start":"2026-04-07T01:24:21.979715Z","end":"2026-04-07T01:24:22.25081Z","steps":["trace[971343351] 'agreement among raft nodes before linearized reading' (duration: 270.897066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:22.251679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:21.783234Z","time spent":"467.239394ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:24:22.251815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:21.788066Z","time spent":"462.607788ms","remote":"127.0.0.1:51898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:24:38.230671Z","caller":"traceutil/trace.go:171","msg":"trace[1509655289] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"121.302415ms","start":"2026-04-07T01:24:38.109337Z","end":"2026-04-07T01:24:38.23064Z","steps":["trace[1509655289] 'process raft request' (duration: 120.333638ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.234233Z","caller":"traceutil/trace.go:171","msg":"trace[1928017926] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"124.559914ms","start":"2026-04-07T01:24:38.109623Z","end":"2026-04-07T01:24:38.234183Z","steps":["trace[1928017926] 'process raft request' (duration: 120.741499ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.234323Z","caller":"traceutil/trace.go:171","msg":"trace[1167489938] transaction","detail":"{read_only:false; number_of_response:1; response_revision:477; }","duration":"124.508183ms","start":"2026-04-07T01:24:38.109805Z","end":"2026-04-07T01:24:38.234313Z","steps":["trace[1167489938] 'process raft request' (duration: 124.340028ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.234273Z","caller":"traceutil/trace.go:171","msg":"trace[602702833] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"124.210774ms","start":"2026-04-07T01:24:38.110044Z","end":"2026-04-07T01:24:38.234255Z","steps":["trace[602702833] 'process raft request' (duration: 124.151233ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.234359Z","caller":"traceutil/trace.go:171","msg":"trace[1323564681] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"124.632707ms","start":"2026-04-07T01:24:38.109671Z","end":"2026-04-07T01:24:38.234304Z","steps":["trace[1323564681] 'process raft request' (duration: 124.396319ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.23938Z","caller":"traceutil/trace.go:171","msg":"trace[716274834] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"122.842647ms","start":"2026-04-07T01:24:38.11652Z","end":"2026-04-07T01:24:38.239362Z","steps":["trace[716274834] 'process raft request' (duration: 122.551168ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.23942Z","caller":"traceutil/trace.go:171","msg":"trace[1934095832] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"122.730874ms","start":"2026-04-07T01:24:38.116667Z","end":"2026-04-07T01:24:38.239398Z","steps":["trace[1934095832] 'process raft request' (duration: 122.508968ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.239587Z","caller":"traceutil/trace.go:171","msg":"trace[252188044] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"122.611531ms","start":"2026-04-07T01:24:38.116964Z","end":"2026-04-07T01:24:38.239575Z","steps":["trace[252188044] 'process raft request' (duration: 122.316432ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.239587Z","caller":"traceutil/trace.go:171","msg":"trace[1008035612] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"121.074378ms","start":"2026-04-07T01:24:38.118474Z","end":"2026-04-07T01:24:38.239548Z","steps":["trace[1008035612] 'process raft request' (duration: 120.910584ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.239954Z","caller":"traceutil/trace.go:171","msg":"trace[1613512710] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"121.288864ms","start":"2026-04-07T01:24:38.11865Z","end":"2026-04-07T01:24:38.239939Z","steps":["trace[1613512710] 'process raft request' (duration: 120.76595ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.240161Z","caller":"traceutil/trace.go:171","msg":"trace[1309323120] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"116.04685ms","start":"2026-04-07T01:24:38.124104Z","end":"2026-04-07T01:24:38.240151Z","steps":["trace[1309323120] 'process raft request' (duration: 115.370312ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.241324Z","caller":"traceutil/trace.go:171","msg":"trace[510637212] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"122.389944ms","start":"2026-04-07T01:24:38.117354Z","end":"2026-04-07T01:24:38.239744Z","steps":["trace[510637212] 'process raft request' (duration: 121.989152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:38.779406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.020274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:24:38.779667Z","caller":"traceutil/trace.go:171","msg":"trace[1732462656] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"184.229513ms","start":"2026-04-07T01:24:38.595395Z","end":"2026-04-07T01:24:38.779625Z","steps":["trace[1732462656] 'process raft request' (duration: 81.759407ms)","trace[1732462656] 'compare' (duration: 101.888361ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:38.779866Z","caller":"traceutil/trace.go:171","msg":"trace[1177274791] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"125.850089ms","start":"2026-04-07T01:24:38.653999Z","end":"2026-04-07T01:24:38.779849Z","steps":["trace[1177274791] 'process raft request' (duration: 125.723566ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.780112Z","caller":"traceutil/trace.go:171","msg":"trace[1568000843] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"141.908741ms","start":"2026-04-07T01:24:38.638194Z","end":"2026-04-07T01:24:38.780103Z","steps":["trace[1568000843] 'process raft request' (duration: 141.389387ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.988836Z","caller":"traceutil/trace.go:171","msg":"trace[1314397806] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"187.111474ms","start":"2026-04-07T01:24:38.801696Z","end":"2026-04-07T01:24:38.988807Z","steps":["trace[1314397806] 'process raft request' (duration: 119.430593ms)","trace[1314397806] 'compare' (duration: 67.429634ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:38.989718Z","caller":"traceutil/trace.go:171","msg":"trace[86043394] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"142.096496ms","start":"2026-04-07T01:24:38.847593Z","end":"2026-04-07T01:24:38.989689Z","steps":["trace[86043394] 'process raft request' (duration: 141.783738ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:38.989716Z","caller":"traceutil/trace.go:171","msg":"trace[1651797539] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"112.917774ms","start":"2026-04-07T01:24:38.876773Z","end":"2026-04-07T01:24:38.98969Z","steps":["trace[1651797539] 'process raft request' (duration: 112.834482ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:44.405921Z","caller":"traceutil/trace.go:171","msg":"trace[1580917785] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"239.453303ms","start":"2026-04-07T01:24:44.166435Z","end":"2026-04-07T01:24:44.405889Z","steps":["trace[1580917785] 'process raft request' (duration: 238.809175ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:44.687874Z","caller":"traceutil/trace.go:171","msg":"trace[230396012] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"236.287195ms","start":"2026-04-07T01:24:44.451556Z","end":"2026-04-07T01:24:44.687843Z","steps":["trace[230396012] 'process raft request' (duration: 162.022023ms)","trace[230396012] 'compare' (duration: 73.85727ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:44.688058Z","caller":"traceutil/trace.go:171","msg":"trace[538422556] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"213.079088ms","start":"2026-04-07T01:24:44.47495Z","end":"2026-04-07T01:24:44.688029Z","steps":["trace[538422556] 'process raft request' (duration: 212.8188ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:44.688063Z","caller":"traceutil/trace.go:171","msg":"trace[26102868] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"187.079892ms","start":"2026-04-07T01:24:44.500947Z","end":"2026-04-07T01:24:44.688027Z","steps":["trace[26102868] 'read index received' (duration: 112.681067ms)","trace[26102868] 'applied index is now lower than readState.Index' (duration: 74.397205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:24:44.688332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.359889ms","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-04-07T01:24:44.688409Z","caller":"traceutil/trace.go:171","msg":"trace[1443412023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"187.487983ms","start":"2026-04-07T01:24:44.500896Z","end":"2026-04-07T01:24:44.688384Z","steps":["trace[1443412023] 'agreement among raft nodes before linearized reading' (duration: 187.217766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:45.630108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.810138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:24:45.630197Z","caller":"traceutil/trace.go:171","msg":"trace[197063655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"651.950332ms","start":"2026-04-07T01:24:44.978227Z","end":"2026-04-07T01:24:45.630177Z","steps":["trace[197063655] 'range keys from in-memory index tree' (duration: 651.707026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:45.630237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:44.978207Z","time spent":"652.020934ms","remote":"127.0.0.1:51702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T01:24:45.75224Z","caller":"traceutil/trace.go:171","msg":"trace[1356206635] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"369.96595ms","start":"2026-04-07T01:24:45.382244Z","end":"2026-04-07T01:24:45.75221Z","steps":["trace[1356206635] 'read index received' (duration: 368.49741ms)","trace[1356206635] 'applied index is now lower than readState.Index' (duration: 1.46756ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:45.752311Z","caller":"traceutil/trace.go:171","msg":"trace[284047333] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"603.056969ms","start":"2026-04-07T01:24:45.14923Z","end":"2026-04-07T01:24:45.752287Z","steps":["trace[284047333] 'process raft request' (duration: 601.892146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:45.752446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:45.1492Z","time spent":"603.165361ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:24:45.752491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.236688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:24:45.752561Z","caller":"traceutil/trace.go:171","msg":"trace[1556160346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"370.34981ms","start":"2026-04-07T01:24:45.382195Z","end":"2026-04-07T01:24:45.752545Z","steps":["trace[1556160346] 'agreement among raft nodes before linearized reading' (duration: 370.152805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:45.752609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:45.382171Z","time spent":"370.425163ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T01:24:45.75272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.437981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:24:45.752939Z","caller":"traceutil/trace.go:171","msg":"trace[355620170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"120.710958ms","start":"2026-04-07T01:24:45.632191Z","end":"2026-04-07T01:24:45.752902Z","steps":["trace[355620170] 'agreement among raft nodes before linearized reading' (duration: 120.267425ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:46.914838Z","caller":"traceutil/trace.go:171","msg":"trace[699329702] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"143.806963ms","start":"2026-04-07T01:24:46.771Z","end":"2026-04-07T01:24:46.914807Z","steps":["trace[699329702] 'process raft request' (duration: 137.150751ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:49.165048Z","caller":"traceutil/trace.go:171","msg":"trace[289598324] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"125.876528ms","start":"2026-04-07T01:25:49.039152Z","end":"2026-04-07T01:25:49.165029Z","steps":["trace[289598324] 'read index received' (duration: 125.663812ms)","trace[289598324] 'applied index is now lower than readState.Index' (duration: 212.016µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:49.165285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.115844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T01:25:49.165325Z","caller":"traceutil/trace.go:171","msg":"trace[1496971725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1385; }","duration":"126.194406ms","start":"2026-04-07T01:25:49.039119Z","end":"2026-04-07T01:25:49.165313Z","steps":["trace[1496971725] 'agreement among raft nodes before linearized reading' (duration: 125.997351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:49.165619Z","caller":"traceutil/trace.go:171","msg":"trace[1936208214] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"127.238945ms","start":"2026-04-07T01:25:49.038371Z","end":"2026-04-07T01:25:49.16561Z","steps":["trace[1936208214] 'process raft request' (duration: 126.510504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:12.288505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.631557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-04-07T01:26:12.288613Z","caller":"traceutil/trace.go:171","msg":"trace[43886624] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1793; }","duration":"183.78403ms","start":"2026-04-07T01:26:12.104806Z","end":"2026-04-07T01:26:12.28859Z","steps":["trace[43886624] 'range keys from in-memory index tree' (duration: 183.41048ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:12.480238Z","caller":"traceutil/trace.go:171","msg":"trace[1206350807] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"359.286945ms","start":"2026-04-07T01:26:12.120921Z","end":"2026-04-07T01:26:12.480208Z","steps":["trace[1206350807] 'process raft request' (duration: 359.060809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:12.48045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:12.120894Z","time spent":"359.443629ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:13.01893Z","caller":"traceutil/trace.go:171","msg":"trace[487063728] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"788.996594ms","start":"2026-04-07T01:26:12.22989Z","end":"2026-04-07T01:26:13.018887Z","steps":["trace[487063728] 'process raft request' (duration: 788.673486ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:13.019018Z","caller":"traceutil/trace.go:171","msg":"trace[686868307] linearizableReadLoop","detail":"{readStateIndex:1863; appliedIndex:1861; }","duration":"731.346188ms","start":"2026-04-07T01:26:12.287643Z","end":"2026-04-07T01:26:13.018989Z","steps":["trace[686868307] 'read index received' (duration: 192.327734ms)","trace[686868307] 'applied index is now lower than readState.Index' (duration: 539.017294ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:26:13.01911Z","caller":"traceutil/trace.go:171","msg":"trace[1452195158] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"718.695743ms","start":"2026-04-07T01:26:12.300366Z","end":"2026-04-07T01:26:13.019062Z","steps":["trace[1452195158] 'process raft request' (duration: 718.372974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:13.019195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:12.229861Z","time spent":"789.175958ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:13.019273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.600845ms","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":"warn","ts":"2026-04-07T01:26:13.019295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:12.30035Z","time spent":"718.850767ms","remote":"127.0.0.1:51898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10966,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:13.019313Z","caller":"traceutil/trace.go:171","msg":"trace[231882090] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1796; }","duration":"731.699648ms","start":"2026-04-07T01:26:12.287601Z","end":"2026-04-07T01:26:13.019301Z","steps":["trace[231882090] 'agreement among raft nodes before linearized reading' (duration: 731.528374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:13.019355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:12.287583Z","time spent":"731.76374ms","remote":"127.0.0.1:53048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T01:26:13.019292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.196546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:26:13.019452Z","caller":"traceutil/trace.go:171","msg":"trace[473391260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1796; }","duration":"689.374551ms","start":"2026-04-07T01:26:12.330061Z","end":"2026-04-07T01:26:13.019436Z","steps":["trace[473391260] 'agreement among raft nodes before linearized reading' (duration: 689.098113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:13.019488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:12.330051Z","time spent":"689.429024ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T01:26:13.019575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.177827ms","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-04-07T01:26:13.019624Z","caller":"traceutil/trace.go:171","msg":"trace[1537706338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1796; }","duration":"182.280028ms","start":"2026-04-07T01:26:12.837327Z","end":"2026-04-07T01:26:13.019607Z","steps":["trace[1537706338] 'agreement among raft nodes before linearized reading' (duration: 182.177125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:13.019672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.3177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T01:26:13.019707Z","caller":"traceutil/trace.go:171","msg":"trace[936179559] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1796; }","duration":"659.378702ms","start":"2026-04-07T01:26:12.36032Z","end":"2026-04-07T01:26:13.019699Z","steps":["trace[936179559] 'agreement among raft nodes before linearized reading' (duration: 659.267669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:13.019741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:12.360301Z","time spent":"659.432094ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:26:13.019977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.411986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T01:26:13.020025Z","caller":"traceutil/trace.go:171","msg":"trace[1876107519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1796; }","duration":"561.519119ms","start":"2026-04-07T01:26:12.458493Z","end":"2026-04-07T01:26:13.020012Z","steps":["trace[1876107519] 'agreement among raft nodes before linearized reading' (duration: 561.412326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:13.02007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:12.458462Z","time spent":"561.596791ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T01:26:21.23629Z","caller":"traceutil/trace.go:171","msg":"trace[2052970905] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"126.777553ms","start":"2026-04-07T01:26:21.10948Z","end":"2026-04-07T01:26:21.236258Z","steps":["trace[2052970905] 'process raft request' (duration: 75.387039ms)","trace[2052970905] 'compare' (duration: 51.23671ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:26:25.159676Z","caller":"traceutil/trace.go:171","msg":"trace[4922614] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"180.128651ms","start":"2026-04-07T01:26:24.979523Z","end":"2026-04-07T01:26:25.159652Z","steps":["trace[4922614] 'process raft request' (duration: 179.976757ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:57.507524Z","caller":"traceutil/trace.go:171","msg":"trace[553478906] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2170; }","duration":"155.245552ms","start":"2026-04-07T01:26:57.352255Z","end":"2026-04-07T01:26:57.507501Z","steps":["trace[553478906] 'read index received' (duration: 155.037956ms)","trace[553478906] 'applied index is now lower than readState.Index' (duration: 206.546µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:26:57.507633Z","caller":"traceutil/trace.go:171","msg":"trace[1825643194] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"213.247916ms","start":"2026-04-07T01:26:57.294359Z","end":"2026-04-07T01:26:57.507607Z","steps":["trace[1825643194] 'process raft request' (duration: 213.001818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:57.507732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.454227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T01:26:57.507777Z","caller":"traceutil/trace.go:171","msg":"trace[1737876344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2092; }","duration":"155.540019ms","start":"2026-04-07T01:26:57.352226Z","end":"2026-04-07T01:26:57.507766Z","steps":["trace[1737876344] 'agreement among raft nodes before linearized reading' (duration: 155.392125ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:57.52881Z","caller":"traceutil/trace.go:171","msg":"trace[1845592149] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"123.530644ms","start":"2026-04-07T01:26:57.405218Z","end":"2026-04-07T01:26:57.528749Z","steps":["trace[1845592149] 'process raft request' (duration: 123.171055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:57.529018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.949608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:26:57.529104Z","caller":"traceutil/trace.go:171","msg":"trace[807565502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2093; }","duration":"127.084111ms","start":"2026-04-07T01:26:57.401997Z","end":"2026-04-07T01:26:57.529081Z","steps":["trace[807565502] 'agreement among raft nodes before linearized reading' (duration: 126.777892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:57.529058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.217832ms","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-04-07T01:26:57.52928Z","caller":"traceutil/trace.go:171","msg":"trace[1806264159] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2093; }","duration":"143.750897ms","start":"2026-04-07T01:26:57.385504Z","end":"2026-04-07T01:26:57.529255Z","steps":["trace[1806264159] 'agreement among raft nodes before linearized reading' (duration: 143.064408ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:03.701946Z","caller":"traceutil/trace.go:171","msg":"trace[149469836] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"177.040936ms","start":"2026-04-07T01:27:03.524881Z","end":"2026-04-07T01:27:03.701921Z","steps":["trace[149469836] 'process raft request' (duration: 176.866081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.253596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.304259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:27:04.253839Z","caller":"traceutil/trace.go:171","msg":"trace[986900510] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2201; }","duration":"681.330165ms","start":"2026-04-07T01:27:03.572486Z","end":"2026-04-07T01:27:04.253816Z","steps":["trace[986900510] 'read index received' (duration: 129.31936ms)","trace[986900510] 'applied index is now lower than readState.Index' (duration: 552.009505ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:04.253953Z","caller":"traceutil/trace.go:171","msg":"trace[2018120823] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"706.827048ms","start":"2026-04-07T01:27:03.547042Z","end":"2026-04-07T01:27:04.253869Z","steps":["trace[2018120823] 'process raft request' (duration: 348.147618ms)","trace[2018120823] 'compare' (duration: 358.192406ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:04.254072Z","caller":"traceutil/trace.go:171","msg":"trace[223166378] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"503.97956ms","start":"2026-04-07T01:27:03.750073Z","end":"2026-04-07T01:27:04.254053Z","steps":["trace[223166378] 'process raft request' (duration: 503.892948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.254106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.547021Z","time spent":"707.012553ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:04.254176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.750057Z","time spent":"504.061112ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:04.254275Z","caller":"traceutil/trace.go:171","msg":"trace[492598299] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"550.997908ms","start":"2026-04-07T01:27:03.703236Z","end":"2026-04-07T01:27:04.254234Z","steps":["trace[492598299] 'process raft request' (duration: 550.516205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.25435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.85633ms","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-04-07T01:27:04.254403Z","caller":"traceutil/trace.go:171","msg":"trace[496994215] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2124; }","duration":"681.942673ms","start":"2026-04-07T01:27:03.572444Z","end":"2026-04-07T01:27:04.254387Z","steps":["trace[496994215] 'agreement among raft nodes before linearized reading' (duration: 681.48542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.254445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.57243Z","time spent":"682.004664ms","remote":"127.0.0.1:53048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T01:27:04.254442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.703207Z","time spent":"551.143951ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:04.48826Z","caller":"traceutil/trace.go:171","msg":"trace[1957175491] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"234.325397ms","start":"2026-04-07T01:27:04.253896Z","end":"2026-04-07T01:27:04.488221Z","steps":["trace[1957175491] 'read index received' (duration: 233.996648ms)","trace[1957175491] 'applied index is now lower than readState.Index' (duration: 327.809µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:04.488499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.363208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:04.488603Z","caller":"traceutil/trace.go:171","msg":"trace[1809929755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2126; }","duration":"509.525023ms","start":"2026-04-07T01:27:03.979063Z","end":"2026-04-07T01:27:04.488588Z","steps":["trace[1809929755] 'agreement among raft nodes before linearized reading' (duration: 509.360749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.488681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.979042Z","time spent":"509.626796ms","remote":"127.0.0.1:51702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:27:04.488713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.316136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:27:04.488765Z","caller":"traceutil/trace.go:171","msg":"trace[601847043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2126; }","duration":"860.382267ms","start":"2026-04-07T01:27:03.628371Z","end":"2026-04-07T01:27:04.488753Z","steps":["trace[601847043] 'agreement among raft nodes before linearized reading' (duration: 860.279744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.4888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.628362Z","time spent":"860.431198ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T01:27:04.488463Z","caller":"traceutil/trace.go:171","msg":"trace[1337162118] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"474.997991ms","start":"2026-04-07T01:27:04.013417Z","end":"2026-04-07T01:27:04.488415Z","steps":["trace[1337162118] 'process raft request' (duration: 474.59756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.488622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.901421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T01:27:04.489025Z","caller":"traceutil/trace.go:171","msg":"trace[300187965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2126; }","duration":"861.275863ms","start":"2026-04-07T01:27:03.627668Z","end":"2026-04-07T01:27:04.488944Z","steps":["trace[300187965] 'agreement among raft nodes before linearized reading' (duration: 860.791069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.488992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.148232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T01:27:04.489132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.611104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-07T01:27:04.489183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:04.013393Z","time spent":"475.539805ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:04.489189Z","caller":"traceutil/trace.go:171","msg":"trace[1137873709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2126; }","duration":"859.410421ms","start":"2026-04-07T01:27:03.629712Z","end":"2026-04-07T01:27:04.489122Z","steps":["trace[1137873709] 'agreement among raft nodes before linearized reading' (duration: 858.987969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.489179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.627649Z","time spent":"861.476927ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T01:27:04.489248Z","caller":"traceutil/trace.go:171","msg":"trace[2039219653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2126; }","duration":"860.814739ms","start":"2026-04-07T01:27:03.628412Z","end":"2026-04-07T01:27:04.489226Z","steps":["trace[2039219653] 'agreement among raft nodes before linearized reading' (duration: 860.211803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.489338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.629701Z","time spent":"859.581296ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T01:27:04.489383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.628406Z","time spent":"860.961184ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:27:04.489506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.633544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T01:27:04.489587Z","caller":"traceutil/trace.go:171","msg":"trace[731991683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2126; }","duration":"765.644143ms","start":"2026-04-07T01:27:03.723928Z","end":"2026-04-07T01:27:04.489572Z","steps":["trace[731991683] 'agreement among raft nodes before linearized reading' (duration: 764.321316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:04.489629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:03.723912Z","time spent":"765.703744ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T01:27:56.072665Z","caller":"traceutil/trace.go:171","msg":"trace[1310618471] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"150.772551ms","start":"2026-04-07T01:27:55.921847Z","end":"2026-04-07T01:27:56.072619Z","steps":["trace[1310618471] 'process raft request' (duration: 150.431842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:29.046299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.987306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T01:28:29.046337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.423334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T01:28:29.046427Z","caller":"traceutil/trace.go:171","msg":"trace[2114522225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2778; }","duration":"116.172401ms","start":"2026-04-07T01:28:28.930227Z","end":"2026-04-07T01:28:29.0464Z","steps":["trace[2114522225] 'range keys from in-memory index tree' (duration: 115.787071ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:29.046459Z","caller":"traceutil/trace.go:171","msg":"trace[449569393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2778; }","duration":"102.599598ms","start":"2026-04-07T01:28:28.943836Z","end":"2026-04-07T01:28:29.046436Z","steps":["trace[449569393] 'range keys from in-memory index tree' (duration: 102.207697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:29.046299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.817059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T01:28:29.046475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.878344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T01:28:29.046543Z","caller":"traceutil/trace.go:171","msg":"trace[2106256826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2778; }","duration":"105.131447ms","start":"2026-04-07T01:28:28.941397Z","end":"2026-04-07T01:28:29.046528Z","steps":["trace[2106256826] 'range keys from in-memory index tree' (duration: 104.596533ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:29.046591Z","caller":"traceutil/trace.go:171","msg":"trace[1106938473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2778; }","duration":"104.071897ms","start":"2026-04-07T01:28:28.942495Z","end":"2026-04-07T01:28:29.046567Z","steps":["trace[1106938473] 'range keys from in-memory index tree' (duration: 103.604656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:29.046387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.408097ms","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-04-07T01:28:29.046943Z","caller":"traceutil/trace.go:171","msg":"trace[77235301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2778; }","duration":"100.954172ms","start":"2026-04-07T01:28:28.94594Z","end":"2026-04-07T01:28:29.046894Z","steps":["trace[77235301] 'range keys from in-memory index tree' (duration: 100.248234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:35.411636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.588261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:28:35.412164Z","caller":"traceutil/trace.go:171","msg":"trace[352469944] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"243.043396ms","start":"2026-04-07T01:28:35.169033Z","end":"2026-04-07T01:28:35.412076Z","steps":["trace[352469944] 'process raft request' (duration: 136.902941ms)","trace[352469944] 'compare' (duration: 105.461657ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:28:35.412177Z","caller":"traceutil/trace.go:171","msg":"trace[1997136160] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"243.114028ms","start":"2026-04-07T01:28:35.169032Z","end":"2026-04-07T01:28:35.412146Z","steps":["trace[1997136160] 'process raft request' (duration: 242.700397ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:35.412383Z","caller":"traceutil/trace.go:171","msg":"trace[1639434199] linearizableReadLoop","detail":"{readStateIndex:2923; appliedIndex:2919; }","duration":"240.549797ms","start":"2026-04-07T01:28:35.171784Z","end":"2026-04-07T01:28:35.412334Z","steps":["trace[1639434199] 'read index received' (duration: 134.146774ms)","trace[1639434199] 'applied index is now lower than readState.Index' (duration: 106.401433ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:28:35.41239Z","caller":"traceutil/trace.go:171","msg":"trace[1697374031] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"242.323635ms","start":"2026-04-07T01:28:35.170036Z","end":"2026-04-07T01:28:35.412359Z","steps":["trace[1697374031] 'process raft request' (duration: 242.197162ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:35.412326Z","caller":"traceutil/trace.go:171","msg":"trace[1101556205] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"242.306125ms","start":"2026-04-07T01:28:35.169993Z","end":"2026-04-07T01:28:35.412299Z","steps":["trace[1101556205] 'process raft request' (duration: 241.792951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:35.412571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.777714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:28:35.41295Z","caller":"traceutil/trace.go:171","msg":"trace[236772677] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2815; }","duration":"241.175034ms","start":"2026-04-07T01:28:35.171762Z","end":"2026-04-07T01:28:35.412937Z","steps":["trace[236772677] 'agreement among raft nodes before linearized reading' (duration: 240.754592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:31:06.213501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.678954ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T01:31:06.213667Z","caller":"traceutil/trace.go:171","msg":"trace[2049486445] linearizableReadLoop","detail":"{readStateIndex:3948; appliedIndex:3947; }","duration":"234.628167ms","start":"2026-04-07T01:31:05.979011Z","end":"2026-04-07T01:31:06.213639Z","steps":["trace[2049486445] 'read index received' (duration: 73.773138ms)","trace[2049486445] 'applied index is now lower than readState.Index' (duration: 160.853199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:31:06.213972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.938335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:31:06.21406Z","caller":"traceutil/trace.go:171","msg":"trace[815188547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3803; }","duration":"235.08481ms","start":"2026-04-07T01:31:05.978956Z","end":"2026-04-07T01:31:06.21404Z","steps":["trace[815188547] 'agreement among raft nodes before linearized reading' (duration: 234.820313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:31:06.214112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.8837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T01:31:06.214242Z","caller":"traceutil/trace.go:171","msg":"trace[48174728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3803; }","duration":"229.075255ms","start":"2026-04-07T01:31:05.985136Z","end":"2026-04-07T01:31:06.214211Z","steps":["trace[48174728] 'agreement among raft nodes before linearized reading' (duration: 228.677043ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:36.903429Z","caller":"traceutil/trace.go:171","msg":"trace[1775138366] linearizableReadLoop","detail":"{readStateIndex:5210; appliedIndex:5209; }","duration":"127.641663ms","start":"2026-04-07T01:32:36.77576Z","end":"2026-04-07T01:32:36.903402Z","steps":["trace[1775138366] 'read index received' (duration: 127.426497ms)","trace[1775138366] 'applied index is now lower than readState.Index' (duration: 214.336µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:32:36.903544Z","caller":"traceutil/trace.go:171","msg":"trace[673998212] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"129.644797ms","start":"2026-04-07T01:32:36.773889Z","end":"2026-04-07T01:32:36.903534Z","steps":["trace[673998212] 'process raft request' (duration: 129.362329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:36.904006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.214829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:32:36.904039Z","caller":"traceutil/trace.go:171","msg":"trace[1604597804] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:5020; }","duration":"128.29444ms","start":"2026-04-07T01:32:36.775736Z","end":"2026-04-07T01:32:36.90403Z","steps":["trace[1604597804] 'agreement among raft nodes before linearized reading' (duration: 128.221988ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:37.01592Z","caller":"traceutil/trace.go:171","msg":"trace[817217210] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"101.349239ms","start":"2026-04-07T01:32:36.914542Z","end":"2026-04-07T01:32:37.015891Z","steps":["trace[817217210] 'process raft request' (duration: 101.237816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:37.016237Z","caller":"traceutil/trace.go:171","msg":"trace[224844689] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"101.600237ms","start":"2026-04-07T01:32:36.914608Z","end":"2026-04-07T01:32:37.016209Z","steps":["trace[224844689] 'process raft request' (duration: 101.246626ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:37.016415Z","caller":"traceutil/trace.go:171","msg":"trace[974873763] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"101.583156ms","start":"2026-04-07T01:32:36.914822Z","end":"2026-04-07T01:32:37.016405Z","steps":["trace[974873763] 'process raft request' (duration: 101.275358ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:37.016673Z","caller":"traceutil/trace.go:171","msg":"trace[1934777807] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"101.554015ms","start":"2026-04-07T01:32:36.915086Z","end":"2026-04-07T01:32:37.01664Z","steps":["trace[1934777807] 'process raft request' (duration: 101.254877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:37.269827Z","caller":"traceutil/trace.go:171","msg":"trace[1235335197] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"134.664912ms","start":"2026-04-07T01:32:37.135124Z","end":"2026-04-07T01:32:37.269789Z","steps":["trace[1235335197] 'process raft request' (duration: 129.444981ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:37.277437Z","caller":"traceutil/trace.go:171","msg":"trace[751802936] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"141.044376ms","start":"2026-04-07T01:32:37.136366Z","end":"2026-04-07T01:32:37.27741Z","steps":["trace[751802936] 'process raft request' (duration: 140.825181ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:47.453591Z","caller":"traceutil/trace.go:171","msg":"trace[821514485] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"164.489043ms","start":"2026-04-07T01:32:47.289068Z","end":"2026-04-07T01:32:47.453557Z","steps":["trace[821514485] 'process raft request' (duration: 164.182374ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:47.509881Z","caller":"traceutil/trace.go:171","msg":"trace[285531022] linearizableReadLoop","detail":"{readStateIndex:5421; appliedIndex:5419; }","duration":"183.891638ms","start":"2026-04-07T01:32:47.325961Z","end":"2026-04-07T01:32:47.509852Z","steps":["trace[285531022] 'read index received' (duration: 127.249103ms)","trace[285531022] 'applied index is now lower than readState.Index' (duration: 56.641465ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:32:47.509986Z","caller":"traceutil/trace.go:171","msg":"trace[556137204] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"196.237723ms","start":"2026-04-07T01:32:47.313716Z","end":"2026-04-07T01:32:47.509954Z","steps":["trace[556137204] 'process raft request' (duration: 195.961186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:47.51024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.240288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7pfzq\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T01:32:47.510332Z","caller":"traceutil/trace.go:171","msg":"trace[1120116080] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7pfzq; range_end:; response_count:1; response_revision:5224; }","duration":"184.389252ms","start":"2026-04-07T01:32:47.32592Z","end":"2026-04-07T01:32:47.510309Z","steps":["trace[1120116080] 'agreement among raft nodes before linearized reading' (duration: 184.070584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:47.516983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.610527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-9ltbl\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-04-07T01:32:47.517152Z","caller":"traceutil/trace.go:171","msg":"trace[1632253725] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-9ltbl; range_end:; response_count:1; response_revision:5225; }","duration":"181.843153ms","start":"2026-04-07T01:32:47.335283Z","end":"2026-04-07T01:32:47.517127Z","steps":["trace[1632253725] 'agreement among raft nodes before linearized reading' (duration: 181.389531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:56.239992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.171449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-07T01:33:07.764206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.390093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7pfzq\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T01:33:07.764317Z","caller":"traceutil/trace.go:171","msg":"trace[1596737172] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7pfzq; range_end:; response_count:1; response_revision:5578; }","duration":"104.565938ms","start":"2026-04-07T01:33:07.659731Z","end":"2026-04-07T01:33:07.764297Z","steps":["trace[1596737172] 'agreement among raft nodes before linearized reading' (duration: 104.27567ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:07.948189Z","caller":"traceutil/trace.go:171","msg":"trace[1644523461] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"146.301319ms","start":"2026-04-07T01:33:07.801844Z","end":"2026-04-07T01:33:07.948145Z","steps":["trace[1644523461] 'process raft request' (duration: 124.935989ms)","trace[1644523461] 'compare' (duration: 21.00871ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:34:07.599671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2989} {"level":"info","ts":"2026-04-07T01:34:07.67922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2989,"took":"77.7754ms","hash":1240254445,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":33669120,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T01:34:07.679316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1240254445,"revision":2989,"compact-revision":-1} {"level":"info","ts":"2026-04-07T01:39:07.610959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6075} {"level":"info","ts":"2026-04-07T01:39:07.719698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6075,"took":"106.316971ms","hash":793826020,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":21553152,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-07T01:39:07.7198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":793826020,"revision":6075,"compact-revision":2989} {"level":"info","ts":"2026-04-07T01:39:25.603667Z","caller":"traceutil/trace.go:171","msg":"trace[1750297271] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"167.574221ms","start":"2026-04-07T01:39:25.436066Z","end":"2026-04-07T01:39:25.60364Z","steps":["trace[1750297271] 'process raft request' (duration: 167.380776ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:36.517953Z","caller":"traceutil/trace.go:171","msg":"trace[1013200779] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"223.905818ms","start":"2026-04-07T01:39:36.294014Z","end":"2026-04-07T01:39:36.51792Z","steps":["trace[1013200779] 'process raft request' (duration: 223.334412ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:37.356143Z","caller":"traceutil/trace.go:171","msg":"trace[1593866952] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"148.501317ms","start":"2026-04-07T01:39:37.207592Z","end":"2026-04-07T01:39:37.356093Z","steps":["trace[1593866952] 'process raft request' (duration: 148.23387ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:39.567889Z","caller":"traceutil/trace.go:171","msg":"trace[1334499821] linearizableReadLoop","detail":"{readStateIndex:8734; appliedIndex:8736; }","duration":"265.503507ms","start":"2026-04-07T01:39:39.302341Z","end":"2026-04-07T01:39:39.567844Z","steps":["trace[1334499821] 'read index received' (duration: 103.372µs)","trace[1334499821] 'applied index is now lower than readState.Index' (duration: 265.397905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:39:40.068267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350678248018258,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:39:40.41001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107641988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:39:40.410093Z","caller":"traceutil/trace.go:171","msg":"trace[250402422] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8436; }","duration":"1.107784251s","start":"2026-04-07T01:39:39.302287Z","end":"2026-04-07T01:39:40.410071Z","steps":["trace[250402422] 'agreement among raft nodes before linearized reading' (duration: 265.871006ms)","trace[250402422] 'count revisions from in-memory index tree' (duration: 841.74043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:39:40.410253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.302263Z","time spent":"1.107977417s","remote":"127.0.0.1:36656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T01:39:40.410443Z","caller":"traceutil/trace.go:171","msg":"trace[370690943] transaction","detail":"{read_only:false; response_revision:8437; number_of_response:1; }","duration":"895.170268ms","start":"2026-04-07T01:39:39.515213Z","end":"2026-04-07T01:39:40.410384Z","steps":["trace[370690943] 'process raft request' (duration: 893.39146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.410705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.515193Z","time spent":"895.372644ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:39:40.412539Z","caller":"traceutil/trace.go:171","msg":"trace[1077238536] transaction","detail":"{read_only:false; response_revision:8438; number_of_response:1; }","duration":"750.689679ms","start":"2026-04-07T01:39:39.661827Z","end":"2026-04-07T01:39:40.412516Z","steps":["trace[1077238536] 'process raft request' (duration: 750.499194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.412651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.661803Z","time spent":"750.799193ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:39:40.41279Z","caller":"traceutil/trace.go:171","msg":"trace[2103612647] linearizableReadLoop","detail":"{readStateIndex:8739; appliedIndex:8736; }","duration":"844.68976ms","start":"2026-04-07T01:39:39.568037Z","end":"2026-04-07T01:39:40.412726Z","steps":["trace[2103612647] 'read index received' (duration: 840.428085ms)","trace[2103612647] 'applied index is now lower than readState.Index' (duration: 4.257825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:39:40.41301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.669081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-07T01:39:40.413054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.727755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T01:39:40.41308Z","caller":"traceutil/trace.go:171","msg":"trace[660270182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8439; }","duration":"921.802136ms","start":"2026-04-07T01:39:39.491257Z","end":"2026-04-07T01:39:40.41306Z","steps":["trace[660270182] 'agreement among raft nodes before linearized reading' (duration: 921.517098ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:40.413098Z","caller":"traceutil/trace.go:171","msg":"trace[148753524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8439; }","duration":"956.771986ms","start":"2026-04-07T01:39:39.456313Z","end":"2026-04-07T01:39:40.413085Z","steps":["trace[148753524] 'agreement among raft nodes before linearized reading' (duration: 956.606972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.413139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.491232Z","time spent":"921.899288ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T01:39:40.413163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.456235Z","time spent":"956.919951ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T01:39:40.413173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052840103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T01:39:40.413224Z","caller":"traceutil/trace.go:171","msg":"trace[660551099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8439; }","duration":"1.052963325s","start":"2026-04-07T01:39:39.360245Z","end":"2026-04-07T01:39:40.413209Z","steps":["trace[660551099] 'agreement among raft nodes before linearized reading' (duration: 1.052684268s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.413263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.360222Z","time spent":"1.053032088s","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:39:40.413388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.551981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-07T01:39:40.413365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.683964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T01:39:40.413432Z","caller":"traceutil/trace.go:171","msg":"trace[1911244181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8439; }","duration":"933.619733ms","start":"2026-04-07T01:39:39.479801Z","end":"2026-04-07T01:39:40.413421Z","steps":["trace[1911244181] 'agreement among raft nodes before linearized reading' (duration: 933.49929ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:40.413452Z","caller":"traceutil/trace.go:171","msg":"trace[165853927] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"591.184594ms","start":"2026-04-07T01:39:39.822249Z","end":"2026-04-07T01:39:40.413434Z","steps":["trace[165853927] 'process raft request' (duration: 590.214208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.413463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.47979Z","time spent":"933.666644ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T01:39:40.413458Z","caller":"traceutil/trace.go:171","msg":"trace[1301947567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8439; }","duration":"735.829758ms","start":"2026-04-07T01:39:39.677606Z","end":"2026-04-07T01:39:40.413436Z","steps":["trace[1301947567] 'agreement among raft nodes before linearized reading' (duration: 735.569102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.413513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.677593Z","time spent":"735.91194ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T01:39:40.413522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.419012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:39:40.41356Z","caller":"traceutil/trace.go:171","msg":"trace[566323322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8439; }","duration":"435.513504ms","start":"2026-04-07T01:39:39.978036Z","end":"2026-04-07T01:39:40.41355Z","steps":["trace[566323322] 'agreement among raft nodes before linearized reading' (duration: 435.446173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.413564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.667678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7pfzq\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-07T01:39:40.413589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.978002Z","time spent":"435.580167ms","remote":"127.0.0.1:51702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:39:40.413596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:39.822214Z","time spent":"591.281987ms","remote":"127.0.0.1:52008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:39:40.41363Z","caller":"traceutil/trace.go:171","msg":"trace[1192971521] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7pfzq; range_end:; response_count:1; response_revision:8439; }","duration":"162.772021ms","start":"2026-04-07T01:39:40.25084Z","end":"2026-04-07T01:39:40.413612Z","steps":["trace[1192971521] 'agreement among raft nodes before linearized reading' (duration: 162.600286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.413604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.300398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:39:40.413708Z","caller":"traceutil/trace.go:171","msg":"trace[770110643] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8439; }","duration":"383.487844ms","start":"2026-04-07T01:39:40.030197Z","end":"2026-04-07T01:39:40.413685Z","steps":["trace[770110643] 'agreement among raft nodes before linearized reading' (duration: 383.262428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:40.413761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:40.030154Z","time spent":"383.596307ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-07T01:39:42.738674Z","caller":"traceutil/trace.go:171","msg":"trace[108661704] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"101.332298ms","start":"2026-04-07T01:39:42.637306Z","end":"2026-04-07T01:39:42.738638Z","steps":["trace[108661704] 'process raft request' (duration: 101.133213ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.744017Z","caller":"traceutil/trace.go:171","msg":"trace[1240747029] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"267.903283ms","start":"2026-04-07T01:39:54.476081Z","end":"2026-04-07T01:39:54.743984Z","steps":["trace[1240747029] 'process raft request' (duration: 267.694027ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.745346Z","caller":"traceutil/trace.go:171","msg":"trace[754137028] linearizableReadLoop","detail":"{readStateIndex:8874; appliedIndex:8872; }","duration":"247.657157ms","start":"2026-04-07T01:39:54.497657Z","end":"2026-04-07T01:39:54.745314Z","steps":["trace[754137028] 'read index received' (duration: 246.157957ms)","trace[754137028] 'applied index is now lower than readState.Index' (duration: 1.49797ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:54.745449Z","caller":"traceutil/trace.go:171","msg":"trace[1771851560] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"264.47112ms","start":"2026-04-07T01:39:54.480927Z","end":"2026-04-07T01:39:54.745398Z","steps":["trace[1771851560] 'process raft request' (duration: 264.207722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:54.74559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.967435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T01:39:54.745643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.21626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T01:39:54.745657Z","caller":"traceutil/trace.go:171","msg":"trace[1301328052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8568; }","duration":"248.091419ms","start":"2026-04-07T01:39:54.497547Z","end":"2026-04-07T01:39:54.745639Z","steps":["trace[1301328052] 'agreement among raft nodes before linearized reading' (duration: 247.902334ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.745691Z","caller":"traceutil/trace.go:171","msg":"trace[935868570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8568; }","duration":"239.316962ms","start":"2026-04-07T01:39:54.506362Z","end":"2026-04-07T01:39:54.745679Z","steps":["trace[935868570] 'agreement among raft nodes before linearized reading' (duration: 239.104276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:54.745827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.341366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T01:39:54.745863Z","caller":"traceutil/trace.go:171","msg":"trace[1120614596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8568; }","duration":"235.398277ms","start":"2026-04-07T01:39:54.510456Z","end":"2026-04-07T01:39:54.745855Z","steps":["trace[1120614596] 'agreement among raft nodes before linearized reading' (duration: 235.305974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:54.745876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.486829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T01:39:54.745904Z","caller":"traceutil/trace.go:171","msg":"trace[443980832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8568; }","duration":"235.538721ms","start":"2026-04-07T01:39:54.510356Z","end":"2026-04-07T01:39:54.745894Z","steps":["trace[443980832] 'agreement among raft nodes before linearized reading' (duration: 235.453269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:54.74602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.044925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7pfzq\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-07T01:39:54.746041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.343763ms","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-04-07T01:39:54.746054Z","caller":"traceutil/trace.go:171","msg":"trace[2115609350] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7pfzq; range_end:; response_count:1; response_revision:8568; }","duration":"229.098138ms","start":"2026-04-07T01:39:54.516947Z","end":"2026-04-07T01:39:54.746045Z","steps":["trace[2115609350] 'agreement among raft nodes before linearized reading' (duration: 229.007176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.746073Z","caller":"traceutil/trace.go:171","msg":"trace[1371900145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8568; }","duration":"236.414675ms","start":"2026-04-07T01:39:54.509649Z","end":"2026-04-07T01:39:54.746063Z","steps":["trace[1371900145] 'agreement among raft nodes before linearized reading' (duration: 236.334242ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.974685Z","caller":"traceutil/trace.go:171","msg":"trace[609108702] linearizableReadLoop","detail":"{readStateIndex:8875; appliedIndex:8874; }","duration":"209.46726ms","start":"2026-04-07T01:39:54.765187Z","end":"2026-04-07T01:39:54.974654Z","steps":["trace[609108702] 'read index received' (duration: 209.283584ms)","trace[609108702] 'applied index is now lower than readState.Index' (duration: 182.486µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:54.97512Z","caller":"traceutil/trace.go:171","msg":"trace[1699325922] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"210.499987ms","start":"2026-04-07T01:39:54.764602Z","end":"2026-04-07T01:39:54.975102Z","steps":["trace[1699325922] 'process raft request' (duration: 209.85922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:54.975996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.773926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228839"} {"level":"warn","ts":"2026-04-07T01:39:54.976019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.332763ms","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:5"} {"level":"info","ts":"2026-04-07T01:39:54.976077Z","caller":"traceutil/trace.go:171","msg":"trace[1779243738] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8569; }","duration":"210.903108ms","start":"2026-04-07T01:39:54.765154Z","end":"2026-04-07T01:39:54.976057Z","steps":["trace[1779243738] 'agreement among raft nodes before linearized reading' (duration: 210.351493ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.976152Z","caller":"traceutil/trace.go:171","msg":"trace[1525748800] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8573; }","duration":"132.460506ms","start":"2026-04-07T01:39:54.843633Z","end":"2026-04-07T01:39:54.976094Z","steps":["trace[1525748800] 'agreement among raft nodes before linearized reading' (duration: 132.25338ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.976411Z","caller":"traceutil/trace.go:171","msg":"trace[1485221010] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"211.00176ms","start":"2026-04-07T01:39:54.765371Z","end":"2026-04-07T01:39:54.976373Z","steps":["trace[1485221010] 'process raft request' (duration: 210.369914ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.976494Z","caller":"traceutil/trace.go:171","msg":"trace[1984779653] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"208.610966ms","start":"2026-04-07T01:39:54.767851Z","end":"2026-04-07T01:39:54.976462Z","steps":["trace[1984779653] 'process raft request' (duration: 207.948719ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.976538Z","caller":"traceutil/trace.go:171","msg":"trace[1019797411] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"206.206591ms","start":"2026-04-07T01:39:54.770307Z","end":"2026-04-07T01:39:54.976513Z","steps":["trace[1019797411] 'process raft request' (duration: 205.536394ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:54.976676Z","caller":"traceutil/trace.go:171","msg":"trace[1179097185] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"211.270388ms","start":"2026-04-07T01:39:54.765383Z","end":"2026-04-07T01:39:54.976653Z","steps":["trace[1179097185] 'process raft request' (duration: 210.202229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:07.515532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.886786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:40:07.515668Z","caller":"traceutil/trace.go:171","msg":"trace[51352102] linearizableReadLoop","detail":"{readStateIndex:8988; appliedIndex:8987; }","duration":"131.292874ms","start":"2026-04-07T01:40:07.384356Z","end":"2026-04-07T01:40:07.515648Z","steps":["trace[51352102] 'read index received' (duration: 59.341µs)","trace[51352102] 'applied index is now lower than readState.Index' (duration: 131.232153ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:07.515761Z","caller":"traceutil/trace.go:171","msg":"trace[329065194] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"237.893313ms","start":"2026-04-07T01:40:07.277813Z","end":"2026-04-07T01:40:07.515707Z","steps":["trace[329065194] 'process raft request' (duration: 84.71943ms)","trace[329065194] 'compare' (duration: 152.604798ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:40:07.515833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.48388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-07T01:40:07.515874Z","caller":"traceutil/trace.go:171","msg":"trace[955439345] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8677; }","duration":"131.560022ms","start":"2026-04-07T01:40:07.384299Z","end":"2026-04-07T01:40:07.515859Z","steps":["trace[955439345] 'agreement among raft nodes before linearized reading' (duration: 131.402537ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:08.584468Z","caller":"traceutil/trace.go:171","msg":"trace[312459461] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"125.429846ms","start":"2026-04-07T01:40:08.459011Z","end":"2026-04-07T01:40:08.584441Z","steps":["trace[312459461] 'process raft request' (duration: 125.23841ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:21.676149Z","caller":"traceutil/trace.go:171","msg":"trace[1534629245] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"101.010639ms","start":"2026-04-07T01:40:21.57508Z","end":"2026-04-07T01:40:21.676091Z","steps":["trace[1534629245] 'process raft request' (duration: 100.772653ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:36.359538Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002456.wal"} {"level":"info","ts":"2026-04-07T01:41:57.740333Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"14f8f2dfdaccebdb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:41:57.748422Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T01:41:57.74857Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T01:44:07.618209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8138} {"level":"info","ts":"2026-04-07T01:44:07.686597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8138,"took":"64.97233ms","hash":2965769183,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":28037120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T01:44:07.686661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2965769183,"revision":8138,"compact-revision":6075} {"level":"info","ts":"2026-04-07T01:49:07.625385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10489} {"level":"info","ts":"2026-04-07T01:49:07.72041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10489,"took":"92.001143ms","hash":955654055,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":21512192,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-07T01:49:07.720475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":955654055,"revision":10489,"compact-revision":8138}