{"level":"warn","ts":"2026-07-13T20:31:14.725492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-13T20:31:14.725573Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-07-13T20:31:14.725637Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-13T20:31:14.725654Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-13T20:31:14.725672Z","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-07-13T20:31:14.726303Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-07-13T20:31:14.726377Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-07-13T20:31:14.730554Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.960993ms"} {"level":"info","ts":"2026-07-13T20:31:14.743318Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-07-13T20:31:14.74343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-13T20:31:14.743468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-07-13T20:31:14.743483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-13T20:31:14.743501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-07-13T20:31:14.743555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-07-13T20:31:14.748983Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-13T20:31:14.750511Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-13T20:31:14.75177Z","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-07-13T20:31:14.754488Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-13T20:31:14.754685Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-13T20:31:14.754768Z","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-07-13T20:31:14.754973Z","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-07-13T20:31:14.754999Z","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-07-13T20:31:14.756672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-07-13T20:31:14.756921Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-13T20:31:14.759877Z","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-07-13T20:31:14.759972Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-13T20:31:14.760037Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-13T20:31:14.760129Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-13T20:31:14.760211Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-13T20:31:15.744749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-13T20:31:15.744807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-13T20:31:15.744831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-07-13T20:31:15.744844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-07-13T20:31:15.744852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-13T20:31:15.744862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-07-13T20:31:15.744871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-13T20:31:15.748441Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-13T20:31:15.748757Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T20:31:15.748886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T20:31:15.74906Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-13T20:31:15.749215Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-13T20:31:15.749062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T20:31:15.749801Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T20:31:15.750064Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T20:31:15.750173Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T20:31:15.752397Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-13T20:31:15.752496Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"warn","ts":"2026-07-13T20:31:29.291162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:28.963534Z","time spent":"327.622107ms","remote":"127.0.0.1:50708","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-13T20:31:41.76293Z","caller":"traceutil/trace.go:171","msg":"trace[2127622251] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"178.948181ms","start":"2026-07-13T20:31:41.583959Z","end":"2026-07-13T20:31:41.762907Z","steps":["trace[2127622251] 'process raft request' (duration: 178.48678ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:41.922773Z","caller":"traceutil/trace.go:171","msg":"trace[838910235] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"155.324913ms","start":"2026-07-13T20:31:41.767404Z","end":"2026-07-13T20:31:41.922729Z","steps":["trace[838910235] 'process raft request' (duration: 80.401236ms)","trace[838910235] 'compare' (duration: 74.800474ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:31:52.134128Z","caller":"traceutil/trace.go:171","msg":"trace[1905309008] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"533.026451ms","start":"2026-07-13T20:31:51.601074Z","end":"2026-07-13T20:31:52.1341Z","steps":["trace[1905309008] 'process raft request' (duration: 532.821636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:52.134688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.601056Z","time spent":"533.166095ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T20:31:52.140335Z","caller":"traceutil/trace.go:171","msg":"trace[385486282] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"533.757618ms","start":"2026-07-13T20:31:51.606546Z","end":"2026-07-13T20:31:52.140304Z","steps":["trace[385486282] 'process raft request' (duration: 533.446791ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:52.140559Z","caller":"traceutil/trace.go:171","msg":"trace[779922193] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"516.865549ms","start":"2026-07-13T20:31:51.623663Z","end":"2026-07-13T20:31:52.140529Z","steps":["trace[779922193] 'process raft request' (duration: 516.679515ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:52.140631Z","caller":"traceutil/trace.go:171","msg":"trace[248759613] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"510.666464ms","start":"2026-07-13T20:31:51.629943Z","end":"2026-07-13T20:31:52.140609Z","steps":["trace[248759613] 'process raft request' (duration: 510.47026ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:52.140675Z","caller":"traceutil/trace.go:171","msg":"trace[1684802559] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"509.734191ms","start":"2026-07-13T20:31:51.630908Z","end":"2026-07-13T20:31:52.140643Z","steps":["trace[1684802559] 'process raft request' (duration: 509.618048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:52.140705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.623632Z","time spent":"517.006833ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T20:31:52.140721Z","caller":"traceutil/trace.go:171","msg":"trace[604733090] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"509.792132ms","start":"2026-07-13T20:31:51.630916Z","end":"2026-07-13T20:31:52.140708Z","steps":["trace[604733090] 'process raft request' (duration: 509.67174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:52.140748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.629921Z","time spent":"510.763526ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:31:52.140783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.630899Z","time spent":"509.835784ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:31:52.140713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.606528Z","time spent":"534.025805ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:31:52.140841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.630909Z","time spent":"509.858335ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T20:31:52.140467Z","caller":"traceutil/trace.go:171","msg":"trace[456507951] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"530.655735ms","start":"2026-07-13T20:31:51.609774Z","end":"2026-07-13T20:31:52.14043Z","steps":["trace[456507951] 'process raft request' (duration: 530.418219ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:52.140971Z","caller":"traceutil/trace.go:171","msg":"trace[428824456] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"504.11767ms","start":"2026-07-13T20:31:51.636814Z","end":"2026-07-13T20:31:52.140931Z","steps":["trace[428824456] 'process raft request' (duration: 503.889003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:52.141056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.609763Z","time spent":"531.25748ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T20:31:52.140854Z","caller":"traceutil/trace.go:171","msg":"trace[245313415] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"509.808054ms","start":"2026-07-13T20:31:51.631008Z","end":"2026-07-13T20:31:52.140816Z","steps":["trace[245313415] 'process raft request' (duration: 509.637379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:52.141124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.631003Z","time spent":"510.101631ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:31:52.141236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.636783Z","time spent":"504.275664ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T20:31:52.141303Z","caller":"traceutil/trace.go:171","msg":"trace[260797235] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"493.642003ms","start":"2026-07-13T20:31:51.647651Z","end":"2026-07-13T20:31:52.141293Z","steps":["trace[260797235] 'process raft request' (duration: 493.123621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:52.141337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.647636Z","time spent":"493.684525ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T20:31:52.141639Z","caller":"traceutil/trace.go:171","msg":"trace[9811429] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"483.276709ms","start":"2026-07-13T20:31:51.658354Z","end":"2026-07-13T20:31:52.141631Z","steps":["trace[9811429] 'process raft request' (duration: 482.863069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:52.141681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.658336Z","time spent":"483.32716ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T20:31:52.14195Z","caller":"traceutil/trace.go:171","msg":"trace[859549314] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"389.970217ms","start":"2026-07-13T20:31:51.751941Z","end":"2026-07-13T20:31:52.141911Z","steps":["trace[859549314] 'process raft request' (duration: 389.716641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:52.142097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:51.751912Z","time spent":"390.098421ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T20:31:52.142242Z","caller":"traceutil/trace.go:171","msg":"trace[569460756] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:537; }","duration":"167.939851ms","start":"2026-07-13T20:31:51.974289Z","end":"2026-07-13T20:31:52.142229Z","steps":["trace[569460756] 'read index received' (duration: 159.651095ms)","trace[569460756] 'applied index is now lower than readState.Index' (duration: 8.286376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:31:52.142408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.121835ms","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-07-13T20:31:52.14248Z","caller":"traceutil/trace.go:171","msg":"trace[1981249008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"168.241718ms","start":"2026-07-13T20:31:51.97423Z","end":"2026-07-13T20:31:52.142472Z","steps":["trace[1981249008] 'agreement among raft nodes before linearized reading' (duration: 168.127566ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:58.288778Z","caller":"traceutil/trace.go:171","msg":"trace[204537496] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"194.927474ms","start":"2026-07-13T20:31:58.093825Z","end":"2026-07-13T20:31:58.288752Z","steps":["trace[204537496] 'process raft request' (duration: 194.859562ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:58.288786Z","caller":"traceutil/trace.go:171","msg":"trace[1571025222] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"421.540879ms","start":"2026-07-13T20:31:57.8672Z","end":"2026-07-13T20:31:58.288741Z","steps":["trace[1571025222] 'process raft request' (duration: 333.757672ms)","trace[1571025222] 'compare' (duration: 87.575853ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:31:58.288901Z","caller":"traceutil/trace.go:171","msg":"trace[2006970344] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"298.66973ms","start":"2026-07-13T20:31:57.990197Z","end":"2026-07-13T20:31:58.288867Z","steps":["trace[2006970344] 'read index received' (duration: 210.75074ms)","trace[2006970344] 'applied index is now lower than readState.Index' (duration: 87.91558ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:31:58.289049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:57.867167Z","time spent":"421.810015ms","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:31:58.289203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.987878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hmxlg\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-13T20:31:58.28922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.120827ms","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-07-13T20:31:58.289274Z","caller":"traceutil/trace.go:171","msg":"trace[313746030] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hmxlg; range_end:; response_count:1; response_revision:629; }","duration":"299.10461ms","start":"2026-07-13T20:31:57.99015Z","end":"2026-07-13T20:31:58.289254Z","steps":["trace[313746030] 'agreement among raft nodes before linearized reading' (duration: 298.887915ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:58.289282Z","caller":"traceutil/trace.go:171","msg":"trace[2004491295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"110.228919ms","start":"2026-07-13T20:31:58.17904Z","end":"2026-07-13T20:31:58.289269Z","steps":["trace[2004491295] 'agreement among raft nodes before linearized reading' (duration: 110.042904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:58.289391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.463743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-13T20:31:58.289455Z","caller":"traceutil/trace.go:171","msg":"trace[1162470774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"104.563615ms","start":"2026-07-13T20:31:58.184873Z","end":"2026-07-13T20:31:58.289436Z","steps":["trace[1162470774] 'agreement among raft nodes before linearized reading' (duration: 104.36776ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:58.711145Z","caller":"traceutil/trace.go:171","msg":"trace[547541641] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"413.352806ms","start":"2026-07-13T20:31:58.297769Z","end":"2026-07-13T20:31:58.711122Z","steps":["trace[547541641] 'read index received' (duration: 65.992453ms)","trace[547541641] 'applied index is now lower than readState.Index' (duration: 347.359383ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:31:58.711202Z","caller":"traceutil/trace.go:171","msg":"trace[2087293508] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"407.868756ms","start":"2026-07-13T20:31:58.303304Z","end":"2026-07-13T20:31:58.711172Z","steps":["trace[2087293508] 'process raft request' (duration: 407.721723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:58.711323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.563706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:31:58.71136Z","caller":"traceutil/trace.go:171","msg":"trace[117331727] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:632; }","duration":"409.649207ms","start":"2026-07-13T20:31:58.301698Z","end":"2026-07-13T20:31:58.711347Z","steps":["trace[117331727] 'agreement among raft nodes before linearized reading' (duration: 409.570095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:58.711403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:58.301686Z","time spent":"409.708769ms","remote":"127.0.0.1:50922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-13T20:31:58.711462Z","caller":"traceutil/trace.go:171","msg":"trace[1214004423] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"417.540594ms","start":"2026-07-13T20:31:58.293886Z","end":"2026-07-13T20:31:58.711426Z","steps":["trace[1214004423] 'process raft request' (duration: 383.658782ms)","trace[1214004423] 'compare' (duration: 33.332089ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:31:58.711402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.550601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T20:31:58.711577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.100406ms","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-07-13T20:31:58.711627Z","caller":"traceutil/trace.go:171","msg":"trace[561336688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"413.860218ms","start":"2026-07-13T20:31:58.297746Z","end":"2026-07-13T20:31:58.711606Z","steps":["trace[561336688] 'agreement among raft nodes before linearized reading' (duration: 413.50899ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:31:58.711662Z","caller":"traceutil/trace.go:171","msg":"trace[1890538012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"216.247049ms","start":"2026-07-13T20:31:58.495395Z","end":"2026-07-13T20:31:58.711642Z","steps":["trace[1890538012] 'agreement among raft nodes before linearized reading' (duration: 215.951672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:58.711683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:58.297737Z","time spent":"413.92991ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:31:58.711704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:58.303276Z","time spent":"407.987669ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:31:58.711788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:58.293876Z","time spent":"417.665588ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:31:59.32533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.594482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:31:59.32543Z","caller":"traceutil/trace.go:171","msg":"trace[1333032105] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"360.620408ms","start":"2026-07-13T20:31:58.964793Z","end":"2026-07-13T20:31:59.325414Z","steps":["trace[1333032105] 'read index received' (duration: 248.816331ms)","trace[1333032105] 'applied index is now lower than readState.Index' (duration: 111.802687ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:31:59.325458Z","caller":"traceutil/trace.go:171","msg":"trace[1975240433] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"609.190322ms","start":"2026-07-13T20:31:58.71624Z","end":"2026-07-13T20:31:59.32543Z","steps":["trace[1975240433] 'process raft request' (duration: 497.274142ms)","trace[1975240433] 'compare' (duration: 111.452779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:31:59.325571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.773701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-13T20:31:59.325608Z","caller":"traceutil/trace.go:171","msg":"trace[1944279912] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:634; }","duration":"360.853583ms","start":"2026-07-13T20:31:58.964746Z","end":"2026-07-13T20:31:59.325599Z","steps":["trace[1944279912] 'agreement among raft nodes before linearized reading' (duration: 360.767411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:31:59.325641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:58.964723Z","time spent":"360.910455ms","remote":"127.0.0.1:50708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-07-13T20:31:59.325666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:58.716215Z","time spent":"609.338045ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:31:59.383582Z","caller":"traceutil/trace.go:171","msg":"trace[1481930091] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"284.68654ms","start":"2026-07-13T20:31:59.098869Z","end":"2026-07-13T20:31:59.383556Z","steps":["trace[1481930091] 'process raft request' (duration: 283.669846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:00.798758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054008128289906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T20:32:00.961772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.309194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:32:00.961893Z","caller":"traceutil/trace.go:171","msg":"trace[256566140] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.564268521s","start":"2026-07-13T20:31:59.397602Z","end":"2026-07-13T20:32:00.961871Z","steps":["trace[256566140] 'process raft request' (duration: 886.761593ms)","trace[256566140] 'compare' (duration: 676.866323ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:32:00.961951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:59.397558Z","time spent":"1.564365944s","remote":"127.0.0.1:50708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:32:00.963241Z","caller":"traceutil/trace.go:171","msg":"trace[31996419] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"665.154417ms","start":"2026-07-13T20:32:00.298057Z","end":"2026-07-13T20:32:00.963211Z","steps":["trace[31996419] 'read index received' (duration: 665.044584ms)","trace[31996419] 'applied index is now lower than readState.Index' (duration: 108.483µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:00.963389Z","caller":"traceutil/trace.go:171","msg":"trace[950298729] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.508235035s","start":"2026-07-13T20:31:59.455138Z","end":"2026-07-13T20:32:00.963373Z","steps":["trace[950298729] 'process raft request' (duration: 1.507980839s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:00.963538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.460554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:32:00.963679Z","caller":"traceutil/trace.go:171","msg":"trace[1992247384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"665.613037ms","start":"2026-07-13T20:32:00.298016Z","end":"2026-07-13T20:32:00.963629Z","steps":["trace[1992247384] 'agreement among raft nodes before linearized reading' (duration: 665.388322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:00.963721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.115032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-13T20:32:00.96376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:32:00.297997Z","time spent":"665.750511ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-13T20:32:00.9638Z","caller":"traceutil/trace.go:171","msg":"trace[478912331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"596.204344ms","start":"2026-07-13T20:32:00.367562Z","end":"2026-07-13T20:32:00.963766Z","steps":["trace[478912331] 'agreement among raft nodes before linearized reading' (duration: 596.03207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:00.963814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.572031ms","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-07-13T20:32:00.96384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:32:00.367542Z","time spent":"596.290386ms","remote":"127.0.0.1:50954","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-07-13T20:32:00.963834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.635782ms","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-07-13T20:32:00.963873Z","caller":"traceutil/trace.go:171","msg":"trace[24515215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"198.674274ms","start":"2026-07-13T20:32:00.765184Z","end":"2026-07-13T20:32:00.963858Z","steps":["trace[24515215] 'agreement among raft nodes before linearized reading' (duration: 198.439678ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:00.963898Z","caller":"traceutil/trace.go:171","msg":"trace[1386140183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"247.738424ms","start":"2026-07-13T20:32:00.716143Z","end":"2026-07-13T20:32:00.963882Z","steps":["trace[1386140183] 'agreement among raft nodes before linearized reading' (duration: 247.532019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:00.963898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:31:59.455112Z","time spent":"1.508487352s","remote":"127.0.0.1:50922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:32:02.396734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.108551ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:32:02.396848Z","caller":"traceutil/trace.go:171","msg":"trace[1277703967] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"260.061807ms","start":"2026-07-13T20:32:02.136758Z","end":"2026-07-13T20:32:02.39682Z","steps":["trace[1277703967] 'process raft request' (duration: 250.040729ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:02.396882Z","caller":"traceutil/trace.go:171","msg":"trace[2135978320] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:657; }","duration":"160.281125ms","start":"2026-07-13T20:32:02.236581Z","end":"2026-07-13T20:32:02.396862Z","steps":["trace[2135978320] 'range keys from in-memory index tree' (duration: 160.088791ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:02.397863Z","caller":"traceutil/trace.go:171","msg":"trace[832170211] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"100.270064ms","start":"2026-07-13T20:32:02.297571Z","end":"2026-07-13T20:32:02.397841Z","steps":["trace[832170211] 'read index received' (duration: 89.373026ms)","trace[832170211] 'applied index is now lower than readState.Index' (duration: 10.895928ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:02.397917Z","caller":"traceutil/trace.go:171","msg":"trace[717476987] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"169.075412ms","start":"2026-07-13T20:32:02.228815Z","end":"2026-07-13T20:32:02.397891Z","steps":["trace[717476987] 'process raft request' (duration: 168.906658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:02.398006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.414177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:32:02.398063Z","caller":"traceutil/trace.go:171","msg":"trace[1021444209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"100.54068ms","start":"2026-07-13T20:32:02.297508Z","end":"2026-07-13T20:32:02.398049Z","steps":["trace[1021444209] 'agreement among raft nodes before linearized reading' (duration: 100.425107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:05.337361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.894752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-07-13T20:32:05.337463Z","caller":"traceutil/trace.go:171","msg":"trace[172292416] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:713; }","duration":"132.067356ms","start":"2026-07-13T20:32:05.205373Z","end":"2026-07-13T20:32:05.33744Z","steps":["trace[172292416] 'range keys from in-memory index tree' (duration: 131.616496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:16.516039Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088402287s","expected-duration":"1s"} {"level":"info","ts":"2026-07-13T20:32:16.516581Z","caller":"traceutil/trace.go:171","msg":"trace[406090648] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.089034643s","start":"2026-07-13T20:32:15.42752Z","end":"2026-07-13T20:32:16.516554Z","steps":["trace[406090648] 'process raft request' (duration: 1.088818258s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:16.516763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:32:15.427496Z","time spent":"1.089171525s","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:32:16.645413Z","caller":"traceutil/trace.go:171","msg":"trace[331066770] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:821; }","duration":"340.316477ms","start":"2026-07-13T20:32:16.305065Z","end":"2026-07-13T20:32:16.645381Z","steps":["trace[331066770] 'read index received' (duration: 211.279353ms)","trace[331066770] 'applied index is now lower than readState.Index' (duration: 129.036024ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:16.645537Z","caller":"traceutil/trace.go:171","msg":"trace[447253253] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"541.847908ms","start":"2026-07-13T20:32:16.10362Z","end":"2026-07-13T20:32:16.645467Z","steps":["trace[447253253] 'process raft request' (duration: 541.613373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:16.645629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.535632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T20:32:16.645716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:32:16.103578Z","time spent":"542.047723ms","remote":"127.0.0.1:50922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:32:16.645723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.241771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-13T20:32:16.645732Z","caller":"traceutil/trace.go:171","msg":"trace[1370274757] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:799; }","duration":"340.688676ms","start":"2026-07-13T20:32:16.305022Z","end":"2026-07-13T20:32:16.645711Z","steps":["trace[1370274757] 'agreement among raft nodes before linearized reading' (duration: 340.505242ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:16.645768Z","caller":"traceutil/trace.go:171","msg":"trace[1002845119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"162.347973ms","start":"2026-07-13T20:32:16.483408Z","end":"2026-07-13T20:32:16.645756Z","steps":["trace[1002845119] 'agreement among raft nodes before linearized reading' (duration: 162.190479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:16.645782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:32:16.305002Z","time spent":"340.768247ms","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:32:20.502374Z","caller":"traceutil/trace.go:171","msg":"trace[1585638107] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"145.191877ms","start":"2026-07-13T20:32:20.357156Z","end":"2026-07-13T20:32:20.502348Z","steps":["trace[1585638107] 'process raft request' (duration: 144.984593ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:29.427736Z","caller":"traceutil/trace.go:171","msg":"trace[750326499] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"110.901646ms","start":"2026-07-13T20:32:29.316796Z","end":"2026-07-13T20:32:29.427697Z","steps":["trace[750326499] 'process raft request' (duration: 67.629781ms)","trace[750326499] 'compare' (duration: 43.0512ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:34.56217Z","caller":"traceutil/trace.go:171","msg":"trace[1690877669] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"112.206736ms","start":"2026-07-13T20:32:34.449933Z","end":"2026-07-13T20:32:34.562139Z","steps":["trace[1690877669] 'process raft request' (duration: 112.00109ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:34.562243Z","caller":"traceutil/trace.go:171","msg":"trace[1315158784] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"104.898374ms","start":"2026-07-13T20:32:34.457303Z","end":"2026-07-13T20:32:34.562202Z","steps":["trace[1315158784] 'process raft request' (duration: 104.746291ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.033848Z","caller":"traceutil/trace.go:171","msg":"trace[1561909849] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"178.932576ms","start":"2026-07-13T20:32:34.854894Z","end":"2026-07-13T20:32:35.033826Z","steps":["trace[1561909849] 'process raft request' (duration: 178.856705ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.033933Z","caller":"traceutil/trace.go:171","msg":"trace[1790100840] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"178.486126ms","start":"2026-07-13T20:32:34.855438Z","end":"2026-07-13T20:32:35.033925Z","steps":["trace[1790100840] 'process raft request' (duration: 178.347122ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.033828Z","caller":"traceutil/trace.go:171","msg":"trace[1631417643] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"178.939807ms","start":"2026-07-13T20:32:34.854855Z","end":"2026-07-13T20:32:35.033794Z","steps":["trace[1631417643] 'process raft request' (duration: 99.892945ms)","trace[1631417643] 'compare' (duration: 78.840107ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:35.034055Z","caller":"traceutil/trace.go:171","msg":"trace[714189142] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"174.218704ms","start":"2026-07-13T20:32:34.8598Z","end":"2026-07-13T20:32:35.034019Z","steps":["trace[714189142] 'process raft request' (duration: 174.03308ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.034189Z","caller":"traceutil/trace.go:171","msg":"trace[1762866825] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1085; }","duration":"134.790591ms","start":"2026-07-13T20:32:34.899368Z","end":"2026-07-13T20:32:35.034159Z","steps":["trace[1762866825] 'read index received' (duration: 55.379951ms)","trace[1762866825] 'applied index is now lower than readState.Index' (duration: 79.4093ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:32:35.034454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.066697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-13T20:32:35.034507Z","caller":"traceutil/trace.go:171","msg":"trace[1807487145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"135.188601ms","start":"2026-07-13T20:32:34.8993Z","end":"2026-07-13T20:32:35.034488Z","steps":["trace[1807487145] 'agreement among raft nodes before linearized reading' (duration: 134.979286ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.034537Z","caller":"traceutil/trace.go:171","msg":"trace[1899404550] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"151.64904ms","start":"2026-07-13T20:32:34.882842Z","end":"2026-07-13T20:32:35.034491Z","steps":["trace[1899404550] 'process raft request' (duration: 151.116627ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.254392Z","caller":"traceutil/trace.go:171","msg":"trace[1465194852] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"213.344161ms","start":"2026-07-13T20:32:35.041019Z","end":"2026-07-13T20:32:35.254363Z","steps":["trace[1465194852] 'process raft request' (duration: 199.948433ms)","trace[1465194852] 'compare' (duration: 13.275445ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:35.259278Z","caller":"traceutil/trace.go:171","msg":"trace[348188667] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"217.409957ms","start":"2026-07-13T20:32:35.041832Z","end":"2026-07-13T20:32:35.259242Z","steps":["trace[348188667] 'process raft request' (duration: 217.13961ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.259634Z","caller":"traceutil/trace.go:171","msg":"trace[448166167] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"217.227863ms","start":"2026-07-13T20:32:35.042367Z","end":"2026-07-13T20:32:35.259595Z","steps":["trace[448166167] 'process raft request' (duration: 216.812362ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.259712Z","caller":"traceutil/trace.go:171","msg":"trace[1365295263] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"178.946947ms","start":"2026-07-13T20:32:35.080744Z","end":"2026-07-13T20:32:35.259691Z","steps":["trace[1365295263] 'process raft request' (duration: 178.758112ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.259926Z","caller":"traceutil/trace.go:171","msg":"trace[382941472] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1090; }","duration":"178.823354ms","start":"2026-07-13T20:32:35.081064Z","end":"2026-07-13T20:32:35.259888Z","steps":["trace[382941472] 'read index received' (duration: 159.981337ms)","trace[382941472] 'applied index is now lower than readState.Index' (duration: 18.840136ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:35.260015Z","caller":"traceutil/trace.go:171","msg":"trace[1050703999] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"178.186128ms","start":"2026-07-13T20:32:35.081785Z","end":"2026-07-13T20:32:35.259971Z","steps":["trace[1050703999] 'process raft request' (duration: 177.847331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:35.260148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.875524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:32:35.26018Z","caller":"traceutil/trace.go:171","msg":"trace[1768685272] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:1065; }","duration":"178.974177ms","start":"2026-07-13T20:32:35.081196Z","end":"2026-07-13T20:32:35.26017Z","steps":["trace[1768685272] 'agreement among raft nodes before linearized reading' (duration: 178.830464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:35.26023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.08875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/cert-manager/\" range_end:\"/registry/limitranges/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:32:35.260362Z","caller":"traceutil/trace.go:171","msg":"trace[1147411828] range","detail":"{range_begin:/registry/limitranges/cert-manager/; range_end:/registry/limitranges/cert-manager0; response_count:0; response_revision:1065; }","duration":"179.347896ms","start":"2026-07-13T20:32:35.080991Z","end":"2026-07-13T20:32:35.260339Z","steps":["trace[1147411828] 'agreement among raft nodes before linearized reading' (duration: 179.031239ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.432047Z","caller":"traceutil/trace.go:171","msg":"trace[555017899] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"160.783896ms","start":"2026-07-13T20:32:35.271132Z","end":"2026-07-13T20:32:35.431916Z","steps":["trace[555017899] 'process raft request' (duration: 114.749036ms)","trace[555017899] 'compare' (duration: 45.778064ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:35.495201Z","caller":"traceutil/trace.go:171","msg":"trace[634444552] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"213.389632ms","start":"2026-07-13T20:32:35.281772Z","end":"2026-07-13T20:32:35.495162Z","steps":["trace[634444552] 'process raft request' (duration: 213.080705ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.495303Z","caller":"traceutil/trace.go:171","msg":"trace[217380207] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"213.460184ms","start":"2026-07-13T20:32:35.28181Z","end":"2026-07-13T20:32:35.49527Z","steps":["trace[217380207] 'process raft request' (duration: 213.230599ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.49597Z","caller":"traceutil/trace.go:171","msg":"trace[1143688943] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"213.867503ms","start":"2026-07-13T20:32:35.282082Z","end":"2026-07-13T20:32:35.495949Z","steps":["trace[1143688943] 'process raft request' (duration: 213.022543ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.49615Z","caller":"traceutil/trace.go:171","msg":"trace[934782235] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"213.923284ms","start":"2026-07-13T20:32:35.282209Z","end":"2026-07-13T20:32:35.496132Z","steps":["trace[934782235] 'process raft request' (duration: 213.791581ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.496154Z","caller":"traceutil/trace.go:171","msg":"trace[1854504198] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1095; }","duration":"214.200041ms","start":"2026-07-13T20:32:35.281894Z","end":"2026-07-13T20:32:35.496094Z","steps":["trace[1854504198] 'read index received' (duration: 103.9037ms)","trace[1854504198] 'applied index is now lower than readState.Index' (duration: 110.295091ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:35.496178Z","caller":"traceutil/trace.go:171","msg":"trace[1025949268] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"211.435446ms","start":"2026-07-13T20:32:35.284704Z","end":"2026-07-13T20:32:35.49614Z","steps":["trace[1025949268] 'process raft request' (duration: 211.350494ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.496257Z","caller":"traceutil/trace.go:171","msg":"trace[1185393752] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"214.082748ms","start":"2026-07-13T20:32:35.282155Z","end":"2026-07-13T20:32:35.496237Z","steps":["trace[1185393752] 'process raft request' (duration: 213.74861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:35.496446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.52764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:3576"} {"level":"info","ts":"2026-07-13T20:32:35.496521Z","caller":"traceutil/trace.go:171","msg":"trace[2041956630] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:1072; }","duration":"214.658822ms","start":"2026-07-13T20:32:35.28185Z","end":"2026-07-13T20:32:35.496509Z","steps":["trace[2041956630] 'agreement among raft nodes before linearized reading' (duration: 214.424936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:35.514705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.732279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4643"} {"level":"info","ts":"2026-07-13T20:32:35.514778Z","caller":"traceutil/trace.go:171","msg":"trace[2061400530] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1073; }","duration":"232.829602ms","start":"2026-07-13T20:32:35.281931Z","end":"2026-07-13T20:32:35.514761Z","steps":["trace[2061400530] 'agreement among raft nodes before linearized reading' (duration: 232.632618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:35.514766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.278703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-13T20:32:35.514824Z","caller":"traceutil/trace.go:171","msg":"trace[414153003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"192.397306ms","start":"2026-07-13T20:32:35.322412Z","end":"2026-07-13T20:32:35.514809Z","steps":["trace[414153003] 'agreement among raft nodes before linearized reading' (duration: 192.194911ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.514689Z","caller":"traceutil/trace.go:171","msg":"trace[994662498] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"115.401153ms","start":"2026-07-13T20:32:35.399262Z","end":"2026-07-13T20:32:35.514663Z","steps":["trace[994662498] 'process raft request' (duration: 115.038714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:35.51484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.639983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T20:32:35.514891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.928714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4825"} {"level":"info","ts":"2026-07-13T20:32:35.514922Z","caller":"traceutil/trace.go:171","msg":"trace[1454928513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"217.774526ms","start":"2026-07-13T20:32:35.297128Z","end":"2026-07-13T20:32:35.514902Z","steps":["trace[1454928513] 'agreement among raft nodes before linearized reading' (duration: 217.574161ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.514958Z","caller":"traceutil/trace.go:171","msg":"trace[974417099] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1073; }","duration":"233.027848ms","start":"2026-07-13T20:32:35.281915Z","end":"2026-07-13T20:32:35.514943Z","steps":["trace[974417099] 'agreement among raft nodes before linearized reading' (duration: 232.848163ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.72071Z","caller":"traceutil/trace.go:171","msg":"trace[264322407] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"186.960047ms","start":"2026-07-13T20:32:35.533722Z","end":"2026-07-13T20:32:35.720682Z","steps":["trace[264322407] 'process raft request' (duration: 171.530382ms)","trace[264322407] 'compare' (duration: 15.255361ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:35.720994Z","caller":"traceutil/trace.go:171","msg":"trace[2072982569] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"185.955801ms","start":"2026-07-13T20:32:35.53502Z","end":"2026-07-13T20:32:35.720976Z","steps":["trace[2072982569] 'process raft request' (duration: 185.619144ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.721046Z","caller":"traceutil/trace.go:171","msg":"trace[910362907] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"181.805824ms","start":"2026-07-13T20:32:35.539217Z","end":"2026-07-13T20:32:35.721022Z","steps":["trace[910362907] 'process raft request' (duration: 181.67077ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.72115Z","caller":"traceutil/trace.go:171","msg":"trace[2140159046] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"112.3826ms","start":"2026-07-13T20:32:35.608757Z","end":"2026-07-13T20:32:35.72114Z","steps":["trace[2140159046] 'process raft request' (duration: 112.273748ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.721151Z","caller":"traceutil/trace.go:171","msg":"trace[1755563588] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"111.211582ms","start":"2026-07-13T20:32:35.609918Z","end":"2026-07-13T20:32:35.72113Z","steps":["trace[1755563588] 'process raft request' (duration: 111.148641ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.721208Z","caller":"traceutil/trace.go:171","msg":"trace[339587742] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"112.875592ms","start":"2026-07-13T20:32:35.608278Z","end":"2026-07-13T20:32:35.721154Z","steps":["trace[339587742] 'process raft request' (duration: 112.703998ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:35.721235Z","caller":"traceutil/trace.go:171","msg":"trace[973072089] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1116; }","duration":"119.815696ms","start":"2026-07-13T20:32:35.601335Z","end":"2026-07-13T20:32:35.721151Z","steps":["trace[973072089] 'read index received' (duration: 2.95721ms)","trace[973072089] 'applied index is now lower than readState.Index' (duration: 116.852115ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:32:35.721445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.460018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-webhook-7d6b59cfdc\" ","response":"range_response_count:1 size:4693"} {"level":"info","ts":"2026-07-13T20:32:35.721548Z","caller":"traceutil/trace.go:171","msg":"trace[1769416082] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-webhook-7d6b59cfdc; range_end:; response_count:1; response_revision:1095; }","duration":"187.59045ms","start":"2026-07-13T20:32:35.533942Z","end":"2026-07-13T20:32:35.721533Z","steps":["trace[1769416082] 'agreement among raft nodes before linearized reading' (duration: 187.385776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:36.282017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.841056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:32:36.282216Z","caller":"traceutil/trace.go:171","msg":"trace[1051298089] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"230.15744ms","start":"2026-07-13T20:32:36.052029Z","end":"2026-07-13T20:32:36.282187Z","steps":["trace[1051298089] 'process raft request' (duration: 74.980785ms)","trace[1051298089] 'compare' (duration: 154.58528ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:36.290769Z","caller":"traceutil/trace.go:171","msg":"trace[349557730] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"173.941598ms","start":"2026-07-13T20:32:36.1168Z","end":"2026-07-13T20:32:36.290742Z","steps":["trace[349557730] 'process raft request' (duration: 173.816465ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:36.312046Z","caller":"traceutil/trace.go:171","msg":"trace[1297778658] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"184.174891ms","start":"2026-07-13T20:32:36.127832Z","end":"2026-07-13T20:32:36.312006Z","steps":["trace[1297778658] 'process raft request' (duration: 183.428613ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:36.67098Z","caller":"traceutil/trace.go:171","msg":"trace[1548748910] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"115.472973ms","start":"2026-07-13T20:32:36.55548Z","end":"2026-07-13T20:32:36.670953Z","steps":["trace[1548748910] 'process raft request' (duration: 115.159376ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:36.730105Z","caller":"traceutil/trace.go:171","msg":"trace[1428137640] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"136.500831ms","start":"2026-07-13T20:32:36.593578Z","end":"2026-07-13T20:32:36.730079Z","steps":["trace[1428137640] 'process raft request' (duration: 136.394578ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:36.979086Z","caller":"traceutil/trace.go:171","msg":"trace[1885109949] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"166.564862ms","start":"2026-07-13T20:32:36.812492Z","end":"2026-07-13T20:32:36.979057Z","steps":["trace[1885109949] 'process raft request' (duration: 150.083612ms)","trace[1885109949] 'compare' (duration: 16.308996ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:36.979457Z","caller":"traceutil/trace.go:171","msg":"trace[191621669] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1148; }","duration":"149.254453ms","start":"2026-07-13T20:32:36.830182Z","end":"2026-07-13T20:32:36.979436Z","steps":["trace[191621669] 'read index received' (duration: 132.314832ms)","trace[191621669] 'applied index is now lower than readState.Index' (duration: 16.939021ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:32:36.979455Z","caller":"traceutil/trace.go:171","msg":"trace[749340966] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"162.409845ms","start":"2026-07-13T20:32:36.817009Z","end":"2026-07-13T20:32:36.979419Z","steps":["trace[749340966] 'process raft request' (duration: 161.999165ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:36.979525Z","caller":"traceutil/trace.go:171","msg":"trace[1018349601] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"150.075292ms","start":"2026-07-13T20:32:36.829393Z","end":"2026-07-13T20:32:36.979468Z","steps":["trace[1018349601] 'process raft request' (duration: 149.97698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:32:36.979687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.146279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-13T20:32:36.979692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.50621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-zwkbf\" ","response":"range_response_count:1 size:2963"} {"level":"info","ts":"2026-07-13T20:32:36.979718Z","caller":"traceutil/trace.go:171","msg":"trace[485981573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1121; }","duration":"132.230721ms","start":"2026-07-13T20:32:36.847476Z","end":"2026-07-13T20:32:36.979707Z","steps":["trace[485981573] 'agreement among raft nodes before linearized reading' (duration: 132.077007ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:36.979742Z","caller":"traceutil/trace.go:171","msg":"trace[1920334003] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-zwkbf; range_end:; response_count:1; response_revision:1121; }","duration":"149.594412ms","start":"2026-07-13T20:32:36.830131Z","end":"2026-07-13T20:32:36.979725Z","steps":["trace[1920334003] 'agreement among raft nodes before linearized reading' (duration: 149.390416ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:32:41.482155Z","caller":"traceutil/trace.go:171","msg":"trace[2094707140] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"123.932563ms","start":"2026-07-13T20:32:41.358176Z","end":"2026-07-13T20:32:41.482108Z","steps":["trace[2094707140] 'process raft request' (duration: 123.761489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:00.868926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.622691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:33:00.86899Z","caller":"traceutil/trace.go:171","msg":"trace[1305983981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"570.744184ms","start":"2026-07-13T20:33:00.298231Z","end":"2026-07-13T20:33:00.868975Z","steps":["trace[1305983981] 'range keys from in-memory index tree' (duration: 569.990326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:00.869018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:00.298072Z","time spent":"570.939119ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:33:00.869275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.66401ms","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-07-13T20:33:00.869317Z","caller":"traceutil/trace.go:171","msg":"trace[1180568322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"998.735492ms","start":"2026-07-13T20:32:59.87057Z","end":"2026-07-13T20:33:00.869306Z","steps":["trace[1180568322] 'range keys from in-memory index tree' (duration: 998.491035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:00.869323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.569179ms","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-07-13T20:33:00.869384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.388861ms","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-07-13T20:33:00.869413Z","caller":"traceutil/trace.go:171","msg":"trace[1165223807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1248; }","duration":"963.704171ms","start":"2026-07-13T20:32:59.905687Z","end":"2026-07-13T20:33:00.869391Z","steps":["trace[1165223807] 'range keys from in-memory index tree' (duration: 963.376863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:00.869471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:32:59.905667Z","time spent":"963.791893ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-13T20:33:00.86936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:32:59.870552Z","time spent":"998.786153ms","remote":"127.0.0.1:50954","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-07-13T20:33:00.869458Z","caller":"traceutil/trace.go:171","msg":"trace[1781866735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1248; }","duration":"942.522634ms","start":"2026-07-13T20:32:59.926918Z","end":"2026-07-13T20:33:00.86944Z","steps":["trace[1781866735] 'range keys from in-memory index tree' (duration: 942.200316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:00.870162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:32:59.926896Z","time spent":"943.1834ms","remote":"127.0.0.1:50954","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":"info","ts":"2026-07-13T20:33:01.033146Z","caller":"traceutil/trace.go:171","msg":"trace[439815625] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"597.548633ms","start":"2026-07-13T20:33:00.435569Z","end":"2026-07-13T20:33:01.033118Z","steps":["trace[439815625] 'process raft request' (duration: 597.399469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:01.033322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:00.435552Z","time spent":"597.689627ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:33:01.035593Z","caller":"traceutil/trace.go:171","msg":"trace[1668628243] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"495.132762ms","start":"2026-07-13T20:33:00.540432Z","end":"2026-07-13T20:33:01.035565Z","steps":["trace[1668628243] 'process raft request' (duration: 494.979379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:01.035834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:00.540407Z","time spent":"495.261186ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:33:01.035869Z","caller":"traceutil/trace.go:171","msg":"trace[1513626006] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"162.040681ms","start":"2026-07-13T20:33:00.873819Z","end":"2026-07-13T20:33:01.03586Z","steps":["trace[1513626006] 'process raft request' (duration: 161.709553ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:01.036095Z","caller":"traceutil/trace.go:171","msg":"trace[265446382] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"161.519359ms","start":"2026-07-13T20:33:00.874552Z","end":"2026-07-13T20:33:01.036071Z","steps":["trace[265446382] 'process raft request' (duration: 161.353855ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:01.036069Z","caller":"traceutil/trace.go:171","msg":"trace[1773205417] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"162.113982ms","start":"2026-07-13T20:33:00.873942Z","end":"2026-07-13T20:33:01.036056Z","steps":["trace[1773205417] 'process raft request' (duration: 161.889087ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:01.036077Z","caller":"traceutil/trace.go:171","msg":"trace[1601862816] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1284; }","duration":"254.635022ms","start":"2026-07-13T20:33:00.781395Z","end":"2026-07-13T20:33:01.036031Z","steps":["trace[1601862816] 'read index received' (duration: 251.54649ms)","trace[1601862816] 'applied index is now lower than readState.Index' (duration: 3.087742ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:33:01.036248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.74633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:33:01.036293Z","caller":"traceutil/trace.go:171","msg":"trace[184141437] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1253; }","duration":"163.821333ms","start":"2026-07-13T20:33:00.872462Z","end":"2026-07-13T20:33:01.036284Z","steps":["trace[184141437] 'agreement among raft nodes before linearized reading' (duration: 163.759541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:01.036272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.571443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:33:01.036332Z","caller":"traceutil/trace.go:171","msg":"trace[1614479288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"165.664285ms","start":"2026-07-13T20:33:00.870652Z","end":"2026-07-13T20:33:01.036316Z","steps":["trace[1614479288] 'agreement among raft nodes before linearized reading' (duration: 165.567713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:01.036293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.752985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:33:01.03641Z","caller":"traceutil/trace.go:171","msg":"trace[1316610311] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1253; }","duration":"255.044151ms","start":"2026-07-13T20:33:00.781352Z","end":"2026-07-13T20:33:01.036396Z","steps":["trace[1316610311] 'agreement among raft nodes before linearized reading' (duration: 254.769025ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:16.637151Z","caller":"traceutil/trace.go:171","msg":"trace[1713424302] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"284.593875ms","start":"2026-07-13T20:33:16.352517Z","end":"2026-07-13T20:33:16.637111Z","steps":["trace[1713424302] 'process raft request' (duration: 284.329919ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:22.238348Z","caller":"traceutil/trace.go:171","msg":"trace[1462628970] linearizableReadLoop","detail":"{readStateIndex:1542; appliedIndex:1541; }","duration":"300.14208ms","start":"2026-07-13T20:33:21.938184Z","end":"2026-07-13T20:33:22.238326Z","steps":["trace[1462628970] 'read index received' (duration: 299.909965ms)","trace[1462628970] 'applied index is now lower than readState.Index' (duration: 230.875µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:33:22.238393Z","caller":"traceutil/trace.go:171","msg":"trace[2061317574] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"436.306013ms","start":"2026-07-13T20:33:21.802055Z","end":"2026-07-13T20:33:22.238361Z","steps":["trace[2061317574] 'process raft request' (duration: 436.136219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:22.238544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:21.802031Z","time spent":"436.439487ms","remote":"127.0.0.1:50744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":859,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T20:33:22.238586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.375455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-13T20:33:22.238679Z","caller":"traceutil/trace.go:171","msg":"trace[672074438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1496; }","duration":"300.492509ms","start":"2026-07-13T20:33:21.938174Z","end":"2026-07-13T20:33:22.238667Z","steps":["trace[672074438] 'agreement among raft nodes before linearized reading' (duration: 300.260553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:22.238749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:21.93811Z","time spent":"300.626452ms","remote":"127.0.0.1:50954","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-07-13T20:33:22.506126Z","caller":"traceutil/trace.go:171","msg":"trace[1193843874] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1542; }","duration":"209.448373ms","start":"2026-07-13T20:33:22.296649Z","end":"2026-07-13T20:33:22.506097Z","steps":["trace[1193843874] 'read index received' (duration: 170.687894ms)","trace[1193843874] 'applied index is now lower than readState.Index' (duration: 38.759249ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:33:22.506262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.597736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:33:22.50626Z","caller":"traceutil/trace.go:171","msg":"trace[905711749] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"262.956998ms","start":"2026-07-13T20:33:22.243267Z","end":"2026-07-13T20:33:22.506224Z","steps":["trace[905711749] 'process raft request' (duration: 224.007124ms)","trace[905711749] 'compare' (duration: 38.653837ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:33:22.506324Z","caller":"traceutil/trace.go:171","msg":"trace[120110133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1497; }","duration":"209.709639ms","start":"2026-07-13T20:33:22.296601Z","end":"2026-07-13T20:33:22.506311Z","steps":["trace[120110133] 'agreement among raft nodes before linearized reading' (duration: 209.601626ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:25.000809Z","caller":"traceutil/trace.go:171","msg":"trace[35053446] linearizableReadLoop","detail":"{readStateIndex:1556; appliedIndex:1555; }","duration":"453.932138ms","start":"2026-07-13T20:33:24.546843Z","end":"2026-07-13T20:33:25.000775Z","steps":["trace[35053446] 'read index received' (duration: 453.568398ms)","trace[35053446] 'applied index is now lower than readState.Index' (duration: 362.29µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:33:25.000924Z","caller":"traceutil/trace.go:171","msg":"trace[1709480142] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"485.699172ms","start":"2026-07-13T20:33:24.515182Z","end":"2026-07-13T20:33:25.000882Z","steps":["trace[1709480142] 'process raft request' (duration: 485.369054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:25.001101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:24.515162Z","time spent":"485.838495ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:33:25.001143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.214329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T20:33:25.001177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.297706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T20:33:25.001236Z","caller":"traceutil/trace.go:171","msg":"trace[406306874] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1509; }","duration":"454.414438ms","start":"2026-07-13T20:33:24.546809Z","end":"2026-07-13T20:33:25.001224Z","steps":["trace[406306874] 'agreement among raft nodes before linearized reading' (duration: 454.153202ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:25.001243Z","caller":"traceutil/trace.go:171","msg":"trace[1836389446] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1509; }","duration":"337.396414ms","start":"2026-07-13T20:33:24.663822Z","end":"2026-07-13T20:33:25.001219Z","steps":["trace[1836389446] 'agreement among raft nodes before linearized reading' (duration: 337.21085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:25.001284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:24.546795Z","time spent":"454.47844ms","remote":"127.0.0.1:50890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":53,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:33:25.001294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:24.663789Z","time spent":"337.494267ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:33:25.397269Z","caller":"traceutil/trace.go:171","msg":"trace[1202409433] linearizableReadLoop","detail":"{readStateIndex:1557; appliedIndex:1556; }","duration":"139.920882ms","start":"2026-07-13T20:33:25.257325Z","end":"2026-07-13T20:33:25.397246Z","steps":["trace[1202409433] 'read index received' (duration: 139.689117ms)","trace[1202409433] 'applied index is now lower than readState.Index' (duration: 230.615µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:33:25.397322Z","caller":"traceutil/trace.go:171","msg":"trace[545215212] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"230.939667ms","start":"2026-07-13T20:33:25.166352Z","end":"2026-07-13T20:33:25.397292Z","steps":["trace[545215212] 'process raft request' (duration: 230.739042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:25.397574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.708152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T20:33:25.397576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.159678ms","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-07-13T20:33:25.397617Z","caller":"traceutil/trace.go:171","msg":"trace[2112119740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1510; }","duration":"100.780524ms","start":"2026-07-13T20:33:25.296826Z","end":"2026-07-13T20:33:25.397607Z","steps":["trace[2112119740] 'agreement among raft nodes before linearized reading' (duration: 100.693442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:25.397664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.252936ms","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-07-13T20:33:25.397673Z","caller":"traceutil/trace.go:171","msg":"trace[841689901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1510; }","duration":"140.366852ms","start":"2026-07-13T20:33:25.257288Z","end":"2026-07-13T20:33:25.397655Z","steps":["trace[841689901] 'agreement among raft nodes before linearized reading' (duration: 140.088205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:25.397664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.737768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-13T20:33:25.397727Z","caller":"traceutil/trace.go:171","msg":"trace[174540879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1510; }","duration":"136.340317ms","start":"2026-07-13T20:33:25.261369Z","end":"2026-07-13T20:33:25.39771Z","steps":["trace[174540879] 'agreement among raft nodes before linearized reading' (duration: 136.175093ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:25.397775Z","caller":"traceutil/trace.go:171","msg":"trace[198541359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1510; }","duration":"139.86439ms","start":"2026-07-13T20:33:25.257893Z","end":"2026-07-13T20:33:25.397758Z","steps":["trace[198541359] 'agreement among raft nodes before linearized reading' (duration: 139.629745ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:25.507218Z","caller":"traceutil/trace.go:171","msg":"trace[307141614] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"104.965002ms","start":"2026-07-13T20:33:25.402224Z","end":"2026-07-13T20:33:25.507189Z","steps":["trace[307141614] 'process raft request' (duration: 53.466534ms)","trace[307141614] 'compare' (duration: 51.370125ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:33:25.509217Z","caller":"traceutil/trace.go:171","msg":"trace[1078433329] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"104.632034ms","start":"2026-07-13T20:33:25.404571Z","end":"2026-07-13T20:33:25.509203Z","steps":["trace[1078433329] 'process raft request' (duration: 104.596893ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:25.509295Z","caller":"traceutil/trace.go:171","msg":"trace[1139638045] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"106.769575ms","start":"2026-07-13T20:33:25.402493Z","end":"2026-07-13T20:33:25.509263Z","steps":["trace[1139638045] 'process raft request' (duration: 106.531299ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:25.509354Z","caller":"traceutil/trace.go:171","msg":"trace[438286777] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"105.763611ms","start":"2026-07-13T20:33:25.403567Z","end":"2026-07-13T20:33:25.509331Z","steps":["trace[438286777] 'process raft request' (duration: 105.565607ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:34.680243Z","caller":"traceutil/trace.go:171","msg":"trace[1329854069] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"142.83053ms","start":"2026-07-13T20:33:34.537382Z","end":"2026-07-13T20:33:34.680212Z","steps":["trace[1329854069] 'process raft request' (duration: 142.683096ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:34.680564Z","caller":"traceutil/trace.go:171","msg":"trace[1072559799] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1716; }","duration":"142.072143ms","start":"2026-07-13T20:33:34.53848Z","end":"2026-07-13T20:33:34.680552Z","steps":["trace[1072559799] 'process raft request' (duration: 141.688774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.038497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054008128292413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T20:33:50.079675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.780766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:33:50.079764Z","caller":"traceutil/trace.go:171","msg":"trace[670853555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1922; }","duration":"781.925459ms","start":"2026-07-13T20:33:49.297818Z","end":"2026-07-13T20:33:50.079744Z","steps":["trace[670853555] 'range keys from in-memory index tree' (duration: 781.660904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.079846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.297783Z","time spent":"782.054303ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:33:50.079826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.719963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-13T20:33:50.079921Z","caller":"traceutil/trace.go:171","msg":"trace[1433217638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1922; }","duration":"966.912088ms","start":"2026-07-13T20:33:49.112985Z","end":"2026-07-13T20:33:50.079897Z","steps":["trace[1433217638] 'range keys from in-memory index tree' (duration: 966.521709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.080057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.112927Z","time spent":"967.113733ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-13T20:33:50.080123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.72191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-13T20:33:50.080235Z","caller":"traceutil/trace.go:171","msg":"trace[922600760] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009; range_end:; response_count:1; response_revision:1922; }","duration":"904.913694ms","start":"2026-07-13T20:33:49.175291Z","end":"2026-07-13T20:33:50.080204Z","steps":["trace[922600760] 'range keys from in-memory index tree' (duration: 904.181517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.080288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.175268Z","time spent":"905.007827ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009\" "} {"level":"info","ts":"2026-07-13T20:33:50.080505Z","caller":"traceutil/trace.go:171","msg":"trace[742258429] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"590.007578ms","start":"2026-07-13T20:33:49.490477Z","end":"2026-07-13T20:33:50.080484Z","steps":["trace[742258429] 'process raft request' (duration: 585.973603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.080629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.49045Z","time spent":"590.10796ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:33:50.081079Z","caller":"traceutil/trace.go:171","msg":"trace[1080674490] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1996; }","duration":"542.77282ms","start":"2026-07-13T20:33:49.538265Z","end":"2026-07-13T20:33:50.081038Z","steps":["trace[1080674490] 'read index received' (duration: 538.199164ms)","trace[1080674490] 'applied index is now lower than readState.Index' (duration: 4.569536ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:33:50.081235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.958175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-13T20:33:50.081401Z","caller":"traceutil/trace.go:171","msg":"trace[1850077185] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009; range_end:; response_count:1; response_revision:1924; }","duration":"543.155449ms","start":"2026-07-13T20:33:49.538229Z","end":"2026-07-13T20:33:50.081385Z","steps":["trace[1850077185] 'agreement among raft nodes before linearized reading' (duration: 542.896393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.081454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.380053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-13T20:33:50.081499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.53821Z","time spent":"543.254082ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009\" "} {"level":"warn","ts":"2026-07-13T20:33:50.081665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.928485ms","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-07-13T20:33:50.081521Z","caller":"traceutil/trace.go:171","msg":"trace[437139697] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1924; }","duration":"502.467496ms","start":"2026-07-13T20:33:49.579039Z","end":"2026-07-13T20:33:50.081506Z","steps":["trace[437139697] 'agreement among raft nodes before linearized reading' (duration: 502.367583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.081806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.881084ms","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-07-13T20:33:50.081843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.579028Z","time spent":"502.779993ms","remote":"127.0.0.1:51182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":5,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:33:50.081904Z","caller":"traceutil/trace.go:171","msg":"trace[2138464965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1924; }","duration":"404.042388ms","start":"2026-07-13T20:33:49.677839Z","end":"2026-07-13T20:33:50.081882Z","steps":["trace[2138464965] 'agreement among raft nodes before linearized reading' (duration: 403.69959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.081962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.677805Z","time spent":"404.14496ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-13T20:33:50.081996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.427186ms","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-07-13T20:33:50.082031Z","caller":"traceutil/trace.go:171","msg":"trace[1614088401] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1924; }","duration":"262.459147ms","start":"2026-07-13T20:33:49.819559Z","end":"2026-07-13T20:33:50.082019Z","steps":["trace[1614088401] 'agreement among raft nodes before linearized reading' (duration: 262.377295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.082047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.741839ms","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-07-13T20:33:50.082093Z","caller":"traceutil/trace.go:171","msg":"trace[2040769405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1924; }","duration":"400.81164ms","start":"2026-07-13T20:33:49.681268Z","end":"2026-07-13T20:33:50.08208Z","steps":["trace[2040769405] 'agreement among raft nodes before linearized reading' (duration: 400.671727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.082138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.68125Z","time spent":"400.879953ms","remote":"127.0.0.1:50954","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-07-13T20:33:50.08213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.041054ms","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-07-13T20:33:50.082207Z","caller":"traceutil/trace.go:171","msg":"trace[427681897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1924; }","duration":"406.161907ms","start":"2026-07-13T20:33:49.676032Z","end":"2026-07-13T20:33:50.082194Z","steps":["trace[427681897] 'agreement among raft nodes before linearized reading' (duration: 405.903631ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:50.081735Z","caller":"traceutil/trace.go:171","msg":"trace[1829001959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1924; }","duration":"404.008407ms","start":"2026-07-13T20:33:49.677709Z","end":"2026-07-13T20:33:50.081718Z","steps":["trace[1829001959] 'agreement among raft nodes before linearized reading' (duration: 403.835433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.08225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.676Z","time spent":"406.239248ms","remote":"127.0.0.1:50954","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-07-13T20:33:50.082286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:49.677698Z","time spent":"404.57819ms","remote":"127.0.0.1:50954","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-07-13T20:33:50.235149Z","caller":"traceutil/trace.go:171","msg":"trace[905611746] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"149.020665ms","start":"2026-07-13T20:33:50.086097Z","end":"2026-07-13T20:33:50.235117Z","steps":["trace[905611746] 'process raft request' (duration: 119.837681ms)","trace[905611746] 'compare' (duration: 29.050151ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:33:50.496777Z","caller":"traceutil/trace.go:171","msg":"trace[118930957] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"410.09635ms","start":"2026-07-13T20:33:50.08663Z","end":"2026-07-13T20:33:50.496727Z","steps":["trace[118930957] 'process raft request' (duration: 409.867404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.497059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:50.086614Z","time spent":"410.293874ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:33:50.496809Z","caller":"traceutil/trace.go:171","msg":"trace[489866494] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"410.083509ms","start":"2026-07-13T20:33:50.086697Z","end":"2026-07-13T20:33:50.49678Z","steps":["trace[489866494] 'process raft request' (duration: 409.971076ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:50.497257Z","caller":"traceutil/trace.go:171","msg":"trace[578860850] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"410.097828ms","start":"2026-07-13T20:33:50.08714Z","end":"2026-07-13T20:33:50.497238Z","steps":["trace[578860850] 'process raft request' (duration: 409.573546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.497447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:50.087122Z","time spent":"410.207601ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:33:50.497671Z","caller":"traceutil/trace.go:171","msg":"trace[1919963678] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:1998; }","duration":"407.758133ms","start":"2026-07-13T20:33:50.089893Z","end":"2026-07-13T20:33:50.497651Z","steps":["trace[1919963678] 'read index received' (duration: 116.065341ms)","trace[1919963678] 'applied index is now lower than readState.Index' (duration: 291.691072ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:33:50.497637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:50.086679Z","time spent":"410.699604ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:33:50.497717Z","caller":"traceutil/trace.go:171","msg":"trace[302070546] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"408.754978ms","start":"2026-07-13T20:33:50.088923Z","end":"2026-07-13T20:33:50.497678Z","steps":["trace[302070546] 'process raft request' (duration: 408.463131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.497787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.880376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:33:50.497777Z","caller":"traceutil/trace.go:171","msg":"trace[1522649566] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"382.709187ms","start":"2026-07-13T20:33:50.115022Z","end":"2026-07-13T20:33:50.497731Z","steps":["trace[1522649566] 'process raft request' (duration: 382.552073ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:50.49784Z","caller":"traceutil/trace.go:171","msg":"trace[1428383777] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1931; }","duration":"407.951748ms","start":"2026-07-13T20:33:50.089869Z","end":"2026-07-13T20:33:50.49782Z","steps":["trace[1428383777] 'agreement among raft nodes before linearized reading' (duration: 407.861716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.497851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:50.088912Z","time spent":"408.87262ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:33:50.497895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:50.089855Z","time spent":"408.029239ms","remote":"127.0.0.1:44860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:33:50.497676Z","caller":"traceutil/trace.go:171","msg":"trace[746520979] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"409.115565ms","start":"2026-07-13T20:33:50.088509Z","end":"2026-07-13T20:33:50.497624Z","steps":["trace[746520979] 'process raft request' (duration: 408.629404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:50.497992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:50.115007Z","time spent":"382.8793ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:33:50.498001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:50.0885Z","time spent":"409.440464ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:33:51.588776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054008128292426,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-13T20:33:51.659474Z","caller":"traceutil/trace.go:171","msg":"trace[1131511094] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"698.35165ms","start":"2026-07-13T20:33:50.961094Z","end":"2026-07-13T20:33:51.659445Z","steps":["trace[1131511094] 'process raft request' (duration: 698.163465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:51.65967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:50.961061Z","time spent":"698.497673ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:33:51.663706Z","caller":"traceutil/trace.go:171","msg":"trace[319278837] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2006; }","duration":"575.862437ms","start":"2026-07-13T20:33:51.087823Z","end":"2026-07-13T20:33:51.663686Z","steps":["trace[319278837] 'read index received' (duration: 571.418563ms)","trace[319278837] 'applied index is now lower than readState.Index' (duration: 4.442984ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:33:51.663995Z","caller":"traceutil/trace.go:171","msg":"trace[370952451] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"515.504531ms","start":"2026-07-13T20:33:51.148477Z","end":"2026-07-13T20:33:51.663981Z","steps":["trace[370952451] 'process raft request' (duration: 515.117873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:51.664086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:51.148432Z","time spent":"515.601824ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:33:51.664392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.557524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-07-13T20:33:51.664423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.793497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:33:51.664458Z","caller":"traceutil/trace.go:171","msg":"trace[1153030052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1934; }","duration":"367.865819ms","start":"2026-07-13T20:33:51.296583Z","end":"2026-07-13T20:33:51.664448Z","steps":["trace[1153030052] 'agreement among raft nodes before linearized reading' (duration: 367.800698ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:33:51.664459Z","caller":"traceutil/trace.go:171","msg":"trace[1603242094] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009; range_end:; response_count:1; response_revision:1934; }","duration":"576.654805ms","start":"2026-07-13T20:33:51.087786Z","end":"2026-07-13T20:33:51.664441Z","steps":["trace[1603242094] 'agreement among raft nodes before linearized reading' (duration: 576.476661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:51.664485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:51.296562Z","time spent":"367.91709ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:33:51.664505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:51.087769Z","time spent":"576.722607ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-214bec0b-ec28-40fa-92ea-0cf6ddf2a009\" "} {"level":"warn","ts":"2026-07-13T20:33:51.66462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.726561ms","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-07-13T20:33:51.664721Z","caller":"traceutil/trace.go:171","msg":"trace[244881087] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1934; }","duration":"574.865175ms","start":"2026-07-13T20:33:51.089823Z","end":"2026-07-13T20:33:51.664688Z","steps":["trace[244881087] 'agreement among raft nodes before linearized reading' (duration: 574.528747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:33:51.664778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:33:51.089805Z","time spent":"574.959677ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-13T20:34:04.277917Z","caller":"traceutil/trace.go:171","msg":"trace[2131180691] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"121.637762ms","start":"2026-07-13T20:34:04.156249Z","end":"2026-07-13T20:34:04.277887Z","steps":["trace[2131180691] 'process raft request' (duration: 121.474348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:04.945217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.872546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:34:04.945447Z","caller":"traceutil/trace.go:171","msg":"trace[926686373] linearizableReadLoop","detail":"{readStateIndex:2114; appliedIndex:2113; }","duration":"648.067267ms","start":"2026-07-13T20:34:04.297334Z","end":"2026-07-13T20:34:04.945401Z","steps":["trace[926686373] 'read index received' (duration: 108.824862ms)","trace[926686373] 'applied index is now lower than readState.Index' (duration: 539.240095ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:34:04.945459Z","caller":"traceutil/trace.go:171","msg":"trace[826649195] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"788.899089ms","start":"2026-07-13T20:34:04.156524Z","end":"2026-07-13T20:34:04.945423Z","steps":["trace[826649195] 'process raft request' (duration: 249.728717ms)","trace[826649195] 'compare' (duration: 538.753753ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:34:04.94593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.156495Z","time spent":"789.080565ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:34:05.067415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.068132ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-13T20:34:05.06743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.069477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:34:05.067598Z","caller":"traceutil/trace.go:171","msg":"trace[644313073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2038; }","duration":"770.259441ms","start":"2026-07-13T20:34:04.297292Z","end":"2026-07-13T20:34:05.067552Z","steps":["trace[644313073] 'agreement among raft nodes before linearized reading' (duration: 648.299373ms)","trace[644313073] 'range keys from in-memory index tree' (duration: 121.779224ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:34:05.067747Z","caller":"traceutil/trace.go:171","msg":"trace[1162166658] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"482.036988ms","start":"2026-07-13T20:34:04.585686Z","end":"2026-07-13T20:34:05.067723Z","steps":["trace[1162166658] 'process raft request' (duration: 481.871085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:05.067766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.297272Z","time spent":"770.426815ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T20:34:05.067881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.585669Z","time spent":"482.135801ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:34:05.080142Z","caller":"traceutil/trace.go:171","msg":"trace[1345944272] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2114; }","duration":"134.538363ms","start":"2026-07-13T20:34:04.945559Z","end":"2026-07-13T20:34:05.080098Z","steps":["trace[1345944272] 'read index received' (duration: 107.493801ms)","trace[1345944272] 'applied index is now lower than readState.Index' (duration: 27.043542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:34:05.080417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.53897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:34:05.080496Z","caller":"traceutil/trace.go:171","msg":"trace[1333575837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2040; }","duration":"473.644373ms","start":"2026-07-13T20:34:04.606834Z","end":"2026-07-13T20:34:05.080478Z","steps":["trace[1333575837] 'agreement among raft nodes before linearized reading' (duration: 473.495859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:05.080532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.696833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-13T20:34:05.080528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.967728ms","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-07-13T20:34:05.080547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.606825Z","time spent":"473.705924ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:34:05.080577Z","caller":"traceutil/trace.go:171","msg":"trace[973037140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2040; }","duration":"473.767796ms","start":"2026-07-13T20:34:04.606797Z","end":"2026-07-13T20:34:05.080565Z","steps":["trace[973037140] 'agreement among raft nodes before linearized reading' (duration: 473.615272ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:05.080598Z","caller":"traceutil/trace.go:171","msg":"trace[434864980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2040; }","duration":"783.114951ms","start":"2026-07-13T20:34:04.297467Z","end":"2026-07-13T20:34:05.080581Z","steps":["trace[434864980] 'agreement among raft nodes before linearized reading' (duration: 782.912926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:05.080613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.606785Z","time spent":"473.820126ms","remote":"127.0.0.1:50954","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":"warn","ts":"2026-07-13T20:34:05.080641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.297432Z","time spent":"783.198643ms","remote":"127.0.0.1:50954","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":"warn","ts":"2026-07-13T20:34:05.080536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.84233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-jq6hf\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-07-13T20:34:05.080776Z","caller":"traceutil/trace.go:171","msg":"trace[1763192813] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-jq6hf; range_end:; response_count:1; response_revision:2040; }","duration":"540.077045ms","start":"2026-07-13T20:34:04.540679Z","end":"2026-07-13T20:34:05.080756Z","steps":["trace[1763192813] 'agreement among raft nodes before linearized reading' (duration: 539.749087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:05.080827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.540627Z","time spent":"540.183687ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":5875,"request content":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-jq6hf\" "} {"level":"warn","ts":"2026-07-13T20:34:05.080867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.538537ms","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-07-13T20:34:05.080958Z","caller":"traceutil/trace.go:171","msg":"trace[431987381] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"490.594658ms","start":"2026-07-13T20:34:04.590345Z","end":"2026-07-13T20:34:05.080939Z","steps":["trace[431987381] 'process raft request' (duration: 489.612315ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:05.081012Z","caller":"traceutil/trace.go:171","msg":"trace[1109446257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2040; }","duration":"466.718481ms","start":"2026-07-13T20:34:04.614278Z","end":"2026-07-13T20:34:05.080996Z","steps":["trace[1109446257] 'agreement among raft nodes before linearized reading' (duration: 466.482946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:05.081042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.880702ms","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-07-13T20:34:05.08108Z","caller":"traceutil/trace.go:171","msg":"trace[158739584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2040; }","duration":"468.949114ms","start":"2026-07-13T20:34:04.612121Z","end":"2026-07-13T20:34:05.081071Z","steps":["trace[158739584] 'agreement among raft nodes before linearized reading' (duration: 468.849702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:05.081086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.614253Z","time spent":"466.819643ms","remote":"127.0.0.1:50954","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-07-13T20:34:05.081108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.612104Z","time spent":"468.998045ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-13T20:34:05.080861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.291722ms","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-07-13T20:34:05.08122Z","caller":"traceutil/trace.go:171","msg":"trace[426975406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2040; }","duration":"466.67414ms","start":"2026-07-13T20:34:04.614538Z","end":"2026-07-13T20:34:05.081212Z","steps":["trace[426975406] 'agreement among raft nodes before linearized reading' (duration: 466.107787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:05.081227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.075449ms","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-07-13T20:34:05.081247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.614529Z","time spent":"466.710541ms","remote":"127.0.0.1:50954","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-07-13T20:34:05.081078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.590321Z","time spent":"490.679751ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:34:05.081278Z","caller":"traceutil/trace.go:171","msg":"trace[1287975786] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2040; }","duration":"324.152772ms","start":"2026-07-13T20:34:04.757109Z","end":"2026-07-13T20:34:05.081262Z","steps":["trace[1287975786] 'agreement among raft nodes before linearized reading' (duration: 324.000508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:05.081319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:04.757091Z","time spent":"324.216263ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-13T20:34:07.239683Z","caller":"traceutil/trace.go:171","msg":"trace[1742184710] linearizableReadLoop","detail":"{readStateIndex:2141; appliedIndex:2140; }","duration":"101.954541ms","start":"2026-07-13T20:34:07.137575Z","end":"2026-07-13T20:34:07.23953Z","steps":["trace[1742184710] 'read index received' (duration: 96.136845ms)","trace[1742184710] 'applied index is now lower than readState.Index' (duration: 5.816226ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:34:07.239809Z","caller":"traceutil/trace.go:171","msg":"trace[1119972804] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"132.004003ms","start":"2026-07-13T20:34:07.107623Z","end":"2026-07-13T20:34:07.239627Z","steps":["trace[1119972804] 'process raft request' (duration: 126.117436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:07.239909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.034805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-13T20:34:07.239967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.938022ms","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-07-13T20:34:07.240031Z","caller":"traceutil/trace.go:171","msg":"trace[120819929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2064; }","duration":"102.009173ms","start":"2026-07-13T20:34:07.138005Z","end":"2026-07-13T20:34:07.240014Z","steps":["trace[120819929] 'agreement among raft nodes before linearized reading' (duration: 101.8828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:07.240053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.055864ms","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-07-13T20:34:07.240081Z","caller":"traceutil/trace.go:171","msg":"trace[2028415432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2064; }","duration":"102.102376ms","start":"2026-07-13T20:34:07.137971Z","end":"2026-07-13T20:34:07.240073Z","steps":["trace[2028415432] 'agreement among raft nodes before linearized reading' (duration: 102.028194ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:07.239973Z","caller":"traceutil/trace.go:171","msg":"trace[356256676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2064; }","duration":"102.123516ms","start":"2026-07-13T20:34:07.13783Z","end":"2026-07-13T20:34:07.239954Z","steps":["trace[356256676] 'agreement among raft nodes before linearized reading' (duration: 101.993302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:07.239825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.225217ms","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-07-13T20:34:07.240155Z","caller":"traceutil/trace.go:171","msg":"trace[2032266241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2064; }","duration":"102.602967ms","start":"2026-07-13T20:34:07.137537Z","end":"2026-07-13T20:34:07.24014Z","steps":["trace[2032266241] 'agreement among raft nodes before linearized reading' (duration: 102.141216ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:08.831041Z","caller":"traceutil/trace.go:171","msg":"trace[1099159490] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"542.105331ms","start":"2026-07-13T20:34:08.288916Z","end":"2026-07-13T20:34:08.831021Z","steps":["trace[1099159490] 'process raft request' (duration: 542.002479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:08.83115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:08.288884Z","time spent":"542.210065ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:34:41.748394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.248992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:34:41.748748Z","caller":"traceutil/trace.go:171","msg":"trace[392867681] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"543.963414ms","start":"2026-07-13T20:34:41.204765Z","end":"2026-07-13T20:34:41.748729Z","steps":["trace[392867681] 'process raft request' (duration: 240.144049ms)","trace[392867681] 'compare' (duration: 303.024997ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:34:41.748805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:41.204738Z","time spent":"544.034496ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:34:41.878287Z","caller":"traceutil/trace.go:171","msg":"trace[186040229] linearizableReadLoop","detail":"{readStateIndex:2334; appliedIndex:2332; }","duration":"549.709009ms","start":"2026-07-13T20:34:41.328531Z","end":"2026-07-13T20:34:41.87824Z","steps":["trace[186040229] 'read index received' (duration: 116.392759ms)","trace[186040229] 'applied index is now lower than readState.Index' (duration: 433.31478ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:34:41.878321Z","caller":"traceutil/trace.go:171","msg":"trace[1855176800] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"657.630679ms","start":"2026-07-13T20:34:41.220661Z","end":"2026-07-13T20:34:41.878292Z","steps":["trace[1855176800] 'process raft request' (duration: 642.763943ms)","trace[1855176800] 'compare' (duration: 14.538139ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:34:41.878469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:41.220632Z","time spent":"657.758112ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:34:41.878565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.000855ms","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-07-13T20:34:41.87862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.608004ms","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-07-13T20:34:41.878651Z","caller":"traceutil/trace.go:171","msg":"trace[183046850] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2249; }","duration":"550.127859ms","start":"2026-07-13T20:34:41.328502Z","end":"2026-07-13T20:34:41.87863Z","steps":["trace[183046850] 'agreement among raft nodes before linearized reading' (duration: 549.876913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:41.878724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:41.328492Z","time spent":"550.221941ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-13T20:34:41.878805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.605444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-13T20:34:41.878773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.831766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-13T20:34:41.878659Z","caller":"traceutil/trace.go:171","msg":"trace[961740485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2249; }","duration":"447.703306ms","start":"2026-07-13T20:34:41.430944Z","end":"2026-07-13T20:34:41.878648Z","steps":["trace[961740485] 'agreement among raft nodes before linearized reading' (duration: 447.509422ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:41.878842Z","caller":"traceutil/trace.go:171","msg":"trace[183626566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2249; }","duration":"447.661205ms","start":"2026-07-13T20:34:41.431172Z","end":"2026-07-13T20:34:41.878834Z","steps":["trace[183626566] 'agreement among raft nodes before linearized reading' (duration: 447.576823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:41.878882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:41.431161Z","time spent":"447.714048ms","remote":"127.0.0.1:50954","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-07-13T20:34:41.878884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:41.430922Z","time spent":"447.948412ms","remote":"127.0.0.1:50954","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-07-13T20:34:41.878889Z","caller":"traceutil/trace.go:171","msg":"trace[785527188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2249; }","duration":"473.98516ms","start":"2026-07-13T20:34:41.404876Z","end":"2026-07-13T20:34:41.878861Z","steps":["trace[785527188] 'agreement among raft nodes before linearized reading' (duration: 473.584141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:41.878943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:41.404866Z","time spent":"474.067663ms","remote":"127.0.0.1:50954","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":"warn","ts":"2026-07-13T20:34:41.87873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.905378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T20:34:41.878998Z","caller":"traceutil/trace.go:171","msg":"trace[1361110589] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2249; }","duration":"298.201725ms","start":"2026-07-13T20:34:41.580784Z","end":"2026-07-13T20:34:41.878985Z","steps":["trace[1361110589] 'agreement among raft nodes before linearized reading' (duration: 297.912867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:41.879054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.26289ms","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-07-13T20:34:41.879086Z","caller":"traceutil/trace.go:171","msg":"trace[188525330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2249; }","duration":"457.338242ms","start":"2026-07-13T20:34:41.42174Z","end":"2026-07-13T20:34:41.879079Z","steps":["trace[188525330] 'agreement among raft nodes before linearized reading' (duration: 457.25759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:41.879112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:41.421715Z","time spent":"457.391514ms","remote":"127.0.0.1:50954","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-07-13T20:34:41.879609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.122481ms","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-07-13T20:34:41.87975Z","caller":"traceutil/trace.go:171","msg":"trace[301888252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2249; }","duration":"467.300455ms","start":"2026-07-13T20:34:41.412425Z","end":"2026-07-13T20:34:41.879725Z","steps":["trace[301888252] 'agreement among raft nodes before linearized reading' (duration: 466.63114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:41.87981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:41.412414Z","time spent":"467.382377ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-13T20:34:42.48881Z","caller":"traceutil/trace.go:171","msg":"trace[913516635] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"117.243978ms","start":"2026-07-13T20:34:42.371539Z","end":"2026-07-13T20:34:42.488783Z","steps":["trace[913516635] 'process raft request' (duration: 117.009753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:43.608724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.738221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:34:43.608857Z","caller":"traceutil/trace.go:171","msg":"trace[1587937304] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2342; }","duration":"425.953728ms","start":"2026-07-13T20:34:43.182883Z","end":"2026-07-13T20:34:43.608837Z","steps":["trace[1587937304] 'read index received' (duration: 119.983852ms)","trace[1587937304] 'applied index is now lower than readState.Index' (duration: 305.968166ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:34:43.60897Z","caller":"traceutil/trace.go:171","msg":"trace[118344551] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"519.450292ms","start":"2026-07-13T20:34:43.089506Z","end":"2026-07-13T20:34:43.608956Z","steps":["trace[118344551] 'process raft request' (duration: 213.363553ms)","trace[118344551] 'compare' (duration: 305.494985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:34:43.609033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:43.089459Z","time spent":"519.539644ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:34:43.609411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.540502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-07-13T20:34:43.609447Z","caller":"traceutil/trace.go:171","msg":"trace[662700555] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2258; }","duration":"426.625924ms","start":"2026-07-13T20:34:43.182811Z","end":"2026-07-13T20:34:43.609437Z","steps":["trace[662700555] 'agreement among raft nodes before linearized reading' (duration: 426.43911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:43.609413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.154429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T20:34:43.60953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:43.18277Z","time spent":"426.750837ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-13T20:34:43.609555Z","caller":"traceutil/trace.go:171","msg":"trace[306871520] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2258; }","duration":"201.336253ms","start":"2026-07-13T20:34:43.408193Z","end":"2026-07-13T20:34:43.609529Z","steps":["trace[306871520] 'agreement among raft nodes before linearized reading' (duration: 201.152009ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:43.878967Z","caller":"traceutil/trace.go:171","msg":"trace[923590110] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"124.18505ms","start":"2026-07-13T20:34:43.754751Z","end":"2026-07-13T20:34:43.878936Z","steps":["trace[923590110] 'process raft request' (duration: 124.033707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:44.797246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054008128293160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T20:34:45.198146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134483318s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:34:45.198284Z","caller":"traceutil/trace.go:171","msg":"trace[524001935] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"1.267522785s","start":"2026-07-13T20:34:43.930739Z","end":"2026-07-13T20:34:45.198262Z","steps":["trace[524001935] 'process raft request' (duration: 132.820932ms)","trace[524001935] 'compare' (duration: 1.134362425s)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:34:45.198354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:43.930699Z","time spent":"1.267621997s","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:34:45.297745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054008128293160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T20:34:45.744727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.681038423s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-13T20:34:45.994476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.400995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T20:34:45.995005Z","caller":"traceutil/trace.go:171","msg":"trace[1097939918] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"2.06403644s","start":"2026-07-13T20:34:43.930921Z","end":"2026-07-13T20:34:45.994957Z","steps":["trace[1097939918] 'process raft request' (duration: 1.814059171s)","trace[1097939918] 'compare' (duration: 249.250832ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:34:45.995077Z","caller":"traceutil/trace.go:171","msg":"trace[714748292] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"2.063522678s","start":"2026-07-13T20:34:43.93153Z","end":"2026-07-13T20:34:45.995053Z","steps":["trace[714748292] 'process raft request' (duration: 2.063416865s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:45.995242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:43.9309Z","time spent":"2.064213733s","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:34:45.995258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:43.931516Z","time spent":"2.063698831s","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:34:45.99544Z","caller":"traceutil/trace.go:171","msg":"trace[1199980498] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"2.062456792s","start":"2026-07-13T20:34:43.932958Z","end":"2026-07-13T20:34:45.995415Z","steps":["trace[1199980498] 'process raft request' (duration: 2.062058183s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:45.995649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:43.932943Z","time spent":"2.062546394s","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:34:46.150351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.00548ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-13T20:34:46.150653Z","caller":"traceutil/trace.go:171","msg":"trace[300506744] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"1.930017499s","start":"2026-07-13T20:34:44.220619Z","end":"2026-07-13T20:34:46.150637Z","steps":["trace[300506744] 'process raft request' (duration: 1.929832535s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.150748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:44.220595Z","time spent":"1.930091721s","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:34:46.150857Z","caller":"traceutil/trace.go:171","msg":"trace[492945855] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"1.797990444s","start":"2026-07-13T20:34:44.352846Z","end":"2026-07-13T20:34:46.150836Z","steps":["trace[492945855] 'process raft request' (duration: 1.797753249s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.150991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:44.352816Z","time spent":"1.798106217s","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:34:46.151274Z","caller":"traceutil/trace.go:171","msg":"trace[1032520364] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"1.081728366s","start":"2026-07-13T20:34:45.069514Z","end":"2026-07-13T20:34:46.151242Z","steps":["trace[1032520364] 'process raft request' (duration: 1.081242964s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.151475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:45.069452Z","time spent":"1.081913611s","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:34:46.151459Z","caller":"traceutil/trace.go:171","msg":"trace[556593385] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"535.478597ms","start":"2026-07-13T20:34:45.615941Z","end":"2026-07-13T20:34:46.151419Z","steps":["trace[556593385] 'process raft request' (duration: 535.234421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.151652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:45.615924Z","time spent":"535.62641ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:34:46.151487Z","caller":"traceutil/trace.go:171","msg":"trace[964984198] linearizableReadLoop","detail":"{readStateIndex:2354; appliedIndex:2347; }","duration":"1.854427803s","start":"2026-07-13T20:34:44.296996Z","end":"2026-07-13T20:34:46.151423Z","steps":["trace[964984198] 'read index received' (duration: 1.44800159s)","trace[964984198] 'applied index is now lower than readState.Index' (duration: 406.425113ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:34:46.151762Z","caller":"traceutil/trace.go:171","msg":"trace[1950617951] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"267.265603ms","start":"2026-07-13T20:34:45.884472Z","end":"2026-07-13T20:34:46.151737Z","steps":["trace[1950617951] 'process raft request' (duration: 266.912645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.151902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854905055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:34:46.151971Z","caller":"traceutil/trace.go:171","msg":"trace[252479672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2270; }","duration":"1.855015347s","start":"2026-07-13T20:34:44.296938Z","end":"2026-07-13T20:34:46.151953Z","steps":["trace[252479672] 'agreement among raft nodes before linearized reading' (duration: 1.854624388s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.152007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:44.296917Z","time spent":"1.855082588s","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-13T20:34:46.152425Z","caller":"traceutil/trace.go:171","msg":"trace[2115469469] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"200.485293ms","start":"2026-07-13T20:34:45.951928Z","end":"2026-07-13T20:34:46.152414Z","steps":["trace[2115469469] 'process raft request' (duration: 200.401951ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:46.152448Z","caller":"traceutil/trace.go:171","msg":"trace[872467304] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"259.567043ms","start":"2026-07-13T20:34:45.892852Z","end":"2026-07-13T20:34:46.152419Z","steps":["trace[872467304] 'process raft request' (duration: 259.411879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.153068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.894537ms","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-07-13T20:34:46.153173Z","caller":"traceutil/trace.go:171","msg":"trace[1202710415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2273; }","duration":"244.06046ms","start":"2026-07-13T20:34:45.909095Z","end":"2026-07-13T20:34:46.153156Z","steps":["trace[1202710415] 'agreement among raft nodes before linearized reading' (duration: 243.612419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.15277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330992337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T20:34:46.153298Z","caller":"traceutil/trace.go:171","msg":"trace[1759005449] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2273; }","duration":"1.33154314s","start":"2026-07-13T20:34:44.821721Z","end":"2026-07-13T20:34:46.153265Z","steps":["trace[1759005449] 'agreement among raft nodes before linearized reading' (duration: 1.330903576s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.153369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:44.821698Z","time spent":"1.331651162s","remote":"127.0.0.1:51136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:34:46.152829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251187213s","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-07-13T20:34:46.153494Z","caller":"traceutil/trace.go:171","msg":"trace[103246120] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2273; }","duration":"1.251871139s","start":"2026-07-13T20:34:44.901591Z","end":"2026-07-13T20:34:46.153462Z","steps":["trace[103246120] 'agreement among raft nodes before linearized reading' (duration: 1.251118421s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.153532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:44.901577Z","time spent":"1.25194258s","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-13T20:34:46.152885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281713446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:34:46.153778Z","caller":"traceutil/trace.go:171","msg":"trace[1684355509] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2273; }","duration":"1.28272254s","start":"2026-07-13T20:34:44.871046Z","end":"2026-07-13T20:34:46.153769Z","steps":["trace[1684355509] 'agreement among raft nodes before linearized reading' (duration: 1.281760937s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.153807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:44.87102Z","time spent":"1.282776482s","remote":"127.0.0.1:54788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-07-13T20:34:46.15292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.665849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T20:34:46.153892Z","caller":"traceutil/trace.go:171","msg":"trace[1953766101] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2273; }","duration":"729.716934ms","start":"2026-07-13T20:34:45.424153Z","end":"2026-07-13T20:34:46.15387Z","steps":["trace[1953766101] 'agreement among raft nodes before linearized reading' (duration: 728.612798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:34:46.153971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:34:45.424134Z","time spent":"729.813137ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-13T20:34:51.447247Z","caller":"traceutil/trace.go:171","msg":"trace[600027327] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2381; }","duration":"184.193713ms","start":"2026-07-13T20:34:51.263025Z","end":"2026-07-13T20:34:51.447219Z","steps":["trace[600027327] 'read index received' (duration: 184.035529ms)","trace[600027327] 'applied index is now lower than readState.Index' (duration: 156.984µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T20:34:51.447519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.463099ms","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-07-13T20:34:51.447516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.896635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:34:51.447565Z","caller":"traceutil/trace.go:171","msg":"trace[1067142869] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2295; }","duration":"184.556551ms","start":"2026-07-13T20:34:51.262997Z","end":"2026-07-13T20:34:51.447553Z","steps":["trace[1067142869] 'agreement among raft nodes before linearized reading' (duration: 184.370407ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:51.447611Z","caller":"traceutil/trace.go:171","msg":"trace[616991070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2295; }","duration":"151.024687ms","start":"2026-07-13T20:34:51.29656Z","end":"2026-07-13T20:34:51.447585Z","steps":["trace[616991070] 'agreement among raft nodes before linearized reading' (duration: 150.860693ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:34:51.447557Z","caller":"traceutil/trace.go:171","msg":"trace[1226806618] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"224.922323ms","start":"2026-07-13T20:34:51.222477Z","end":"2026-07-13T20:34:51.447399Z","steps":["trace[1226806618] 'process raft request' (duration: 224.551884ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:35:19.740461Z","caller":"traceutil/trace.go:171","msg":"trace[921712920] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"139.586835ms","start":"2026-07-13T20:35:19.600854Z","end":"2026-07-13T20:35:19.74044Z","steps":["trace[921712920] 'process raft request' (duration: 139.448871ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:35:35.031872Z","caller":"traceutil/trace.go:171","msg":"trace[1532296761] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"476.552512ms","start":"2026-07-13T20:35:34.555296Z","end":"2026-07-13T20:35:35.031848Z","steps":["trace[1532296761] 'process raft request' (duration: 472.56309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:35:35.032048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:35:34.555261Z","time spent":"476.710425ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:35:35.0323Z","caller":"traceutil/trace.go:171","msg":"trace[1658453375] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"476.791419ms","start":"2026-07-13T20:35:34.555497Z","end":"2026-07-13T20:35:35.032288Z","steps":["trace[1658453375] 'process raft request' (duration: 476.314157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:35:35.032362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:35:34.555465Z","time spent":"476.866911ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T20:35:44.066105Z","caller":"traceutil/trace.go:171","msg":"trace[730525257] linearizableReadLoop","detail":"{readStateIndex:2897; appliedIndex:2896; }","duration":"212.288279ms","start":"2026-07-13T20:35:43.853791Z","end":"2026-07-13T20:35:44.066079Z","steps":["trace[730525257] 'read index received' (duration: 212.149136ms)","trace[730525257] 'applied index is now lower than readState.Index' (duration: 138.163µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:35:44.066169Z","caller":"traceutil/trace.go:171","msg":"trace[1538711164] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"313.289314ms","start":"2026-07-13T20:35:43.752841Z","end":"2026-07-13T20:35:44.066131Z","steps":["trace[1538711164] 'process raft request' (duration: 313.07982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:35:44.066385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T20:35:43.752818Z","time spent":"313.444358ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T20:35:44.066367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.593946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-13T20:35:44.06657Z","caller":"traceutil/trace.go:171","msg":"trace[1855369063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2792; }","duration":"212.840472ms","start":"2026-07-13T20:35:43.853696Z","end":"2026-07-13T20:35:44.066536Z","steps":["trace[1855369063] 'agreement among raft nodes before linearized reading' (duration: 212.474303ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:38:57.359861Z","caller":"traceutil/trace.go:171","msg":"trace[1596319385] transaction","detail":"{read_only:false; response_revision:3978; number_of_response:1; }","duration":"120.986455ms","start":"2026-07-13T20:38:57.238847Z","end":"2026-07-13T20:38:57.359834Z","steps":["trace[1596319385] 'process raft request' (duration: 120.80309ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:38:57.685979Z","caller":"traceutil/trace.go:171","msg":"trace[1866305564] transaction","detail":"{read_only:false; response_revision:3982; number_of_response:1; }","duration":"132.302847ms","start":"2026-07-13T20:38:57.553649Z","end":"2026-07-13T20:38:57.685952Z","steps":["trace[1866305564] 'process raft request' (duration: 103.219963ms)","trace[1866305564] 'compare' (duration: 28.901269ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:38:57.686093Z","caller":"traceutil/trace.go:171","msg":"trace[1590503983] transaction","detail":"{read_only:false; response_revision:3983; number_of_response:1; }","duration":"132.213094ms","start":"2026-07-13T20:38:57.553852Z","end":"2026-07-13T20:38:57.686065Z","steps":["trace[1590503983] 'process raft request' (duration: 132.053531ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:39:49.747645Z","caller":"traceutil/trace.go:171","msg":"trace[2112995578] transaction","detail":"{read_only:false; response_revision:4311; number_of_response:1; }","duration":"109.610981ms","start":"2026-07-13T20:39:49.638012Z","end":"2026-07-13T20:39:49.747623Z","steps":["trace[2112995578] 'process raft request' (duration: 109.473978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:39:52.458336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.500656ms","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-07-13T20:39:52.458429Z","caller":"traceutil/trace.go:171","msg":"trace[617184275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4326; }","duration":"244.624349ms","start":"2026-07-13T20:39:52.213784Z","end":"2026-07-13T20:39:52.458409Z","steps":["trace[617184275] 'range keys from in-memory index tree' (duration: 244.345453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:39:52.458361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.520683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:39:52.458501Z","caller":"traceutil/trace.go:171","msg":"trace[1410625647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4326; }","duration":"162.694437ms","start":"2026-07-13T20:39:52.295788Z","end":"2026-07-13T20:39:52.458483Z","steps":["trace[1410625647] 'range keys from in-memory index tree' (duration: 162.430822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:39:52.458479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.71517ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:39:52.45866Z","caller":"traceutil/trace.go:171","msg":"trace[151559140] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4326; }","duration":"221.907164ms","start":"2026-07-13T20:39:52.236726Z","end":"2026-07-13T20:39:52.458634Z","steps":["trace[151559140] 'range keys from in-memory index tree' (duration: 221.69525ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:39:52.477015Z","caller":"traceutil/trace.go:171","msg":"trace[669863071] transaction","detail":"{read_only:false; response_revision:4327; number_of_response:1; }","duration":"109.970209ms","start":"2026-07-13T20:39:52.367034Z","end":"2026-07-13T20:39:52.477004Z","steps":["trace[669863071] 'process raft request' (duration: 109.879457ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:39:52.60728Z","caller":"traceutil/trace.go:171","msg":"trace[1883672022] linearizableReadLoop","detail":"{readStateIndex:4485; appliedIndex:4483; }","duration":"222.14708ms","start":"2026-07-13T20:39:52.385115Z","end":"2026-07-13T20:39:52.607262Z","steps":["trace[1883672022] 'read index received' (duration: 91.837811ms)","trace[1883672022] 'applied index is now lower than readState.Index' (duration: 130.308379ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T20:39:52.607322Z","caller":"traceutil/trace.go:171","msg":"trace[498306679] transaction","detail":"{read_only:false; response_revision:4328; number_of_response:1; }","duration":"225.118609ms","start":"2026-07-13T20:39:52.382169Z","end":"2026-07-13T20:39:52.607288Z","steps":["trace[498306679] 'process raft request' (duration: 224.143707ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:39:52.607373Z","caller":"traceutil/trace.go:171","msg":"trace[1560539474] transaction","detail":"{read_only:false; response_revision:4329; number_of_response:1; }","duration":"144.712073ms","start":"2026-07-13T20:39:52.462651Z","end":"2026-07-13T20:39:52.607363Z","steps":["trace[1560539474] 'process raft request' (duration: 144.572188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:39:52.607436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.309305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T20:39:52.607459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.714122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:39:52.607498Z","caller":"traceutil/trace.go:171","msg":"trace[922068205] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4329; }","duration":"222.400057ms","start":"2026-07-13T20:39:52.385084Z","end":"2026-07-13T20:39:52.607484Z","steps":["trace[922068205] 'agreement among raft nodes before linearized reading' (duration: 222.304035ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:39:52.607522Z","caller":"traceutil/trace.go:171","msg":"trace[1026497133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4329; }","duration":"147.823835ms","start":"2026-07-13T20:39:52.45968Z","end":"2026-07-13T20:39:52.607504Z","steps":["trace[1026497133] 'agreement among raft nodes before linearized reading' (duration: 147.706932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:39:53.615943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.465797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-13T20:39:53.61608Z","caller":"traceutil/trace.go:171","msg":"trace[47093826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4331; }","duration":"105.66704ms","start":"2026-07-13T20:39:53.510385Z","end":"2026-07-13T20:39:53.616052Z","steps":["trace[47093826] 'range keys from in-memory index tree' (duration: 105.22372ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:39:54.181697Z","caller":"traceutil/trace.go:171","msg":"trace[1461510104] transaction","detail":"{read_only:false; response_revision:4337; number_of_response:1; }","duration":"129.490001ms","start":"2026-07-13T20:39:54.052182Z","end":"2026-07-13T20:39:54.181672Z","steps":["trace[1461510104] 'process raft request' (duration: 129.324547ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:41:15.775123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-07-13T20:41:15.882446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"106.467609ms","hash":3932859296,"current-db-size-bytes":38117376,"current-db-size":"38 MB","current-db-size-in-use-bytes":14438400,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-13T20:41:15.882525Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3932859296,"revision":2986,"compact-revision":-1} {"level":"info","ts":"2026-07-13T20:46:15.781336Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4828} {"level":"info","ts":"2026-07-13T20:46:15.82098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4828,"took":"38.709548ms","hash":3588633118,"current-db-size-bytes":38117376,"current-db-size":"38 MB","current-db-size-in-use-bytes":10448896,"current-db-size-in-use":"10 MB"} {"level":"info","ts":"2026-07-13T20:46:15.82104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3588633118,"revision":4828,"compact-revision":2986} {"level":"info","ts":"2026-07-13T20:47:07.827863Z","caller":"traceutil/trace.go:171","msg":"trace[1451831344] transaction","detail":"{read_only:false; response_revision:6964; number_of_response:1; }","duration":"111.674775ms","start":"2026-07-13T20:47:07.716152Z","end":"2026-07-13T20:47:07.827827Z","steps":["trace[1451831344] 'process raft request' (duration: 108.189225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T20:50:24.473822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.04212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T20:50:24.473961Z","caller":"traceutil/trace.go:171","msg":"trace[1185312307] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8130; }","duration":"110.239536ms","start":"2026-07-13T20:50:24.363699Z","end":"2026-07-13T20:50:24.473939Z","steps":["trace[1185312307] 'range keys from in-memory index tree' (duration: 109.903158ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T20:51:15.788455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6653} {"level":"info","ts":"2026-07-13T20:51:15.827511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6653,"took":"38.031732ms","hash":699677843,"current-db-size-bytes":38117376,"current-db-size":"38 MB","current-db-size-in-use-bytes":10317824,"current-db-size-in-use":"10 MB"} {"level":"info","ts":"2026-07-13T20:51:15.827573Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":699677843,"revision":6653,"compact-revision":4828}