{"level":"warn","ts":"2026-05-20T03:05:59.884401Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:05:59.884575Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-20T03:05:59.884663Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:05:59.884678Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-20T03:05:59.884701Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T03:05:59.885571Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-05-20T03:05:59.885654Z","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":8,"max-cpu-available":8,"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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-20T03:05:59.893996Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.089817ms"} {"level":"info","ts":"2026-05-20T03:05:59.903978Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-20T03:05:59.904067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T03:05:59.904154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-20T03:05:59.904173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T03:05:59.904188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-20T03:05:59.904264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-20T03:05:59.918835Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T03:05:59.920823Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T03:05:59.923602Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-20T03:05:59.92597Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T03:05:59.9264Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T03:05:59.926576Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:05:59.926615Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:05:59.926626Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:05:59.928973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-20T03:05:59.936452Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-20T03:05:59.937302Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T03:05:59.937476Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-20T03:05:59.937507Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-20T03:05:59.937597Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T03:05:59.937637Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T03:06:00.905409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T03:06:00.905475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T03:06:00.905517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-20T03:06:00.905596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-20T03:06:00.905611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-20T03:06:00.905667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-20T03:06:00.905709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-20T03:06:00.906987Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T03:06:00.906987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:06:00.906987Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:00.90702Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:06:00.907322Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T03:06:00.907355Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T03:06:00.907962Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:00.908089Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:00.908127Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:00.9088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-20T03:06:00.910786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T03:06:02.608196Z","caller":"traceutil/trace.go:171","msg":"trace[1422656958] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"191.122257ms","start":"2026-05-20T03:06:02.417033Z","end":"2026-05-20T03:06:02.608156Z","steps":["trace[1422656958] 'process raft request' (duration: 186.923994ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:05.143199Z","caller":"traceutil/trace.go:171","msg":"trace[1815887446] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"118.273541ms","start":"2026-05-20T03:06:05.024879Z","end":"2026-05-20T03:06:05.143152Z","steps":["trace[1815887446] 'process raft request' (duration: 94.313761ms)","trace[1815887446] 'compare' (duration: 23.688098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:06:05.143358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.692396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-05-20T03:06:05.143558Z","caller":"traceutil/trace.go:171","msg":"trace[1343389032] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:211; }","duration":"129.907489ms","start":"2026-05-20T03:06:05.013599Z","end":"2026-05-20T03:06:05.143507Z","steps":["trace[1343389032] 'agreement among raft nodes before linearized reading' (duration: 129.570635ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.767221Z","caller":"traceutil/trace.go:171","msg":"trace[909489006] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"179.621548ms","start":"2026-05-20T03:06:29.587568Z","end":"2026-05-20T03:06:29.767189Z","steps":["trace[909489006] 'process raft request' (duration: 179.464356ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774158Z","caller":"traceutil/trace.go:171","msg":"trace[2056598249] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"185.714447ms","start":"2026-05-20T03:06:29.58842Z","end":"2026-05-20T03:06:29.774135Z","steps":["trace[2056598249] 'process raft request' (duration: 185.593746ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774356Z","caller":"traceutil/trace.go:171","msg":"trace[308690324] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"170.208929ms","start":"2026-05-20T03:06:29.604125Z","end":"2026-05-20T03:06:29.774334Z","steps":["trace[308690324] 'process raft request' (duration: 170.030078ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774502Z","caller":"traceutil/trace.go:171","msg":"trace[1169727639] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"179.025682ms","start":"2026-05-20T03:06:29.595468Z","end":"2026-05-20T03:06:29.774493Z","steps":["trace[1169727639] 'process raft request' (duration: 178.612029ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774551Z","caller":"traceutil/trace.go:171","msg":"trace[342177923] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"178.846921ms","start":"2026-05-20T03:06:29.595696Z","end":"2026-05-20T03:06:29.774543Z","steps":["trace[342177923] 'process raft request' (duration: 178.415157ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774623Z","caller":"traceutil/trace.go:171","msg":"trace[918227224] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"174.236352ms","start":"2026-05-20T03:06:29.60038Z","end":"2026-05-20T03:06:29.774616Z","steps":["trace[918227224] 'process raft request' (duration: 173.757748ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774748Z","caller":"traceutil/trace.go:171","msg":"trace[1169561069] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"169.075798ms","start":"2026-05-20T03:06:29.605662Z","end":"2026-05-20T03:06:29.774737Z","steps":["trace[1169561069] 'process raft request' (duration: 168.513244ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774802Z","caller":"traceutil/trace.go:171","msg":"trace[1316110555] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"157.240341ms","start":"2026-05-20T03:06:29.617554Z","end":"2026-05-20T03:06:29.774794Z","steps":["trace[1316110555] 'process raft request' (duration: 156.763947ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774846Z","caller":"traceutil/trace.go:171","msg":"trace[991041842] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"156.08807ms","start":"2026-05-20T03:06:29.618752Z","end":"2026-05-20T03:06:29.77484Z","steps":["trace[991041842] 'process raft request' (duration: 155.608336ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774869Z","caller":"traceutil/trace.go:171","msg":"trace[394903565] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"169.138149ms","start":"2026-05-20T03:06:29.605726Z","end":"2026-05-20T03:06:29.774864Z","steps":["trace[394903565] 'process raft request' (duration: 168.486244ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774872Z","caller":"traceutil/trace.go:171","msg":"trace[627064852] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"166.493657ms","start":"2026-05-20T03:06:29.608373Z","end":"2026-05-20T03:06:29.774867Z","steps":["trace[627064852] 'process raft request' (duration: 165.889332ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774917Z","caller":"traceutil/trace.go:171","msg":"trace[816924049] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"166.494077ms","start":"2026-05-20T03:06:29.608418Z","end":"2026-05-20T03:06:29.774912Z","steps":["trace[816924049] 'process raft request' (duration: 165.871302ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774846Z","caller":"traceutil/trace.go:171","msg":"trace[325443167] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"169.080989ms","start":"2026-05-20T03:06:29.605758Z","end":"2026-05-20T03:06:29.774839Z","steps":["trace[325443167] 'process raft request' (duration: 168.476514ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:29.774947Z","caller":"traceutil/trace.go:171","msg":"trace[889580238] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"138.217172ms","start":"2026-05-20T03:06:29.636723Z","end":"2026-05-20T03:06:29.77494Z","steps":["trace[889580238] 'process raft request' (duration: 137.725198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:34.052999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.427764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T03:06:34.053094Z","caller":"traceutil/trace.go:171","msg":"trace[2147483052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"831.583535ms","start":"2026-05-20T03:06:33.221491Z","end":"2026-05-20T03:06:34.053075Z","steps":["trace[2147483052] 'range keys from in-memory index tree' (duration: 831.209852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:34.053141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:33.221468Z","time spent":"831.663846ms","remote":"127.0.0.1:48430","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-05-20T03:06:34.083335Z","caller":"traceutil/trace.go:171","msg":"trace[1280275931] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"839.58908ms","start":"2026-05-20T03:06:33.243724Z","end":"2026-05-20T03:06:34.083313Z","steps":["trace[1280275931] 'process raft request' (duration: 839.393079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:34.083891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:33.243697Z","time spent":"839.703911ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:06:34.101692Z","caller":"traceutil/trace.go:171","msg":"trace[992838113] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"364.611068ms","start":"2026-05-20T03:06:33.73703Z","end":"2026-05-20T03:06:34.101641Z","steps":["trace[992838113] 'read index received' (duration: 346.092788ms)","trace[992838113] 'applied index is now lower than readState.Index' (duration: 18.51707ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:06:34.10181Z","caller":"traceutil/trace.go:171","msg":"trace[1409097127] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"565.580136ms","start":"2026-05-20T03:06:33.5362Z","end":"2026-05-20T03:06:34.10178Z","steps":["trace[1409097127] 'process raft request' (duration: 560.261953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:34.101835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.794809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:34.101873Z","caller":"traceutil/trace.go:171","msg":"trace[883056211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"364.86415ms","start":"2026-05-20T03:06:33.736998Z","end":"2026-05-20T03:06:34.101862Z","steps":["trace[883056211] 'agreement among raft nodes before linearized reading' (duration: 364.797119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:34.10191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:33.736985Z","time spent":"364.91785ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:06:34.101909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.819291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-20T03:06:34.10193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:33.536172Z","time spent":"565.694768ms","remote":"127.0.0.1:48244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:06:34.101972Z","caller":"traceutil/trace.go:171","msg":"trace[1627361301] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:556; }","duration":"255.936672ms","start":"2026-05-20T03:06:33.846017Z","end":"2026-05-20T03:06:34.101954Z","steps":["trace[1627361301] 'agreement among raft nodes before linearized reading' (duration: 255.811981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:34.102031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.24259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:06:34.102107Z","caller":"traceutil/trace.go:171","msg":"trace[795061036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"291.352301ms","start":"2026-05-20T03:06:33.810734Z","end":"2026-05-20T03:06:34.102087Z","steps":["trace[795061036] 'agreement among raft nodes before linearized reading' (duration: 291.099989ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:36.49253Z","caller":"traceutil/trace.go:171","msg":"trace[208429323] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"180.746611ms","start":"2026-05-20T03:06:36.311755Z","end":"2026-05-20T03:06:36.492501Z","steps":["trace[208429323] 'process raft request' (duration: 176.82134ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:36.678366Z","caller":"traceutil/trace.go:171","msg":"trace[613947186] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"155.241507ms","start":"2026-05-20T03:06:36.523097Z","end":"2026-05-20T03:06:36.678338Z","steps":["trace[613947186] 'process raft request' (duration: 154.581811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:37.633325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.05847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:06:37.633429Z","caller":"traceutil/trace.go:171","msg":"trace[381871064] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"825.959559ms","start":"2026-05-20T03:06:36.807451Z","end":"2026-05-20T03:06:37.633411Z","steps":["trace[381871064] 'read index received' (duration: 466.560746ms)","trace[381871064] 'applied index is now lower than readState.Index' (duration: 359.396653ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:06:37.633528Z","caller":"traceutil/trace.go:171","msg":"trace[763422303] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"894.256008ms","start":"2026-05-20T03:06:36.739261Z","end":"2026-05-20T03:06:37.633517Z","steps":["trace[763422303] 'process raft request' (duration: 534.804804ms)","trace[763422303] 'compare' (duration: 358.831598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:06:37.633582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:36.739252Z","time spent":"894.306688ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:06:37.633881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.846099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d47tg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-20T03:06:37.633968Z","caller":"traceutil/trace.go:171","msg":"trace[954078622] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d47tg; range_end:; response_count:1; response_revision:578; }","duration":"738.97248ms","start":"2026-05-20T03:06:36.894973Z","end":"2026-05-20T03:06:37.633946Z","steps":["trace[954078622] 'agreement among raft nodes before linearized reading' (duration: 738.694448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:37.634012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:36.894957Z","time spent":"739.04642ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-d47tg\" "} {"level":"warn","ts":"2026-05-20T03:06:37.634375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.543243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:06:37.634538Z","caller":"traceutil/trace.go:171","msg":"trace[1314332471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"827.105297ms","start":"2026-05-20T03:06:36.807411Z","end":"2026-05-20T03:06:37.634516Z","steps":["trace[1314332471] 'agreement among raft nodes before linearized reading' (duration: 826.297391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:37.634683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:36.807398Z","time spent":"827.176368ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T03:06:39.478118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722096862s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:06:39.478184Z","caller":"traceutil/trace.go:171","msg":"trace[353713965] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"1.830231195s","start":"2026-05-20T03:06:37.647942Z","end":"2026-05-20T03:06:39.478173Z","steps":["trace[353713965] 'read index received' (duration: 65.956546ms)","trace[353713965] 'applied index is now lower than readState.Index' (duration: 1.764272859s)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:06:39.478227Z","caller":"traceutil/trace.go:171","msg":"trace[1258226318] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.830751129s","start":"2026-05-20T03:06:37.647468Z","end":"2026-05-20T03:06:39.47822Z","steps":["trace[1258226318] 'process raft request' (duration: 108.492536ms)","trace[1258226318] 'compare' (duration: 1.72187936s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:06:39.478255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:37.647441Z","time spent":"1.830798999s","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:06:39.478412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830428406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:39.478537Z","caller":"traceutil/trace.go:171","msg":"trace[345191827] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:580; }","duration":"1.830566588s","start":"2026-05-20T03:06:37.647896Z","end":"2026-05-20T03:06:39.478463Z","steps":["trace[345191827] 'agreement among raft nodes before linearized reading' (duration: 1.830427477s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:39.478607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:37.647874Z","time spent":"1.830694319s","remote":"127.0.0.1:48294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-20T03:06:39.687037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.814383ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-20T03:06:39.687368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.322923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:39.687384Z","caller":"traceutil/trace.go:171","msg":"trace[682533513] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"205.74371ms","start":"2026-05-20T03:06:39.48162Z","end":"2026-05-20T03:06:39.687364Z","steps":["trace[682533513] 'process raft request' (duration: 205.523368ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:39.687384Z","caller":"traceutil/trace.go:171","msg":"trace[127656538] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"209.081436ms","start":"2026-05-20T03:06:39.478199Z","end":"2026-05-20T03:06:39.68728Z","steps":["trace[127656538] 'read index received' (duration: 33.026522ms)","trace[127656538] 'applied index is now lower than readState.Index' (duration: 176.049274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:06:39.687463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.965263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:39.687429Z","caller":"traceutil/trace.go:171","msg":"trace[249330360] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:580; }","duration":"293.436874ms","start":"2026-05-20T03:06:39.393977Z","end":"2026-05-20T03:06:39.687414Z","steps":["trace[249330360] 'agreement among raft nodes before linearized reading' (duration: 293.312943ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:39.687542Z","caller":"traceutil/trace.go:171","msg":"trace[1358429697] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:581; }","duration":"263.079734ms","start":"2026-05-20T03:06:39.424445Z","end":"2026-05-20T03:06:39.687525Z","steps":["trace[1358429697] 'agreement among raft nodes before linearized reading' (duration: 262.882772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:39.68756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.150302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:39.687644Z","caller":"traceutil/trace.go:171","msg":"trace[379957007] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:581; }","duration":"293.268673ms","start":"2026-05-20T03:06:39.394362Z","end":"2026-05-20T03:06:39.68763Z","steps":["trace[379957007] 'agreement among raft nodes before linearized reading' (duration: 293.136632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:39.687696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.727816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:06:39.687767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.949585734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:06:39.687802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.279541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:39.687791Z","caller":"traceutil/trace.go:171","msg":"trace[1225749706] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:581; }","duration":"293.811097ms","start":"2026-05-20T03:06:39.39395Z","end":"2026-05-20T03:06:39.687761Z","steps":["trace[1225749706] 'agreement among raft nodes before linearized reading' (duration: 293.664765ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:39.687843Z","caller":"traceutil/trace.go:171","msg":"trace[326063451] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:581; }","duration":"198.332911ms","start":"2026-05-20T03:06:39.489496Z","end":"2026-05-20T03:06:39.687829Z","steps":["trace[326063451] 'agreement among raft nodes before linearized reading' (duration: 198.25666ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:39.687857Z","caller":"traceutil/trace.go:171","msg":"trace[11894753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"1.949701065s","start":"2026-05-20T03:06:37.738119Z","end":"2026-05-20T03:06:39.68782Z","steps":["trace[11894753] 'agreement among raft nodes before linearized reading' (duration: 1.949626704s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:39.687916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:37.738095Z","time spent":"1.949812746s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:06:39.687471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.741302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:06:39.688024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360405648s","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-05-20T03:06:39.688056Z","caller":"traceutil/trace.go:171","msg":"trace[1242979982] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:581; }","duration":"267.357477ms","start":"2026-05-20T03:06:39.420685Z","end":"2026-05-20T03:06:39.688042Z","steps":["trace[1242979982] 'agreement among raft nodes before linearized reading' (duration: 266.761632ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:39.688075Z","caller":"traceutil/trace.go:171","msg":"trace[2030922384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.360491879s","start":"2026-05-20T03:06:38.327563Z","end":"2026-05-20T03:06:39.688055Z","steps":["trace[2030922384] 'agreement among raft nodes before linearized reading' (duration: 1.360398758s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:39.68812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:38.327537Z","time spent":"1.360575929s","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:06:39.687913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572208022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-20T03:06:39.688191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.368541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:39.688225Z","caller":"traceutil/trace.go:171","msg":"trace[2082686715] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:581; }","duration":"198.462042ms","start":"2026-05-20T03:06:39.489755Z","end":"2026-05-20T03:06:39.688218Z","steps":["trace[2082686715] 'agreement among raft nodes before linearized reading' (duration: 198.367341ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:39.688225Z","caller":"traceutil/trace.go:171","msg":"trace[1052622792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"1.572554805s","start":"2026-05-20T03:06:38.115629Z","end":"2026-05-20T03:06:39.688184Z","steps":["trace[1052622792] 'agreement among raft nodes before linearized reading' (duration: 1.572076261s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:39.688328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:38.115607Z","time spent":"1.572711196s","remote":"127.0.0.1:48430","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-05-20T03:06:39.688405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.877955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:39.688439Z","caller":"traceutil/trace.go:171","msg":"trace[1045114321] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:581; }","duration":"198.922976ms","start":"2026-05-20T03:06:39.489509Z","end":"2026-05-20T03:06:39.688432Z","steps":["trace[1045114321] 'agreement among raft nodes before linearized reading' (duration: 198.852595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:39.688445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.567836298s","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-05-20T03:06:39.688483Z","caller":"traceutil/trace.go:171","msg":"trace[1460935470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.567926849s","start":"2026-05-20T03:06:38.120546Z","end":"2026-05-20T03:06:39.688473Z","steps":["trace[1460935470] 'agreement among raft nodes before linearized reading' (duration: 1.567861318s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:39.688515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:38.120511Z","time spent":"1.567998379s","remote":"127.0.0.1:48430","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-05-20T03:06:39.687369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.55945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:06:39.688593Z","caller":"traceutil/trace.go:171","msg":"trace[629975315] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:580; }","duration":"267.80483ms","start":"2026-05-20T03:06:39.420774Z","end":"2026-05-20T03:06:39.688578Z","steps":["trace[629975315] 'agreement among raft nodes before linearized reading' (duration: 266.52675ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:41.349472Z","caller":"traceutil/trace.go:171","msg":"trace[1285720262] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"188.619611ms","start":"2026-05-20T03:06:41.160827Z","end":"2026-05-20T03:06:41.349447Z","steps":["trace[1285720262] 'process raft request' (duration: 188.50693ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:42.394219Z","caller":"traceutil/trace.go:171","msg":"trace[711948125] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"128.107572ms","start":"2026-05-20T03:06:42.266085Z","end":"2026-05-20T03:06:42.394192Z","steps":["trace[711948125] 'process raft request' (duration: 127.920961ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:42.588486Z","caller":"traceutil/trace.go:171","msg":"trace[1030761622] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"107.713101ms","start":"2026-05-20T03:06:42.480722Z","end":"2026-05-20T03:06:42.588435Z","steps":["trace[1030761622] 'process raft request' (duration: 106.14528ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:44.203177Z","caller":"traceutil/trace.go:171","msg":"trace[555835568] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"113.939363ms","start":"2026-05-20T03:06:44.089206Z","end":"2026-05-20T03:06:44.203145Z","steps":["trace[555835568] 'process raft request' (duration: 113.710912ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:46.148219Z","caller":"traceutil/trace.go:171","msg":"trace[678597239] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"143.203351ms","start":"2026-05-20T03:06:46.004966Z","end":"2026-05-20T03:06:46.148169Z","steps":["trace[678597239] 'process raft request' (duration: 142.573154ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:06:55.358215Z","caller":"traceutil/trace.go:171","msg":"trace[440188905] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"301.630752ms","start":"2026-05-20T03:06:55.056552Z","end":"2026-05-20T03:06:55.358183Z","steps":["trace[440188905] 'read index received' (duration: 301.3517ms)","trace[440188905] 'applied index is now lower than readState.Index' (duration: 278.121µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:06:55.358312Z","caller":"traceutil/trace.go:171","msg":"trace[807782773] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"350.185197ms","start":"2026-05-20T03:06:55.008082Z","end":"2026-05-20T03:06:55.358267Z","steps":["trace[807782773] 'process raft request' (duration: 349.896715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:55.358534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.975296ms","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-05-20T03:06:55.358549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:55.008035Z","time spent":"350.418917ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:06:55.358597Z","caller":"traceutil/trace.go:171","msg":"trace[697503659] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"302.08178ms","start":"2026-05-20T03:06:55.056499Z","end":"2026-05-20T03:06:55.358581Z","steps":["trace[697503659] 'agreement among raft nodes before linearized reading' (duration: 301.863161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:55.358637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:55.056476Z","time spent":"302.153943ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T03:07:08.403989Z","caller":"traceutil/trace.go:171","msg":"trace[58550082] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"244.270195ms","start":"2026-05-20T03:07:08.159694Z","end":"2026-05-20T03:07:08.403964Z","steps":["trace[58550082] 'process raft request' (duration: 244.106498ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:24.116166Z","caller":"traceutil/trace.go:171","msg":"trace[189221066] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"377.25277ms","start":"2026-05-20T03:07:23.738891Z","end":"2026-05-20T03:07:24.116144Z","steps":["trace[189221066] 'read index received' (duration: 377.121036ms)","trace[189221066] 'applied index is now lower than readState.Index' (duration: 130.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:24.116205Z","caller":"traceutil/trace.go:171","msg":"trace[1770564258] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"540.429197ms","start":"2026-05-20T03:07:23.575754Z","end":"2026-05-20T03:07:24.116183Z","steps":["trace[1770564258] 'process raft request' (duration: 540.259082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:24.116415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.501268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:07:24.11652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.230209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T03:07:24.1166Z","caller":"traceutil/trace.go:171","msg":"trace[1709352487] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:1137; }","duration":"268.320022ms","start":"2026-05-20T03:07:23.848265Z","end":"2026-05-20T03:07:24.116585Z","steps":["trace[1709352487] 'agreement among raft nodes before linearized reading' (duration: 268.034343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:24.116632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.324839ms","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-05-20T03:07:24.116712Z","caller":"traceutil/trace.go:171","msg":"trace[1681265550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1137; }","duration":"184.443172ms","start":"2026-05-20T03:07:23.932256Z","end":"2026-05-20T03:07:24.116699Z","steps":["trace[1681265550] 'agreement among raft nodes before linearized reading' (duration: 184.241016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:24.116688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.134599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:07:24.116533Z","caller":"traceutil/trace.go:171","msg":"trace[1570964237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1137; }","duration":"377.688913ms","start":"2026-05-20T03:07:23.738821Z","end":"2026-05-20T03:07:24.11651Z","steps":["trace[1570964237] 'agreement among raft nodes before linearized reading' (duration: 377.489167ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:24.116903Z","caller":"traceutil/trace.go:171","msg":"trace[136434800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1137; }","duration":"181.320195ms","start":"2026-05-20T03:07:23.935512Z","end":"2026-05-20T03:07:24.116833Z","steps":["trace[136434800] 'agreement among raft nodes before linearized reading' (duration: 180.949604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:24.116907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:23.738788Z","time spent":"378.107996ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:07:24.116443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:23.575728Z","time spent":"540.550711ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:07:42.665965Z","caller":"traceutil/trace.go:171","msg":"trace[641804114] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"104.37248ms","start":"2026-05-20T03:07:42.561318Z","end":"2026-05-20T03:07:42.665691Z","steps":["trace[641804114] 'process raft request' (duration: 104.209605ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:50.644011Z","caller":"traceutil/trace.go:171","msg":"trace[1483728809] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"264.73353ms","start":"2026-05-20T03:07:50.379252Z","end":"2026-05-20T03:07:50.643986Z","steps":["trace[1483728809] 'read index received' (duration: 264.575147ms)","trace[1483728809] 'applied index is now lower than readState.Index' (duration: 157.003µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:50.644136Z","caller":"traceutil/trace.go:171","msg":"trace[1460689723] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"340.041964ms","start":"2026-05-20T03:07:50.304059Z","end":"2026-05-20T03:07:50.644101Z","steps":["trace[1460689723] 'process raft request' (duration: 339.759027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:50.644301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.043027ms","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-05-20T03:07:50.644293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.564107ms","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-05-20T03:07:50.644344Z","caller":"traceutil/trace.go:171","msg":"trace[631888414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1411; }","duration":"265.12175ms","start":"2026-05-20T03:07:50.379212Z","end":"2026-05-20T03:07:50.644334Z","steps":["trace[631888414] 'agreement among raft nodes before linearized reading' (duration: 264.933085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:50.644331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:50.304044Z","time spent":"340.183357ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:07:50.644351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.392336ms","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-05-20T03:07:50.644375Z","caller":"traceutil/trace.go:171","msg":"trace[526974952] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1411; }","duration":"167.68159ms","start":"2026-05-20T03:07:50.476674Z","end":"2026-05-20T03:07:50.644356Z","steps":["trace[526974952] 'agreement among raft nodes before linearized reading' (duration: 167.530017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:50.644417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.313409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:07:50.644483Z","caller":"traceutil/trace.go:171","msg":"trace[485647650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1411; }","duration":"207.400782ms","start":"2026-05-20T03:07:50.437064Z","end":"2026-05-20T03:07:50.644465Z","steps":["trace[485647650] 'agreement among raft nodes before linearized reading' (duration: 207.237558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:50.644531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.157489ms","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-05-20T03:07:50.644572Z","caller":"traceutil/trace.go:171","msg":"trace[1848042643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1411; }","duration":"208.221661ms","start":"2026-05-20T03:07:50.436339Z","end":"2026-05-20T03:07:50.644561Z","steps":["trace[1848042643] 'agreement among raft nodes before linearized reading' (duration: 208.097698ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:50.644423Z","caller":"traceutil/trace.go:171","msg":"trace[804424604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1411; }","duration":"220.517349ms","start":"2026-05-20T03:07:50.423886Z","end":"2026-05-20T03:07:50.644403Z","steps":["trace[804424604] 'agreement among raft nodes before linearized reading' (duration: 220.269023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:50.875176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.480328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:07:50.875234Z","caller":"traceutil/trace.go:171","msg":"trace[1503620566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1416; }","duration":"137.579241ms","start":"2026-05-20T03:07:50.737642Z","end":"2026-05-20T03:07:50.875221Z","steps":["trace[1503620566] 'range keys from in-memory index tree' (duration: 137.350206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:50.875247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.052766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:1 size:1905"} {"level":"info","ts":"2026-05-20T03:07:50.875305Z","caller":"traceutil/trace.go:171","msg":"trace[1745794189] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:1; response_revision:1416; }","duration":"134.157968ms","start":"2026-05-20T03:07:50.74113Z","end":"2026-05-20T03:07:50.875288Z","steps":["trace[1745794189] 'range keys from in-memory index tree' (duration: 133.881651ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:12.927907Z","caller":"traceutil/trace.go:171","msg":"trace[640117907] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"123.419598ms","start":"2026-05-20T03:08:12.804454Z","end":"2026-05-20T03:08:12.927874Z","steps":["trace[640117907] 'process raft request' (duration: 121.923918ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:12.928928Z","caller":"traceutil/trace.go:171","msg":"trace[451213376] linearizableReadLoop","detail":"{readStateIndex:1815; appliedIndex:1813; }","duration":"118.058339ms","start":"2026-05-20T03:08:12.810846Z","end":"2026-05-20T03:08:12.928904Z","steps":["trace[451213376] 'read index received' (duration: 115.547577ms)","trace[451213376] 'applied index is now lower than readState.Index' (duration: 2.509732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:12.929254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.381535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:12.929288Z","caller":"traceutil/trace.go:171","msg":"trace[1073587458] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"124.300866ms","start":"2026-05-20T03:08:12.804948Z","end":"2026-05-20T03:08:12.929249Z","steps":["trace[1073587458] 'process raft request' (duration: 123.785745ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:12.929344Z","caller":"traceutil/trace.go:171","msg":"trace[622904606] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1748; }","duration":"118.499117ms","start":"2026-05-20T03:08:12.810822Z","end":"2026-05-20T03:08:12.929321Z","steps":["trace[622904606] 'agreement among raft nodes before linearized reading' (duration: 118.352634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:12.929359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.71736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:12.929374Z","caller":"traceutil/trace.go:171","msg":"trace[1521651173] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"112.821392ms","start":"2026-05-20T03:08:12.81653Z","end":"2026-05-20T03:08:12.929351Z","steps":["trace[1521651173] 'process raft request' (duration: 112.336992ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:12.929412Z","caller":"traceutil/trace.go:171","msg":"trace[1833419347] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:0; response_revision:1748; }","duration":"115.783182ms","start":"2026-05-20T03:08:12.813615Z","end":"2026-05-20T03:08:12.929398Z","steps":["trace[1833419347] 'agreement among raft nodes before linearized reading' (duration: 115.68743ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:13.142409Z","caller":"traceutil/trace.go:171","msg":"trace[1831168642] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"205.956793ms","start":"2026-05-20T03:08:12.936423Z","end":"2026-05-20T03:08:13.14238Z","steps":["trace[1831168642] 'process raft request' (duration: 205.734428ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:13.142497Z","caller":"traceutil/trace.go:171","msg":"trace[1049289657] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"205.645044ms","start":"2026-05-20T03:08:12.936824Z","end":"2026-05-20T03:08:13.142469Z","steps":["trace[1049289657] 'process raft request' (duration: 205.384139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:13.142946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.246775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:13.142724Z","caller":"traceutil/trace.go:171","msg":"trace[20504212] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"208.235918ms","start":"2026-05-20T03:08:12.934457Z","end":"2026-05-20T03:08:13.142693Z","steps":["trace[20504212] 'process raft request' (duration: 136.435063ms)","trace[20504212] 'compare' (duration: 70.934238ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:13.142993Z","caller":"traceutil/trace.go:171","msg":"trace[1670621874] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:0; response_revision:1755; }","duration":"199.360977ms","start":"2026-05-20T03:08:12.943618Z","end":"2026-05-20T03:08:13.142979Z","steps":["trace[1670621874] 'agreement among raft nodes before linearized reading' (duration: 199.209914ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:13.142776Z","caller":"traceutil/trace.go:171","msg":"trace[620207815] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"208.099805ms","start":"2026-05-20T03:08:12.934591Z","end":"2026-05-20T03:08:13.142691Z","steps":["trace[620207815] 'process raft request' (duration: 207.474512ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:13.142539Z","caller":"traceutil/trace.go:171","msg":"trace[73316448] linearizableReadLoop","detail":"{readStateIndex:1822; appliedIndex:1817; }","duration":"198.850917ms","start":"2026-05-20T03:08:12.943659Z","end":"2026-05-20T03:08:13.14251Z","steps":["trace[73316448] 'read index received' (duration: 21.958729ms)","trace[73316448] 'applied index is now lower than readState.Index' (duration: 176.888548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:13.143366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.624434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3963"} {"level":"warn","ts":"2026-05-20T03:08:13.143384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.289742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:08:13.143413Z","caller":"traceutil/trace.go:171","msg":"trace[1665900023] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1755; }","duration":"154.717136ms","start":"2026-05-20T03:08:12.988683Z","end":"2026-05-20T03:08:13.1434Z","steps":["trace[1665900023] 'agreement among raft nodes before linearized reading' (duration: 154.541812ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:13.143442Z","caller":"traceutil/trace.go:171","msg":"trace[1755746600] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1755; }","duration":"101.387633ms","start":"2026-05-20T03:08:13.042038Z","end":"2026-05-20T03:08:13.143426Z","steps":["trace[1755746600] 'agreement among raft nodes before linearized reading' (duration: 101.253471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:16.42543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.069289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd34316b-edf4-48fb-9221-087715289c14\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-20T03:08:16.425519Z","caller":"traceutil/trace.go:171","msg":"trace[1233965862] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd34316b-edf4-48fb-9221-087715289c14; range_end:; response_count:1; response_revision:1831; }","duration":"644.207972ms","start":"2026-05-20T03:08:15.78129Z","end":"2026-05-20T03:08:16.425498Z","steps":["trace[1233965862] 'range keys from in-memory index tree' (duration: 643.834434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:16.425562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:15.781266Z","time spent":"644.287173ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd34316b-edf4-48fb-9221-087715289c14\" "} {"level":"info","ts":"2026-05-20T03:08:16.425717Z","caller":"traceutil/trace.go:171","msg":"trace[1159426969] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"261.07763ms","start":"2026-05-20T03:08:16.164605Z","end":"2026-05-20T03:08:16.425683Z","steps":["trace[1159426969] 'read index received' (duration: 208.958486ms)","trace[1159426969] 'applied index is now lower than readState.Index' (duration: 52.117574ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:16.425876Z","caller":"traceutil/trace.go:171","msg":"trace[286482370] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"458.084023ms","start":"2026-05-20T03:08:15.96777Z","end":"2026-05-20T03:08:16.425854Z","steps":["trace[286482370] 'process raft request' (duration: 405.828696ms)","trace[286482370] 'compare' (duration: 51.317288ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:16.426011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:15.967744Z","time spent":"458.169925ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:08:16.426087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.479008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-20T03:08:16.426148Z","caller":"traceutil/trace.go:171","msg":"trace[1311288289] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1832; }","duration":"261.57528ms","start":"2026-05-20T03:08:16.164552Z","end":"2026-05-20T03:08:16.426128Z","steps":["trace[1311288289] 'agreement among raft nodes before linearized reading' (duration: 261.391086ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:16.989269Z","caller":"traceutil/trace.go:171","msg":"trace[2065705509] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"251.184623ms","start":"2026-05-20T03:08:16.738053Z","end":"2026-05-20T03:08:16.989237Z","steps":["trace[2065705509] 'read index received' (duration: 250.931018ms)","trace[2065705509] 'applied index is now lower than readState.Index' (duration: 252.185µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:16.989354Z","caller":"traceutil/trace.go:171","msg":"trace[2108816855] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"356.757178ms","start":"2026-05-20T03:08:16.632529Z","end":"2026-05-20T03:08:16.989286Z","steps":["trace[2108816855] 'process raft request' (duration: 356.489303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:16.989444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.371918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:16.989482Z","caller":"traceutil/trace.go:171","msg":"trace[920418148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1834; }","duration":"251.4687ms","start":"2026-05-20T03:08:16.738001Z","end":"2026-05-20T03:08:16.98947Z","steps":["trace[920418148] 'agreement among raft nodes before linearized reading' (duration: 251.383838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:16.989555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:16.632494Z","time spent":"356.954342ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:08:17.067982Z","caller":"traceutil/trace.go:171","msg":"trace[157917519] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"125.160734ms","start":"2026-05-20T03:08:16.942792Z","end":"2026-05-20T03:08:17.067953Z","steps":["trace[157917519] 'process raft request' (duration: 124.782567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:17.068045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.009312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T03:08:17.068081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.165361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:08:17.068121Z","caller":"traceutil/trace.go:171","msg":"trace[461214217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1835; }","duration":"120.134965ms","start":"2026-05-20T03:08:16.947967Z","end":"2026-05-20T03:08:17.068102Z","steps":["trace[461214217] 'agreement among raft nodes before linearized reading' (duration: 119.820219ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:17.068175Z","caller":"traceutil/trace.go:171","msg":"trace[783630910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1835; }","duration":"271.282564ms","start":"2026-05-20T03:08:16.796845Z","end":"2026-05-20T03:08:17.068127Z","steps":["trace[783630910] 'agreement among raft nodes before linearized reading' (duration: 271.026589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:18.89317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.707748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T03:08:18.893287Z","caller":"traceutil/trace.go:171","msg":"trace[501774568] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1916; }","duration":"155.459323ms","start":"2026-05-20T03:08:18.737811Z","end":"2026-05-20T03:08:18.893271Z","steps":["trace[501774568] 'read index received' (duration: 38.53µs)","trace[501774568] 'applied index is now lower than readState.Index' (duration: 155.419423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:18.893348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.542695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:18.893358Z","caller":"traceutil/trace.go:171","msg":"trace[580174006] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1848; }","duration":"335.801742ms","start":"2026-05-20T03:08:18.557536Z","end":"2026-05-20T03:08:18.893338Z","steps":["trace[580174006] 'process raft request' (duration: 62.792878ms)","trace[580174006] 'compare' (duration: 272.404393ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:18.89338Z","caller":"traceutil/trace.go:171","msg":"trace[657642860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"155.606586ms","start":"2026-05-20T03:08:18.737762Z","end":"2026-05-20T03:08:18.893369Z","steps":["trace[657642860] 'agreement among raft nodes before linearized reading' (duration: 155.551515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:18.893477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:18.557524Z","time spent":"335.879963ms","remote":"127.0.0.1:60708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-05-20T03:08:45.334591Z","caller":"traceutil/trace.go:171","msg":"trace[23385081] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"127.102909ms","start":"2026-05-20T03:08:45.207439Z","end":"2026-05-20T03:08:45.334542Z","steps":["trace[23385081] 'process raft request' (duration: 126.859575ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:45.338817Z","caller":"traceutil/trace.go:171","msg":"trace[1886598862] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"128.94755ms","start":"2026-05-20T03:08:45.209848Z","end":"2026-05-20T03:08:45.338796Z","steps":["trace[1886598862] 'process raft request' (duration: 128.783217ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:45.339076Z","caller":"traceutil/trace.go:171","msg":"trace[1244797053] linearizableReadLoop","detail":"{readStateIndex:2114; appliedIndex:2112; }","duration":"114.731893ms","start":"2026-05-20T03:08:45.224319Z","end":"2026-05-20T03:08:45.339049Z","steps":["trace[1244797053] 'read index received' (duration: 109.964733ms)","trace[1244797053] 'applied index is now lower than readState.Index' (duration: 4.76388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:45.339333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.988187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-05-20T03:08:45.339406Z","caller":"traceutil/trace.go:171","msg":"trace[1288700573] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2039; }","duration":"115.115139ms","start":"2026-05-20T03:08:45.224265Z","end":"2026-05-20T03:08:45.339381Z","steps":["trace[1288700573] 'agreement among raft nodes before linearized reading' (duration: 114.904396ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:45.591215Z","caller":"traceutil/trace.go:171","msg":"trace[995573825] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"247.399014ms","start":"2026-05-20T03:08:45.343771Z","end":"2026-05-20T03:08:45.59117Z","steps":["trace[995573825] 'process raft request' (duration: 247.265592ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:45.591253Z","caller":"traceutil/trace.go:171","msg":"trace[432437168] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"247.784401ms","start":"2026-05-20T03:08:45.343437Z","end":"2026-05-20T03:08:45.591222Z","steps":["trace[432437168] 'process raft request' (duration: 247.429605ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:45.591622Z","caller":"traceutil/trace.go:171","msg":"trace[28661763] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"247.7816ms","start":"2026-05-20T03:08:45.343815Z","end":"2026-05-20T03:08:45.591597Z","steps":["trace[28661763] 'process raft request' (duration: 247.301742ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:45.593106Z","caller":"traceutil/trace.go:171","msg":"trace[500216533] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"248.284249ms","start":"2026-05-20T03:08:45.344795Z","end":"2026-05-20T03:08:45.59308Z","steps":["trace[500216533] 'process raft request' (duration: 246.718693ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:45.593485Z","caller":"traceutil/trace.go:171","msg":"trace[1596897878] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"248.491444ms","start":"2026-05-20T03:08:45.344976Z","end":"2026-05-20T03:08:45.593467Z","steps":["trace[1596897878] 'process raft request' (duration: 247.924324ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:45.598416Z","caller":"traceutil/trace.go:171","msg":"trace[556171538] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"232.500026ms","start":"2026-05-20T03:08:45.365889Z","end":"2026-05-20T03:08:45.598389Z","steps":["trace[556171538] 'process raft request' (duration: 232.401224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.094086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.470933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-20T03:08:48.09415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.002001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-20T03:08:48.094174Z","caller":"traceutil/trace.go:171","msg":"trace[1325710224] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2052; }","duration":"631.634476ms","start":"2026-05-20T03:08:47.462517Z","end":"2026-05-20T03:08:48.094151Z","steps":["trace[1325710224] 'range keys from in-memory index tree' (duration: 631.2329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.094222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.462498Z","time spent":"631.714367ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-20T03:08:48.094217Z","caller":"traceutil/trace.go:171","msg":"trace[1344876137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2052; }","duration":"498.099432ms","start":"2026-05-20T03:08:47.5961Z","end":"2026-05-20T03:08:48.0942Z","steps":["trace[1344876137] 'range keys from in-memory index tree' (duration: 497.884659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.094422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.596089Z","time spent":"498.321256ms","remote":"127.0.0.1:48430","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-05-20T03:08:48.094469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.389716ms","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-05-20T03:08:48.094508Z","caller":"traceutil/trace.go:171","msg":"trace[1621881144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2052; }","duration":"498.452328ms","start":"2026-05-20T03:08:47.596044Z","end":"2026-05-20T03:08:48.094496Z","steps":["trace[1621881144] 'range keys from in-memory index tree' (duration: 498.237004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.094536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.596027Z","time spent":"498.502638ms","remote":"127.0.0.1:48430","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-05-20T03:08:48.09465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.173477ms","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-05-20T03:08:48.09468Z","caller":"traceutil/trace.go:171","msg":"trace[81131577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2052; }","duration":"497.229258ms","start":"2026-05-20T03:08:47.597444Z","end":"2026-05-20T03:08:48.094673Z","steps":["trace[81131577] 'range keys from in-memory index tree' (duration: 497.065616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.094705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.59743Z","time spent":"497.268848ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T03:08:48.094908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.034517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:08:48.094936Z","caller":"traceutil/trace.go:171","msg":"trace[515693573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2052; }","duration":"499.107958ms","start":"2026-05-20T03:08:47.595821Z","end":"2026-05-20T03:08:48.094929Z","steps":["trace[515693573] 'range keys from in-memory index tree' (duration: 498.910805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.094956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.595782Z","time spent":"499.168749ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T03:08:48.095287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.762672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:08:48.095383Z","caller":"traceutil/trace.go:171","msg":"trace[1041202313] linearizableReadLoop","detail":"{readStateIndex:2129; appliedIndex:2128; }","duration":"467.246423ms","start":"2026-05-20T03:08:47.628121Z","end":"2026-05-20T03:08:48.095368Z","steps":["trace[1041202313] 'read index received' (duration: 265.330509ms)","trace[1041202313] 'applied index is now lower than readState.Index' (duration: 201.914434ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:48.09567Z","caller":"traceutil/trace.go:171","msg":"trace[784344220] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"493.579238ms","start":"2026-05-20T03:08:47.602073Z","end":"2026-05-20T03:08:48.095653Z","steps":["trace[784344220] 'process raft request' (duration: 291.366459ms)","trace[784344220] 'compare' (duration: 200.542842ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:48.09576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.602062Z","time spent":"493.653549ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:08:48.095909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.187953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:48.095989Z","caller":"traceutil/trace.go:171","msg":"trace[1181120215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2053; }","duration":"358.299405ms","start":"2026-05-20T03:08:47.737667Z","end":"2026-05-20T03:08:48.095966Z","steps":["trace[1181120215] 'agreement among raft nodes before linearized reading' (duration: 358.180913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.096115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.737648Z","time spent":"358.450838ms","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:08:48.095986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.871883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-20T03:08:48.096356Z","caller":"traceutil/trace.go:171","msg":"trace[149483371] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2053; }","duration":"468.27652ms","start":"2026-05-20T03:08:47.628069Z","end":"2026-05-20T03:08:48.096346Z","steps":["trace[149483371] 'agreement among raft nodes before linearized reading' (duration: 467.69064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.096431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.628053Z","time spent":"468.32219ms","remote":"127.0.0.1:48640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":255,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:08:48.09602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.28284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-20T03:08:48.098139Z","caller":"traceutil/trace.go:171","msg":"trace[1732849360] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2053; }","duration":"364.446106ms","start":"2026-05-20T03:08:47.733661Z","end":"2026-05-20T03:08:48.098107Z","steps":["trace[1732849360] 'agreement among raft nodes before linearized reading' (duration: 362.125848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.098221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:47.733642Z","time spent":"364.558678ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-20T03:08:48.500348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.032188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:08:48.500433Z","caller":"traceutil/trace.go:171","msg":"trace[1921487392] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"400.704819ms","start":"2026-05-20T03:08:48.099712Z","end":"2026-05-20T03:08:48.500417Z","steps":["trace[1921487392] 'process raft request' (duration: 237.497178ms)","trace[1921487392] 'compare' (duration: 162.663492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:48.500478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:48.099686Z","time spent":"400.770931ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:08:48.750528Z","caller":"traceutil/trace.go:171","msg":"trace[345653658] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"650.322058ms","start":"2026-05-20T03:08:48.100181Z","end":"2026-05-20T03:08:48.750503Z","steps":["trace[345653658] 'process raft request' (duration: 650.121615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.750699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:48.100164Z","time spent":"650.441739ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:08:48.750821Z","caller":"traceutil/trace.go:171","msg":"trace[1513759523] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"649.242291ms","start":"2026-05-20T03:08:48.101556Z","end":"2026-05-20T03:08:48.750799Z","steps":["trace[1513759523] 'process raft request' (duration: 648.902385ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:48.750838Z","caller":"traceutil/trace.go:171","msg":"trace[535639548] linearizableReadLoop","detail":"{readStateIndex:2134; appliedIndex:2129; }","duration":"577.285438ms","start":"2026-05-20T03:08:48.173535Z","end":"2026-05-20T03:08:48.75082Z","steps":["trace[535639548] 'read index received' (duration: 163.692346ms)","trace[535639548] 'applied index is now lower than readState.Index' (duration: 413.592102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:48.750945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:48.10154Z","time spent":"649.335562ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:08:48.751044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.508892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:48.7511Z","caller":"traceutil/trace.go:171","msg":"trace[1304979616] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2058; }","duration":"577.582113ms","start":"2026-05-20T03:08:48.173491Z","end":"2026-05-20T03:08:48.751073Z","steps":["trace[1304979616] 'agreement among raft nodes before linearized reading' (duration: 577.513472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.751134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:48.17347Z","time spent":"577.654605ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:08:48.751295Z","caller":"traceutil/trace.go:171","msg":"trace[707259174] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"649.665187ms","start":"2026-05-20T03:08:48.101618Z","end":"2026-05-20T03:08:48.751283Z","steps":["trace[707259174] 'process raft request' (duration: 649.080988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.751353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:48.101611Z","time spent":"649.709158ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:08:48.751336Z","caller":"traceutil/trace.go:171","msg":"trace[462047086] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"648.932314ms","start":"2026-05-20T03:08:48.102392Z","end":"2026-05-20T03:08:48.751324Z","steps":["trace[462047086] 'process raft request' (duration: 648.389015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:48.751546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:48.102388Z","time spent":"649.124127ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:08:49.479523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.556465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b127496438fb8a\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-20T03:08:49.479615Z","caller":"traceutil/trace.go:171","msg":"trace[2130557457] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b127496438fb8a; range_end:; response_count:1; response_revision:2058; }","duration":"424.707718ms","start":"2026-05-20T03:08:49.054886Z","end":"2026-05-20T03:08:49.479593Z","steps":["trace[2130557457] 'range keys from in-memory index tree' (duration: 424.406273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:49.479656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:49.054858Z","time spent":"424.78851ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b127496438fb8a\" "} {"level":"warn","ts":"2026-05-20T03:08:49.479949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.946719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-20T03:08:49.480156Z","caller":"traceutil/trace.go:171","msg":"trace[1661661255] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2058; }","duration":"374.204543ms","start":"2026-05-20T03:08:49.105931Z","end":"2026-05-20T03:08:49.480135Z","steps":["trace[1661661255] 'range keys from in-memory index tree' (duration: 373.768036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:49.480234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:49.105909Z","time spent":"374.293615ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-20T03:08:49.481987Z","caller":"traceutil/trace.go:171","msg":"trace[966858875] linearizableReadLoop","detail":"{readStateIndex:2135; appliedIndex:2134; }","duration":"240.289486ms","start":"2026-05-20T03:08:49.241667Z","end":"2026-05-20T03:08:49.481957Z","steps":["trace[966858875] 'read index received' (duration: 234.209147ms)","trace[966858875] 'applied index is now lower than readState.Index' (duration: 6.078379ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:49.482306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.627261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-05-20T03:08:49.48235Z","caller":"traceutil/trace.go:171","msg":"trace[511787716] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2058; }","duration":"240.727173ms","start":"2026-05-20T03:08:49.241612Z","end":"2026-05-20T03:08:49.482339Z","steps":["trace[511787716] 'agreement among raft nodes before linearized reading' (duration: 240.499459ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:49.482981Z","caller":"traceutil/trace.go:171","msg":"trace[1238321179] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"204.375239ms","start":"2026-05-20T03:08:49.278592Z","end":"2026-05-20T03:08:49.482967Z","steps":["trace[1238321179] 'process raft request' (duration: 203.21862ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:49.483097Z","caller":"traceutil/trace.go:171","msg":"trace[1923075167] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"111.379709ms","start":"2026-05-20T03:08:49.371708Z","end":"2026-05-20T03:08:49.483088Z","steps":["trace[1923075167] 'process raft request' (duration: 111.161805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:25.426184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.287555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:09:25.426276Z","caller":"traceutil/trace.go:171","msg":"trace[1271096163] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2263; }","duration":"241.488878ms","start":"2026-05-20T03:09:25.184768Z","end":"2026-05-20T03:09:25.426256Z","steps":["trace[1271096163] 'count revisions from in-memory index tree' (duration: 241.163334ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:02.777742Z","caller":"traceutil/trace.go:171","msg":"trace[547721858] linearizableReadLoop","detail":"{readStateIndex:2648; appliedIndex:2647; }","duration":"325.568046ms","start":"2026-05-20T03:10:02.452153Z","end":"2026-05-20T03:10:02.777721Z","steps":["trace[547721858] 'read index received' (duration: 325.341653ms)","trace[547721858] 'applied index is now lower than readState.Index' (duration: 225.603µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:10:02.777774Z","caller":"traceutil/trace.go:171","msg":"trace[928396287] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"333.026532ms","start":"2026-05-20T03:10:02.444723Z","end":"2026-05-20T03:10:02.77775Z","steps":["trace[928396287] 'process raft request' (duration: 332.863779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:02.77788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:02.444705Z","time spent":"333.120413ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:10:02.777904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.764989ms","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-05-20T03:10:02.777968Z","caller":"traceutil/trace.go:171","msg":"trace[316246352] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2555; }","duration":"325.88134ms","start":"2026-05-20T03:10:02.452069Z","end":"2026-05-20T03:10:02.777951Z","steps":["trace[316246352] 'agreement among raft nodes before linearized reading' (duration: 325.772989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:02.778007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:02.452047Z","time spent":"325.947332ms","remote":"127.0.0.1:48590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:10:02.778224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.651453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-20T03:10:02.778353Z","caller":"traceutil/trace.go:171","msg":"trace[1229576339] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2555; }","duration":"228.828966ms","start":"2026-05-20T03:10:02.549493Z","end":"2026-05-20T03:10:02.778322Z","steps":["trace[1229576339] 'agreement among raft nodes before linearized reading' (duration: 228.439841ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:30.388544Z","caller":"traceutil/trace.go:171","msg":"trace[972122689] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"476.546687ms","start":"2026-05-20T03:10:29.911975Z","end":"2026-05-20T03:10:30.388522Z","steps":["trace[972122689] 'process raft request' (duration: 476.418446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:30.388671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:29.911948Z","time spent":"476.660388ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:10:30.409179Z","caller":"traceutil/trace.go:171","msg":"trace[221404196] linearizableReadLoop","detail":"{readStateIndex:2807; appliedIndex:2804; }","duration":"446.951994ms","start":"2026-05-20T03:10:29.962206Z","end":"2026-05-20T03:10:30.409158Z","steps":["trace[221404196] 'read index received' (duration: 426.23117ms)","trace[221404196] 'applied index is now lower than readState.Index' (duration: 20.720324ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:10:30.409489Z","caller":"traceutil/trace.go:171","msg":"trace[1703433437] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"495.978915ms","start":"2026-05-20T03:10:29.913474Z","end":"2026-05-20T03:10:30.409452Z","steps":["trace[1703433437] 'process raft request' (duration: 495.56227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:30.409542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.982713ms","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-05-20T03:10:30.409522Z","caller":"traceutil/trace.go:171","msg":"trace[1327630367] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"485.033812ms","start":"2026-05-20T03:10:29.924453Z","end":"2026-05-20T03:10:30.409487Z","steps":["trace[1327630367] 'process raft request' (duration: 484.677457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:30.409662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:29.913446Z","time spent":"496.092856ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:10:30.409688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:29.924429Z","time spent":"485.175453ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:10:30.409709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.963993ms","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":"info","ts":"2026-05-20T03:10:30.40973Z","caller":"traceutil/trace.go:171","msg":"trace[305910040] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2707; }","duration":"113.029795ms","start":"2026-05-20T03:10:30.296693Z","end":"2026-05-20T03:10:30.409723Z","steps":["trace[305910040] 'agreement among raft nodes before linearized reading' (duration: 112.996064ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:30.409565Z","caller":"traceutil/trace.go:171","msg":"trace[1080586400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2707; }","duration":"380.017924ms","start":"2026-05-20T03:10:30.02954Z","end":"2026-05-20T03:10:30.409557Z","steps":["trace[1080586400] 'agreement among raft nodes before linearized reading' (duration: 379.922802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:30.409898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:30.029504Z","time spent":"380.382807ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T03:10:30.410072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.844444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:10:30.41012Z","caller":"traceutil/trace.go:171","msg":"trace[1983769170] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2707; }","duration":"447.956317ms","start":"2026-05-20T03:10:29.962153Z","end":"2026-05-20T03:10:30.410109Z","steps":["trace[1983769170] 'agreement among raft nodes before linearized reading' (duration: 447.263748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:30.41026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:29.962132Z","time spent":"448.071528ms","remote":"127.0.0.1:60708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":15,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:10:30.716103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.914847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-20T03:10:30.716188Z","caller":"traceutil/trace.go:171","msg":"trace[2101064704] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2708; }","duration":"173.04686ms","start":"2026-05-20T03:10:30.543122Z","end":"2026-05-20T03:10:30.716169Z","steps":["trace[2101064704] 'range keys from in-memory index tree' (duration: 172.675355ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:30.932108Z","caller":"traceutil/trace.go:171","msg":"trace[1269276810] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"115.736138ms","start":"2026-05-20T03:10:30.816342Z","end":"2026-05-20T03:10:30.932078Z","steps":["trace[1269276810] 'process raft request' (duration: 115.498725ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:30.998391Z","caller":"traceutil/trace.go:171","msg":"trace[1062400410] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"125.04773ms","start":"2026-05-20T03:10:30.873277Z","end":"2026-05-20T03:10:30.998324Z","steps":["trace[1062400410] 'process raft request' (duration: 91.914825ms)","trace[1062400410] 'compare' (duration: 32.67752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:30.998724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.362993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:10:30.999274Z","caller":"traceutil/trace.go:171","msg":"trace[1182972845] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2711; }","duration":"134.070362ms","start":"2026-05-20T03:10:30.865188Z","end":"2026-05-20T03:10:30.999259Z","steps":["trace[1182972845] 'agreement among raft nodes before linearized reading' (duration: 133.310483ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:36.612438Z","caller":"traceutil/trace.go:171","msg":"trace[1409513883] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"203.802156ms","start":"2026-05-20T03:10:36.40861Z","end":"2026-05-20T03:10:36.612412Z","steps":["trace[1409513883] 'process raft request' (duration: 203.608223ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:36.62204Z","caller":"traceutil/trace.go:171","msg":"trace[1813530253] linearizableReadLoop","detail":"{readStateIndex:2842; appliedIndex:2839; }","duration":"169.079494ms","start":"2026-05-20T03:10:36.452948Z","end":"2026-05-20T03:10:36.622027Z","steps":["trace[1813530253] 'read index received' (duration: 159.361697ms)","trace[1813530253] 'applied index is now lower than readState.Index' (duration: 9.716897ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:10:36.622161Z","caller":"traceutil/trace.go:171","msg":"trace[495343449] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"193.666313ms","start":"2026-05-20T03:10:36.428484Z","end":"2026-05-20T03:10:36.62215Z","steps":["trace[495343449] 'process raft request' (duration: 193.514001ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:36.62223Z","caller":"traceutil/trace.go:171","msg":"trace[1647036923] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"195.202992ms","start":"2026-05-20T03:10:36.426881Z","end":"2026-05-20T03:10:36.622084Z","steps":["trace[1647036923] 'process raft request' (duration: 195.06506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:36.622843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.843943ms","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-05-20T03:10:36.623188Z","caller":"traceutil/trace.go:171","msg":"trace[248278962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2741; }","duration":"170.261238ms","start":"2026-05-20T03:10:36.452904Z","end":"2026-05-20T03:10:36.623165Z","steps":["trace[248278962] 'agreement among raft nodes before linearized reading' (duration: 169.62153ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:37.279268Z","caller":"traceutil/trace.go:171","msg":"trace[1943825240] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"257.352224ms","start":"2026-05-20T03:10:37.021877Z","end":"2026-05-20T03:10:37.279229Z","steps":["trace[1943825240] 'process raft request' (duration: 257.169942ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:37.392171Z","caller":"traceutil/trace.go:171","msg":"trace[290720182] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"106.360621ms","start":"2026-05-20T03:10:37.285772Z","end":"2026-05-20T03:10:37.392133Z","steps":["trace[290720182] 'process raft request' (duration: 99.377756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:40.565611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186411515215472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:12:40.952222Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159622048s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T03:12:40.952608Z","caller":"traceutil/trace.go:171","msg":"trace[990481597] linearizableReadLoop","detail":"{readStateIndex:3606; appliedIndex:3605; }","duration":"887.237486ms","start":"2026-05-20T03:12:40.065348Z","end":"2026-05-20T03:12:40.952585Z","steps":["trace[990481597] 'read index received' (duration: 887.045881ms)","trace[990481597] 'applied index is now lower than readState.Index' (duration: 190.395µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:40.952864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.527184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:12:40.952702Z","caller":"traceutil/trace.go:171","msg":"trace[2114011160] transaction","detail":"{read_only:false; response_revision:3475; number_of_response:1; }","duration":"1.160234454s","start":"2026-05-20T03:12:39.792441Z","end":"2026-05-20T03:12:40.952676Z","steps":["trace[2114011160] 'process raft request' (duration: 1.159973057s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:40.952939Z","caller":"traceutil/trace.go:171","msg":"trace[1615489710] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:3475; }","duration":"161.655178ms","start":"2026-05-20T03:12:40.791273Z","end":"2026-05-20T03:12:40.952928Z","steps":["trace[1615489710] 'agreement among raft nodes before linearized reading' (duration: 161.473363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:40.953032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.967573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T03:12:40.953037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:39.792413Z","time spent":"1.160549643s","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:40.952873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.506243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:12:40.953082Z","caller":"traceutil/trace.go:171","msg":"trace[847255498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3475; }","duration":"885.044925ms","start":"2026-05-20T03:12:40.068017Z","end":"2026-05-20T03:12:40.953062Z","steps":["trace[847255498] 'agreement among raft nodes before linearized reading' (duration: 884.969103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:40.95319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:40.068007Z","time spent":"885.174658ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T03:12:40.953197Z","caller":"traceutil/trace.go:171","msg":"trace[1726824036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3475; }","duration":"887.864413ms","start":"2026-05-20T03:12:40.0653Z","end":"2026-05-20T03:12:40.953164Z","steps":["trace[1726824036] 'agreement among raft nodes before linearized reading' (duration: 887.424791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:40.953242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:40.065282Z","time spent":"887.948125ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T03:12:40.95287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.765379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:12:40.953298Z","caller":"traceutil/trace.go:171","msg":"trace[990040402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3475; }","duration":"794.253992ms","start":"2026-05-20T03:12:40.159035Z","end":"2026-05-20T03:12:40.953289Z","steps":["trace[990040402] 'agreement among raft nodes before linearized reading' (duration: 793.739588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:40.953329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:40.159012Z","time spent":"794.309604ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T03:12:40.952896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.578201ms","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-05-20T03:12:40.953398Z","caller":"traceutil/trace.go:171","msg":"trace[692275603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3475; }","duration":"770.102946ms","start":"2026-05-20T03:12:40.183285Z","end":"2026-05-20T03:12:40.953388Z","steps":["trace[692275603] 'agreement among raft nodes before linearized reading' (duration: 769.456188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:40.953429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:40.183274Z","time spent":"770.146556ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T03:13:14.380992Z","caller":"traceutil/trace.go:171","msg":"trace[745064979] linearizableReadLoop","detail":"{readStateIndex:3799; appliedIndex:3798; }","duration":"238.63746ms","start":"2026-05-20T03:13:14.142339Z","end":"2026-05-20T03:13:14.380977Z","steps":["trace[745064979] 'read index received' (duration: 236.858386ms)","trace[745064979] 'applied index is now lower than readState.Index' (duration: 1.778084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:13:14.381328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.967468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:13:14.381384Z","caller":"traceutil/trace.go:171","msg":"trace[70265747] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:3661; }","duration":"239.08196ms","start":"2026-05-20T03:13:14.142293Z","end":"2026-05-20T03:13:14.381375Z","steps":["trace[70265747] 'agreement among raft nodes before linearized reading' (duration: 238.925057ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:13:14.381009Z","caller":"traceutil/trace.go:171","msg":"trace[1971232589] transaction","detail":"{read_only:false; response_revision:3661; number_of_response:1; }","duration":"386.959682ms","start":"2026-05-20T03:13:13.994027Z","end":"2026-05-20T03:13:14.380987Z","steps":["trace[1971232589] 'process raft request' (duration: 385.180658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:13:14.381599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:13:13.993999Z","time spent":"387.548286ms","remote":"127.0.0.1:48166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:14:19.281863Z","caller":"traceutil/trace.go:171","msg":"trace[1285599035] linearizableReadLoop","detail":"{readStateIndex:4644; appliedIndex:4643; }","duration":"203.340159ms","start":"2026-05-20T03:14:19.078491Z","end":"2026-05-20T03:14:19.281831Z","steps":["trace[1285599035] 'read index received' (duration: 173.481523ms)","trace[1285599035] 'applied index is now lower than readState.Index' (duration: 29.857336ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:19.282019Z","caller":"traceutil/trace.go:171","msg":"trace[1994706751] transaction","detail":"{read_only:false; response_revision:4473; number_of_response:1; }","duration":"204.917121ms","start":"2026-05-20T03:14:19.077082Z","end":"2026-05-20T03:14:19.281999Z","steps":["trace[1994706751] 'process raft request' (duration: 174.878642ms)","trace[1994706751] 'compare' (duration: 29.628271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:14:19.282479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.963491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-20T03:14:19.283005Z","caller":"traceutil/trace.go:171","msg":"trace[2107113437] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4473; }","duration":"204.213817ms","start":"2026-05-20T03:14:19.078457Z","end":"2026-05-20T03:14:19.282671Z","steps":["trace[2107113437] 'agreement among raft nodes before linearized reading' (duration: 203.620075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:19.322129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.637077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-20T03:14:19.322167Z","caller":"traceutil/trace.go:171","msg":"trace[260333065] transaction","detail":"{read_only:false; response_revision:4475; number_of_response:1; }","duration":"232.049109ms","start":"2026-05-20T03:14:19.090092Z","end":"2026-05-20T03:14:19.322141Z","steps":["trace[260333065] 'process raft request' (duration: 231.848445ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:19.322218Z","caller":"traceutil/trace.go:171","msg":"trace[2079744216] transaction","detail":"{read_only:false; response_revision:4474; number_of_response:1; }","duration":"232.914188ms","start":"2026-05-20T03:14:19.089267Z","end":"2026-05-20T03:14:19.322181Z","steps":["trace[2079744216] 'process raft request' (duration: 232.53687ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:19.322201Z","caller":"traceutil/trace.go:171","msg":"trace[1024865995] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4475; }","duration":"242.74852ms","start":"2026-05-20T03:14:19.079437Z","end":"2026-05-20T03:14:19.322185Z","steps":["trace[1024865995] 'agreement among raft nodes before linearized reading' (duration: 242.570096ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:24.385835Z","caller":"traceutil/trace.go:171","msg":"trace[989131427] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"117.047074ms","start":"2026-05-20T03:14:24.268757Z","end":"2026-05-20T03:14:24.385804Z","steps":["trace[989131427] 'process raft request' (duration: 37.79864ms)","trace[989131427] 'compare' (duration: 79.103362ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:24.665482Z","caller":"traceutil/trace.go:171","msg":"trace[1472420540] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"216.332866ms","start":"2026-05-20T03:14:24.449128Z","end":"2026-05-20T03:14:24.665461Z","steps":["trace[1472420540] 'process raft request' (duration: 176.79148ms)","trace[1472420540] 'compare' (duration: 39.456484ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:25.669816Z","caller":"traceutil/trace.go:171","msg":"trace[878963550] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"322.021845ms","start":"2026-05-20T03:14:25.34777Z","end":"2026-05-20T03:14:25.669792Z","steps":["trace[878963550] 'process raft request' (duration: 321.878082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:25.670111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:25.347749Z","time spent":"322.132847ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:14:25.670888Z","caller":"traceutil/trace.go:171","msg":"trace[2080643179] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"259.634277ms","start":"2026-05-20T03:14:25.411232Z","end":"2026-05-20T03:14:25.670866Z","steps":["trace[2080643179] 'process raft request' (duration: 259.310071ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:25.671184Z","caller":"traceutil/trace.go:171","msg":"trace[1231519375] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"237.731801ms","start":"2026-05-20T03:14:25.433436Z","end":"2026-05-20T03:14:25.671168Z","steps":["trace[1231519375] 'process raft request' (duration: 237.237151ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:31.271397Z","caller":"traceutil/trace.go:171","msg":"trace[1795057010] linearizableReadLoop","detail":"{readStateIndex:4936; appliedIndex:4935; }","duration":"120.811084ms","start":"2026-05-20T03:14:31.150559Z","end":"2026-05-20T03:14:31.271371Z","steps":["trace[1795057010] 'read index received' (duration: 120.685651ms)","trace[1795057010] 'applied index is now lower than readState.Index' (duration: 124.323µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:14:31.272605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.021707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2196733"} {"level":"info","ts":"2026-05-20T03:14:31.272484Z","caller":"traceutil/trace.go:171","msg":"trace[618326035] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"242.514765ms","start":"2026-05-20T03:14:31.029877Z","end":"2026-05-20T03:14:31.272392Z","steps":["trace[618326035] 'process raft request' (duration: 241.335462ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:31.272677Z","caller":"traceutil/trace.go:171","msg":"trace[103735366] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4758; }","duration":"122.14385ms","start":"2026-05-20T03:14:31.150518Z","end":"2026-05-20T03:14:31.272662Z","steps":["trace[103735366] 'agreement among raft nodes before linearized reading' (duration: 121.046908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.783289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186411515218360,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T03:14:31.978696Z","caller":"traceutil/trace.go:171","msg":"trace[550940818] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"697.381623ms","start":"2026-05-20T03:14:31.281276Z","end":"2026-05-20T03:14:31.978657Z","steps":["trace[550940818] 'process raft request' (duration: 664.402802ms)","trace[550940818] 'compare' (duration: 32.734486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:14:31.97898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:31.281258Z","time spent":"697.619718ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:14:31.979093Z","caller":"traceutil/trace.go:171","msg":"trace[190970093] linearizableReadLoop","detail":"{readStateIndex:4942; appliedIndex:4937; }","duration":"696.854493ms","start":"2026-05-20T03:14:31.282219Z","end":"2026-05-20T03:14:31.979073Z","steps":["trace[190970093] 'read index received' (duration: 663.469203ms)","trace[190970093] 'applied index is now lower than readState.Index' (duration: 33.38399ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:31.978868Z","caller":"traceutil/trace.go:171","msg":"trace[51511612] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"266.834434ms","start":"2026-05-20T03:14:31.712025Z","end":"2026-05-20T03:14:31.97886Z","steps":["trace[51511612] 'process raft request' (duration: 266.64925ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:31.979395Z","caller":"traceutil/trace.go:171","msg":"trace[1366901958] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"266.773834ms","start":"2026-05-20T03:14:31.712604Z","end":"2026-05-20T03:14:31.979378Z","steps":["trace[1366901958] 'process raft request' (duration: 266.225002ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:31.978727Z","caller":"traceutil/trace.go:171","msg":"trace[1548527804] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"266.699341ms","start":"2026-05-20T03:14:31.712006Z","end":"2026-05-20T03:14:31.978705Z","steps":["trace[1548527804] 'process raft request' (duration: 266.630399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.97986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.624258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-05-20T03:14:31.979923Z","caller":"traceutil/trace.go:171","msg":"trace[1007085353] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4764; }","duration":"697.693589ms","start":"2026-05-20T03:14:31.282213Z","end":"2026-05-20T03:14:31.979907Z","steps":["trace[1007085353] 'agreement among raft nodes before linearized reading' (duration: 697.505116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.979971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:31.282191Z","time spent":"697.76424ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12998,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-20T03:14:31.980075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.958586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-20T03:14:31.980078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.931165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-20T03:14:31.980107Z","caller":"traceutil/trace.go:171","msg":"trace[1312798311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4764; }","duration":"262.037048ms","start":"2026-05-20T03:14:31.718061Z","end":"2026-05-20T03:14:31.980098Z","steps":["trace[1312798311] 'agreement among raft nodes before linearized reading' (duration: 261.910376ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:31.980127Z","caller":"traceutil/trace.go:171","msg":"trace[971574916] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4764; }","duration":"697.018826ms","start":"2026-05-20T03:14:31.283093Z","end":"2026-05-20T03:14:31.980112Z","steps":["trace[971574916] 'agreement among raft nodes before linearized reading' (duration: 696.882313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.98016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.444869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-05-20T03:14:31.980176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:31.283074Z","time spent":"697.092348ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-20T03:14:31.980215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.642474ms","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-05-20T03:14:31.980232Z","caller":"traceutil/trace.go:171","msg":"trace[873215900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4764; }","duration":"182.724546ms","start":"2026-05-20T03:14:31.797503Z","end":"2026-05-20T03:14:31.980227Z","steps":["trace[873215900] 'agreement among raft nodes before linearized reading' (duration: 182.672275ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:31.980227Z","caller":"traceutil/trace.go:171","msg":"trace[1651127901] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4764; }","duration":"618.543191ms","start":"2026-05-20T03:14:31.361667Z","end":"2026-05-20T03:14:31.98021Z","steps":["trace[1651127901] 'agreement among raft nodes before linearized reading' (duration: 618.358808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.980288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.272912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-20T03:14:31.98018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.684937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97a1e47a-5124-43ae-9d5d-d44fbfd50486\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-20T03:14:31.980322Z","caller":"traceutil/trace.go:171","msg":"trace[1749070173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4764; }","duration":"264.329953ms","start":"2026-05-20T03:14:31.71598Z","end":"2026-05-20T03:14:31.98031Z","steps":["trace[1749070173] 'agreement among raft nodes before linearized reading' (duration: 264.239192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.980313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:31.361649Z","time spent":"618.649473ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-20T03:14:31.980372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.873635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-20T03:14:31.980393Z","caller":"traceutil/trace.go:171","msg":"trace[1277111366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4764; }","duration":"260.918915ms","start":"2026-05-20T03:14:31.719468Z","end":"2026-05-20T03:14:31.980387Z","steps":["trace[1277111366] 'agreement among raft nodes before linearized reading' (duration: 260.879554ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:31.980372Z","caller":"traceutil/trace.go:171","msg":"trace[1392999318] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97a1e47a-5124-43ae-9d5d-d44fbfd50486; range_end:; response_count:1; response_revision:4764; }","duration":"355.925671ms","start":"2026-05-20T03:14:31.624422Z","end":"2026-05-20T03:14:31.980348Z","steps":["trace[1392999318] 'agreement among raft nodes before linearized reading' (duration: 355.582804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.980338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.769683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T03:14:31.980431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:31.624405Z","time spent":"356.006533ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97a1e47a-5124-43ae-9d5d-d44fbfd50486\" "} {"level":"info","ts":"2026-05-20T03:14:31.980458Z","caller":"traceutil/trace.go:171","msg":"trace[1450080824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4764; }","duration":"259.894125ms","start":"2026-05-20T03:14:31.720548Z","end":"2026-05-20T03:14:31.980442Z","steps":["trace[1450080824] 'agreement among raft nodes before linearized reading' (duration: 259.759502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.980494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.748289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-05-20T03:14:31.980297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.911464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:14:31.980519Z","caller":"traceutil/trace.go:171","msg":"trace[234058438] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4764; }","duration":"678.80466ms","start":"2026-05-20T03:14:31.301706Z","end":"2026-05-20T03:14:31.98051Z","steps":["trace[234058438] 'agreement among raft nodes before linearized reading' (duration: 678.729919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:31.980544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:31.301682Z","time spent":"678.856351ms","remote":"127.0.0.1:48244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1841,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-05-20T03:14:31.978787Z","caller":"traceutil/trace.go:171","msg":"trace[490730283] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"288.14122ms","start":"2026-05-20T03:14:31.690636Z","end":"2026-05-20T03:14:31.978777Z","steps":["trace[490730283] 'process raft request' (duration: 287.941126ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:31.980552Z","caller":"traceutil/trace.go:171","msg":"trace[947176445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4764; }","duration":"243.18464ms","start":"2026-05-20T03:14:31.737351Z","end":"2026-05-20T03:14:31.980535Z","steps":["trace[947176445] 'agreement among raft nodes before linearized reading' (duration: 242.933075ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:32.155802Z","caller":"traceutil/trace.go:171","msg":"trace[2118575109] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"168.79589ms","start":"2026-05-20T03:14:31.986975Z","end":"2026-05-20T03:14:32.155771Z","steps":["trace[2118575109] 'process raft request' (duration: 159.004424ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:32.155894Z","caller":"traceutil/trace.go:171","msg":"trace[1188609911] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"168.191328ms","start":"2026-05-20T03:14:31.987675Z","end":"2026-05-20T03:14:32.155867Z","steps":["trace[1188609911] 'process raft request' (duration: 168.067046ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:32.156023Z","caller":"traceutil/trace.go:171","msg":"trace[437579609] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"168.391752ms","start":"2026-05-20T03:14:31.987622Z","end":"2026-05-20T03:14:32.156014Z","steps":["trace[437579609] 'process raft request' (duration: 168.085176ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:32.156081Z","caller":"traceutil/trace.go:171","msg":"trace[603264136] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"168.977264ms","start":"2026-05-20T03:14:31.987059Z","end":"2026-05-20T03:14:32.156036Z","steps":["trace[603264136] 'process raft request' (duration: 168.606047ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:32.156158Z","caller":"traceutil/trace.go:171","msg":"trace[1216909330] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"168.135626ms","start":"2026-05-20T03:14:31.988007Z","end":"2026-05-20T03:14:32.156142Z","steps":["trace[1216909330] 'process raft request' (duration: 167.80055ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:32.156452Z","caller":"traceutil/trace.go:171","msg":"trace[733837750] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"165.780359ms","start":"2026-05-20T03:14:31.990644Z","end":"2026-05-20T03:14:32.156425Z","steps":["trace[733837750] 'process raft request' (duration: 165.517664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:32.156501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.086446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6cb9c8c589-f9ptc.18b12799fe273a1a\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-20T03:14:32.156672Z","caller":"traceutil/trace.go:171","msg":"trace[1110577727] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6cb9c8c589-f9ptc.18b12799fe273a1a; range_end:; response_count:1; response_revision:4771; }","duration":"168.274899ms","start":"2026-05-20T03:14:31.988373Z","end":"2026-05-20T03:14:32.156647Z","steps":["trace[1110577727] 'agreement among raft nodes before linearized reading' (duration: 168.006994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:32.156783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.970953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-20T03:14:32.156839Z","caller":"traceutil/trace.go:171","msg":"trace[1160399733] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4771; }","duration":"153.072205ms","start":"2026-05-20T03:14:32.003756Z","end":"2026-05-20T03:14:32.156829Z","steps":["trace[1160399733] 'agreement among raft nodes before linearized reading' (duration: 152.870831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:32.773146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.863669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:14:32.773438Z","caller":"traceutil/trace.go:171","msg":"trace[775675699] linearizableReadLoop","detail":"{readStateIndex:4954; appliedIndex:4950; }","duration":"579.053244ms","start":"2026-05-20T03:14:32.194368Z","end":"2026-05-20T03:14:32.773421Z","steps":["trace[775675699] 'read index received' (duration: 86.760127ms)","trace[775675699] 'applied index is now lower than readState.Index' (duration: 492.292247ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:32.773532Z","caller":"traceutil/trace.go:171","msg":"trace[268938145] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"609.115535ms","start":"2026-05-20T03:14:32.164406Z","end":"2026-05-20T03:14:32.773521Z","steps":["trace[268938145] 'process raft request' (duration: 608.903011ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:32.773538Z","caller":"traceutil/trace.go:171","msg":"trace[1425394447] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"609.177176ms","start":"2026-05-20T03:14:32.164341Z","end":"2026-05-20T03:14:32.773518Z","steps":["trace[1425394447] 'process raft request' (duration: 608.899331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:32.773596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:32.164402Z","time spent":"609.155086ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:14:32.77374Z","caller":"traceutil/trace.go:171","msg":"trace[307234300] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"609.806209ms","start":"2026-05-20T03:14:32.163923Z","end":"2026-05-20T03:14:32.773729Z","steps":["trace[307234300] 'process raft request' (duration: 117.259817ms)","trace[307234300] 'compare' (duration: 491.42581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:14:32.773775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:32.163889Z","time spent":"609.86986ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:14:32.773768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.394651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-20T03:14:32.773812Z","caller":"traceutil/trace.go:171","msg":"trace[1369777791] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"607.670707ms","start":"2026-05-20T03:14:32.166108Z","end":"2026-05-20T03:14:32.773778Z","steps":["trace[1369777791] 'process raft request' (duration: 607.27175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:32.773863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.928618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6cb9c8c589-f9ptc.18b12799fe273a1a\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-20T03:14:32.773886Z","caller":"traceutil/trace.go:171","msg":"trace[285659852] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4776; }","duration":"579.476563ms","start":"2026-05-20T03:14:32.194326Z","end":"2026-05-20T03:14:32.773802Z","steps":["trace[285659852] 'agreement among raft nodes before linearized reading' (duration: 579.196377ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:32.773937Z","caller":"traceutil/trace.go:171","msg":"trace[1152977004] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6cb9c8c589-f9ptc.18b12799fe273a1a; range_end:; response_count:1; response_revision:4776; }","duration":"483.042961ms","start":"2026-05-20T03:14:32.290873Z","end":"2026-05-20T03:14:32.773916Z","steps":["trace[1152977004] 'agreement among raft nodes before linearized reading' (duration: 482.811627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:32.773956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.003731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"warn","ts":"2026-05-20T03:14:32.773959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:32.19431Z","time spent":"579.638366ms","remote":"127.0.0.1:48244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-05-20T03:14:32.773991Z","caller":"traceutil/trace.go:171","msg":"trace[112372231] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4776; }","duration":"483.066111ms","start":"2026-05-20T03:14:32.290913Z","end":"2026-05-20T03:14:32.773979Z","steps":["trace[112372231] 'agreement among raft nodes before linearized reading' (duration: 482.936819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:32.773981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:32.290858Z","time spent":"483.114632ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":922,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6cb9c8c589-f9ptc.18b12799fe273a1a\" "} {"level":"warn","ts":"2026-05-20T03:14:32.773981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:32.166094Z","time spent":"607.80327ms","remote":"127.0.0.1:48434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:14:32.774024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:32.290898Z","time spent":"483.117472ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16257,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-20T03:14:32.77391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.001338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-20T03:14:32.774198Z","caller":"traceutil/trace.go:171","msg":"trace[1225089963] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4776; }","duration":"410.312635ms","start":"2026-05-20T03:14:32.363877Z","end":"2026-05-20T03:14:32.774189Z","steps":["trace[1225089963] 'agreement among raft nodes before linearized reading' (duration: 409.873756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:32.774222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:32.36386Z","time spent":"410.354515ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-20T03:14:32.773641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:32.164334Z","time spent":"609.251598ms","remote":"127.0.0.1:48434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:14:33.024643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.181056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:14:33.024786Z","caller":"traceutil/trace.go:171","msg":"trace[332440873] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"204.123696ms","start":"2026-05-20T03:14:32.82065Z","end":"2026-05-20T03:14:33.024774Z","steps":["trace[332440873] 'process raft request' (duration: 204.073105ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:33.02492Z","caller":"traceutil/trace.go:171","msg":"trace[1770627036] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4955; }","duration":"208.011564ms","start":"2026-05-20T03:14:32.816903Z","end":"2026-05-20T03:14:33.024914Z","steps":["trace[1770627036] 'read index received' (duration: 68.215666ms)","trace[1770627036] 'applied index is now lower than readState.Index' (duration: 139.795248ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:33.024907Z","caller":"traceutil/trace.go:171","msg":"trace[2102451497] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"239.657678ms","start":"2026-05-20T03:14:32.785217Z","end":"2026-05-20T03:14:33.024874Z","steps":["trace[2102451497] 'process raft request' (duration: 100.179256ms)","trace[2102451497] 'compare' (duration: 138.754328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:14:33.025046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.536065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-09a7ddd9-4d84-43d8-b9e1-d6fb0264c2b5\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-20T03:14:33.025065Z","caller":"traceutil/trace.go:171","msg":"trace[2145800660] range","detail":"{range_begin:/registry/persistentvolumes/pvc-09a7ddd9-4d84-43d8-b9e1-d6fb0264c2b5; range_end:; response_count:1; response_revision:4779; }","duration":"204.598136ms","start":"2026-05-20T03:14:32.820462Z","end":"2026-05-20T03:14:33.02506Z","steps":["trace[2145800660] 'agreement among raft nodes before linearized reading' (duration: 204.507994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:33.025085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.811941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:14:33.025182Z","caller":"traceutil/trace.go:171","msg":"trace[26008681] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4779; }","duration":"243.950103ms","start":"2026-05-20T03:14:32.781209Z","end":"2026-05-20T03:14:33.025159Z","steps":["trace[26008681] 'agreement among raft nodes before linearized reading' (duration: 243.752389ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:38.136804Z","caller":"traceutil/trace.go:171","msg":"trace[677108837] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"136.164923ms","start":"2026-05-20T03:14:38.000615Z","end":"2026-05-20T03:14:38.13678Z","steps":["trace[677108837] 'process raft request' (duration: 135.977279ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:38.138676Z","caller":"traceutil/trace.go:171","msg":"trace[1029246846] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"135.651571ms","start":"2026-05-20T03:14:38.003005Z","end":"2026-05-20T03:14:38.138657Z","steps":["trace[1029246846] 'process raft request' (duration: 135.542689ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:38.138896Z","caller":"traceutil/trace.go:171","msg":"trace[119038964] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"135.649412ms","start":"2026-05-20T03:14:38.003235Z","end":"2026-05-20T03:14:38.138885Z","steps":["trace[119038964] 'process raft request' (duration: 135.605221ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:38.139118Z","caller":"traceutil/trace.go:171","msg":"trace[1069557595] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"136.04704ms","start":"2026-05-20T03:14:38.003034Z","end":"2026-05-20T03:14:38.139081Z","steps":["trace[1069557595] 'process raft request' (duration: 135.594351ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.126631Z","caller":"traceutil/trace.go:171","msg":"trace[850937471] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"738.767307ms","start":"2026-05-20T03:14:41.38784Z","end":"2026-05-20T03:14:42.126607Z","steps":["trace[850937471] 'process raft request' (duration: 738.565353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:42.126778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:41.387822Z","time spent":"738.88715ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13648,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:14:42.365425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.295835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:14:42.365609Z","caller":"traceutil/trace.go:171","msg":"trace[1254121710] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"232.354938ms","start":"2026-05-20T03:14:42.133174Z","end":"2026-05-20T03:14:42.365529Z","steps":["trace[1254121710] 'process raft request' (duration: 47.874339ms)","trace[1254121710] 'compare' (duration: 184.152173ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:42.38228Z","caller":"traceutil/trace.go:171","msg":"trace[1180229267] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"248.534716ms","start":"2026-05-20T03:14:42.133706Z","end":"2026-05-20T03:14:42.382241Z","steps":["trace[1180229267] 'process raft request' (duration: 248.317872ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.382332Z","caller":"traceutil/trace.go:171","msg":"trace[147586184] linearizableReadLoop","detail":"{readStateIndex:5093; appliedIndex:5088; }","duration":"225.429921ms","start":"2026-05-20T03:14:42.156881Z","end":"2026-05-20T03:14:42.382311Z","steps":["trace[147586184] 'read index received' (duration: 24.205474ms)","trace[147586184] 'applied index is now lower than readState.Index' (duration: 201.223267ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:42.382511Z","caller":"traceutil/trace.go:171","msg":"trace[1307214042] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"248.682019ms","start":"2026-05-20T03:14:42.133809Z","end":"2026-05-20T03:14:42.382491Z","steps":["trace[1307214042] 'process raft request' (duration: 248.334322ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.382527Z","caller":"traceutil/trace.go:171","msg":"trace[370015504] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"248.444863ms","start":"2026-05-20T03:14:42.134053Z","end":"2026-05-20T03:14:42.382498Z","steps":["trace[370015504] 'process raft request' (duration: 248.140057ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.382581Z","caller":"traceutil/trace.go:171","msg":"trace[619437360] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"237.05992ms","start":"2026-05-20T03:14:42.145494Z","end":"2026-05-20T03:14:42.382554Z","steps":["trace[619437360] 'process raft request' (duration: 236.757054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:42.382657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.759938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:14:42.382686Z","caller":"traceutil/trace.go:171","msg":"trace[1307470909] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4909; }","duration":"225.84045ms","start":"2026-05-20T03:14:42.156838Z","end":"2026-05-20T03:14:42.382678Z","steps":["trace[1307470909] 'agreement among raft nodes before linearized reading' (duration: 225.773838ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.438818Z","caller":"traceutil/trace.go:171","msg":"trace[1001528273] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"201.858119ms","start":"2026-05-20T03:14:42.236939Z","end":"2026-05-20T03:14:42.438798Z","steps":["trace[1001528273] 'process raft request' (duration: 201.793588ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.438923Z","caller":"traceutil/trace.go:171","msg":"trace[2136566697] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"225.770558ms","start":"2026-05-20T03:14:42.213118Z","end":"2026-05-20T03:14:42.438889Z","steps":["trace[2136566697] 'process raft request' (duration: 225.460682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:42.439187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.357729ms","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-05-20T03:14:42.439195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.841418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-20T03:14:42.439229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.540614ms","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-05-20T03:14:42.439245Z","caller":"traceutil/trace.go:171","msg":"trace[499426736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4913; }","duration":"176.456982ms","start":"2026-05-20T03:14:42.262776Z","end":"2026-05-20T03:14:42.439233Z","steps":["trace[499426736] 'agreement among raft nodes before linearized reading' (duration: 176.272917ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.439271Z","caller":"traceutil/trace.go:171","msg":"trace[251694380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4913; }","duration":"175.980422ms","start":"2026-05-20T03:14:42.263276Z","end":"2026-05-20T03:14:42.439257Z","steps":["trace[251694380] 'agreement among raft nodes before linearized reading' (duration: 175.746157ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.439287Z","caller":"traceutil/trace.go:171","msg":"trace[965234861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4913; }","duration":"175.622945ms","start":"2026-05-20T03:14:42.263648Z","end":"2026-05-20T03:14:42.439271Z","steps":["trace[965234861] 'agreement among raft nodes before linearized reading' (duration: 175.456541ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.438914Z","caller":"traceutil/trace.go:171","msg":"trace[116051061] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"203.46183ms","start":"2026-05-20T03:14:42.235436Z","end":"2026-05-20T03:14:42.438898Z","steps":["trace[116051061] 'process raft request' (duration: 203.260546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:42.439355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.689466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:14:42.439436Z","caller":"traceutil/trace.go:171","msg":"trace[653140857] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"202.418301ms","start":"2026-05-20T03:14:42.237005Z","end":"2026-05-20T03:14:42.439424Z","steps":["trace[653140857] 'process raft request' (duration: 201.761788ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:42.439461Z","caller":"traceutil/trace.go:171","msg":"trace[661884995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4913; }","duration":"179.88778ms","start":"2026-05-20T03:14:42.259562Z","end":"2026-05-20T03:14:42.43945Z","steps":["trace[661884995] 'agreement among raft nodes before linearized reading' (duration: 179.540432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:42.439561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.184907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:14:42.439674Z","caller":"traceutil/trace.go:171","msg":"trace[394734565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4913; }","duration":"175.35958ms","start":"2026-05-20T03:14:42.264268Z","end":"2026-05-20T03:14:42.439628Z","steps":["trace[394734565] 'agreement among raft nodes before linearized reading' (duration: 174.758538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:47.416126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.188776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:14:47.416297Z","caller":"traceutil/trace.go:171","msg":"trace[1580143377] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"249.951574ms","start":"2026-05-20T03:14:47.166321Z","end":"2026-05-20T03:14:47.416272Z","steps":["trace[1580143377] 'process raft request' (duration: 28.557804ms)","trace[1580143377] 'compare' (duration: 220.906351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:14:48.005012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186411515218804,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:14:48.295499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:47.602974Z","time spent":"692.518642ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-20T03:14:48.30101Z","caller":"traceutil/trace.go:171","msg":"trace[1430384376] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5142; }","duration":"797.060244ms","start":"2026-05-20T03:14:47.50393Z","end":"2026-05-20T03:14:48.30099Z","steps":["trace[1430384376] 'read index received' (duration: 796.789018ms)","trace[1430384376] 'applied index is now lower than readState.Index' (duration: 270.316µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:48.301173Z","caller":"traceutil/trace.go:171","msg":"trace[1252835129] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"865.029484ms","start":"2026-05-20T03:14:47.436128Z","end":"2026-05-20T03:14:48.301158Z","steps":["trace[1252835129] 'process raft request' (duration: 864.677997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.301365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:47.436105Z","time spent":"865.131775ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17217,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:14:48.301437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.952075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:14:48.301504Z","caller":"traceutil/trace.go:171","msg":"trace[1322442466] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4958; }","duration":"494.060366ms","start":"2026-05-20T03:14:47.807428Z","end":"2026-05-20T03:14:48.301488Z","steps":["trace[1322442466] 'agreement among raft nodes before linearized reading' (duration: 493.947164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.30158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:47.80741Z","time spent":"494.156079ms","remote":"127.0.0.1:50854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":10,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:14:48.301698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.287081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:14:48.301759Z","caller":"traceutil/trace.go:171","msg":"trace[114626526] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:4958; }","duration":"617.402903ms","start":"2026-05-20T03:14:47.684344Z","end":"2026-05-20T03:14:48.301747Z","steps":["trace[114626526] 'agreement among raft nodes before linearized reading' (duration: 617.270221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.301756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.816978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-05-20T03:14:48.301793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:47.684325Z","time spent":"617.462224ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:14:48.301832Z","caller":"traceutil/trace.go:171","msg":"trace[654524141] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4958; }","duration":"797.9348ms","start":"2026-05-20T03:14:47.503877Z","end":"2026-05-20T03:14:48.301812Z","steps":["trace[654524141] 'agreement among raft nodes before linearized reading' (duration: 797.651285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.301944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:47.503856Z","time spent":"798.048382ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-20T03:14:48.302141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.332946ms","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-05-20T03:14:48.302204Z","caller":"traceutil/trace.go:171","msg":"trace[908550597] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4958; }","duration":"697.043999ms","start":"2026-05-20T03:14:47.605132Z","end":"2026-05-20T03:14:48.302176Z","steps":["trace[908550597] 'agreement among raft nodes before linearized reading' (duration: 696.223154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.302242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:14:47.605124Z","time spent":"697.10681ms","remote":"127.0.0.1:46752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:14:48.453481Z","caller":"traceutil/trace.go:171","msg":"trace[1092383926] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"133.481517ms","start":"2026-05-20T03:14:48.319975Z","end":"2026-05-20T03:14:48.453457Z","steps":["trace[1092383926] 'process raft request' (duration: 133.308764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.652846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.69774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:14:48.652921Z","caller":"traceutil/trace.go:171","msg":"trace[326415703] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5145; }","duration":"178.338608ms","start":"2026-05-20T03:14:48.47457Z","end":"2026-05-20T03:14:48.652909Z","steps":["trace[326415703] 'read index received' (duration: 39.876883ms)","trace[326415703] 'applied index is now lower than readState.Index' (duration: 138.460845ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:14:48.65317Z","caller":"traceutil/trace.go:171","msg":"trace[646606424] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"182.843956ms","start":"2026-05-20T03:14:48.470316Z","end":"2026-05-20T03:14:48.65316Z","steps":["trace[646606424] 'process raft request' (duration: 76.765659ms)","trace[646606424] 'compare' (duration: 105.598258ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:14:48.653408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.847398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-20T03:14:48.653435Z","caller":"traceutil/trace.go:171","msg":"trace[321622466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4968; }","duration":"178.921089ms","start":"2026-05-20T03:14:48.474507Z","end":"2026-05-20T03:14:48.653428Z","steps":["trace[321622466] 'agreement among raft nodes before linearized reading' (duration: 178.830668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.653649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.197374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T03:14:48.653637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.429348ms","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-05-20T03:14:48.653668Z","caller":"traceutil/trace.go:171","msg":"trace[626239386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4968; }","duration":"164.230545ms","start":"2026-05-20T03:14:48.489432Z","end":"2026-05-20T03:14:48.653663Z","steps":["trace[626239386] 'agreement among raft nodes before linearized reading' (duration: 164.168394ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:48.653703Z","caller":"traceutil/trace.go:171","msg":"trace[1727528054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4968; }","duration":"165.524179ms","start":"2026-05-20T03:14:48.48816Z","end":"2026-05-20T03:14:48.653684Z","steps":["trace[1727528054] 'agreement among raft nodes before linearized reading' (duration: 165.318545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.653636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.599813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-20T03:14:48.653741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.562857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-20T03:14:48.653757Z","caller":"traceutil/trace.go:171","msg":"trace[1580649109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4968; }","duration":"163.758425ms","start":"2026-05-20T03:14:48.489992Z","end":"2026-05-20T03:14:48.65375Z","steps":["trace[1580649109] 'agreement among raft nodes before linearized reading' (duration: 163.525021ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:48.653785Z","caller":"traceutil/trace.go:171","msg":"trace[1436773931] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4968; }","duration":"137.622849ms","start":"2026-05-20T03:14:48.516146Z","end":"2026-05-20T03:14:48.653769Z","steps":["trace[1436773931] 'agreement among raft nodes before linearized reading' (duration: 137.530417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:14:48.653888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.597951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-20T03:14:48.653917Z","caller":"traceutil/trace.go:171","msg":"trace[283273700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4968; }","duration":"165.693082ms","start":"2026-05-20T03:14:48.488216Z","end":"2026-05-20T03:14:48.653909Z","steps":["trace[283273700] 'agreement among raft nodes before linearized reading' (duration: 165.600191ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:34.191686Z","caller":"traceutil/trace.go:171","msg":"trace[116226476] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"297.302612ms","start":"2026-05-20T03:15:33.894366Z","end":"2026-05-20T03:15:34.191669Z","steps":["trace[116226476] 'read index received' (duration: 262.786481ms)","trace[116226476] 'applied index is now lower than readState.Index' (duration: 34.515421ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:15:34.191768Z","caller":"traceutil/trace.go:171","msg":"trace[1428888669] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"298.677496ms","start":"2026-05-20T03:15:33.893045Z","end":"2026-05-20T03:15:34.191722Z","steps":["trace[1428888669] 'process raft request' (duration: 264.168795ms)","trace[1428888669] 'compare' (duration: 34.357008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:15:34.191907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.483715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:15:34.192035Z","caller":"traceutil/trace.go:171","msg":"trace[2128410033] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5332; }","duration":"297.666108ms","start":"2026-05-20T03:15:33.894355Z","end":"2026-05-20T03:15:34.192021Z","steps":["trace[2128410033] 'agreement among raft nodes before linearized reading' (duration: 297.420254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:34.19203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.221422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-20T03:15:34.192218Z","caller":"traceutil/trace.go:171","msg":"trace[54228504] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5332; }","duration":"153.525446ms","start":"2026-05-20T03:15:34.038684Z","end":"2026-05-20T03:15:34.19221Z","steps":["trace[54228504] 'agreement among raft nodes before linearized reading' (duration: 153.17291ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:34.59704Z","caller":"traceutil/trace.go:171","msg":"trace[1837658121] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"364.344422ms","start":"2026-05-20T03:15:34.232658Z","end":"2026-05-20T03:15:34.597003Z","steps":["trace[1837658121] 'process raft request' (duration: 265.860694ms)","trace[1837658121] 'compare' (duration: 98.190123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:15:34.597254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:34.232636Z","time spent":"364.514365ms","remote":"127.0.0.1:47518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:15:35.208157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.577881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:15:35.208228Z","caller":"traceutil/trace.go:171","msg":"trace[682485809] linearizableReadLoop","detail":"{readStateIndex:5531; appliedIndex:5530; }","duration":"527.7189ms","start":"2026-05-20T03:15:34.680498Z","end":"2026-05-20T03:15:35.208217Z","steps":["trace[682485809] 'read index received' (duration: 36.15885ms)","trace[682485809] 'applied index is now lower than readState.Index' (duration: 491.55935ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:15:35.208388Z","caller":"traceutil/trace.go:171","msg":"trace[1471022738] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"576.654208ms","start":"2026-05-20T03:15:34.631724Z","end":"2026-05-20T03:15:35.208378Z","steps":["trace[1471022738] 'process raft request' (duration: 84.802793ms)","trace[1471022738] 'compare' (duration: 491.488909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:15:35.208425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:34.631702Z","time spent":"576.706799ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:15:35.208575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.077977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-20T03:15:35.208594Z","caller":"traceutil/trace.go:171","msg":"trace[899884380] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5335; }","duration":"528.127568ms","start":"2026-05-20T03:15:34.680461Z","end":"2026-05-20T03:15:35.208588Z","steps":["trace[899884380] 'agreement among raft nodes before linearized reading' (duration: 528.050536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.208609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:34.680448Z","time spent":"528.157749ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-20T03:15:35.245882Z","caller":"traceutil/trace.go:171","msg":"trace[748382247] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"552.678107ms","start":"2026-05-20T03:15:34.693184Z","end":"2026-05-20T03:15:35.245862Z","steps":["trace[748382247] 'process raft request' (duration: 552.455973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.245997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:34.693161Z","time spent":"552.776459ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:15:35.245949Z","caller":"traceutil/trace.go:171","msg":"trace[138758542] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"552.132977ms","start":"2026-05-20T03:15:34.693797Z","end":"2026-05-20T03:15:35.24593Z","steps":["trace[138758542] 'process raft request' (duration: 551.979684ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:35.246117Z","caller":"traceutil/trace.go:171","msg":"trace[1981833584] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"551.584418ms","start":"2026-05-20T03:15:34.694504Z","end":"2026-05-20T03:15:35.246088Z","steps":["trace[1981833584] 'process raft request' (duration: 551.316833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.246143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:34.693782Z","time spent":"552.326271ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:15:35.246262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:34.694469Z","time spent":"551.71845ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:15:35.246404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.263105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T03:15:35.246506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.878766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-20T03:15:35.246556Z","caller":"traceutil/trace.go:171","msg":"trace[1465972199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5338; }","duration":"300.936157ms","start":"2026-05-20T03:15:34.945592Z","end":"2026-05-20T03:15:35.246528Z","steps":["trace[1465972199] 'agreement among raft nodes before linearized reading' (duration: 300.800735ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:35.246478Z","caller":"traceutil/trace.go:171","msg":"trace[1025845193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5338; }","duration":"300.370897ms","start":"2026-05-20T03:15:34.946094Z","end":"2026-05-20T03:15:35.246465Z","steps":["trace[1025845193] 'agreement among raft nodes before linearized reading' (duration: 300.023661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.246683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:34.946076Z","time spent":"300.593711ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:15:35.246773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.57423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-20T03:15:35.246592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:34.945577Z","time spent":"301.007808ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T03:15:35.246848Z","caller":"traceutil/trace.go:171","msg":"trace[1624448155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5338; }","duration":"256.682602ms","start":"2026-05-20T03:15:34.990152Z","end":"2026-05-20T03:15:35.246834Z","steps":["trace[1624448155] 'agreement among raft nodes before linearized reading' (duration: 256.507779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.247024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.168583ms","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-05-20T03:15:35.247101Z","caller":"traceutil/trace.go:171","msg":"trace[93870722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5338; }","duration":"256.265704ms","start":"2026-05-20T03:15:34.990823Z","end":"2026-05-20T03:15:35.247088Z","steps":["trace[93870722] 'agreement among raft nodes before linearized reading' (duration: 256.129912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.247469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.998706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-20T03:15:35.247501Z","caller":"traceutil/trace.go:171","msg":"trace[1902447750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5338; }","duration":"258.080117ms","start":"2026-05-20T03:15:34.989413Z","end":"2026-05-20T03:15:35.247493Z","steps":["trace[1902447750] 'agreement among raft nodes before linearized reading' (duration: 257.340394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.662032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.070172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:15:35.662304Z","caller":"traceutil/trace.go:171","msg":"trace[1867173361] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"410.712734ms","start":"2026-05-20T03:15:35.251574Z","end":"2026-05-20T03:15:35.662287Z","steps":["trace[1867173361] 'process raft request' (duration: 410.642552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.662378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:35.251553Z","time spent":"410.792045ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:15:35.662461Z","caller":"traceutil/trace.go:171","msg":"trace[1224336564] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"410.925798ms","start":"2026-05-20T03:15:35.251506Z","end":"2026-05-20T03:15:35.662432Z","steps":["trace[1224336564] 'process raft request' (duration: 87.376806ms)","trace[1224336564] 'compare' (duration: 322.96284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:15:35.662604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:35.25149Z","time spent":"411.03747ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:15:35.66287Z","caller":"traceutil/trace.go:171","msg":"trace[1302032296] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"411.339075ms","start":"2026-05-20T03:15:35.251515Z","end":"2026-05-20T03:15:35.662854Z","steps":["trace[1302032296] 'process raft request' (duration: 410.641152ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:35.662886Z","caller":"traceutil/trace.go:171","msg":"trace[1996808540] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"410.212815ms","start":"2026-05-20T03:15:35.252661Z","end":"2026-05-20T03:15:35.662874Z","steps":["trace[1996808540] 'process raft request' (duration: 409.586554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.662944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:35.251503Z","time spent":"411.406416ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:15:35.662949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:35.252636Z","time spent":"410.279176ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:15:35.663066Z","caller":"traceutil/trace.go:171","msg":"trace[810234431] linearizableReadLoop","detail":"{readStateIndex:5539; appliedIndex:5534; }","duration":"356.221247ms","start":"2026-05-20T03:15:35.306833Z","end":"2026-05-20T03:15:35.663054Z","steps":["trace[810234431] 'read index received' (duration: 32.026694ms)","trace[810234431] 'applied index is now lower than readState.Index' (duration: 324.193343ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:15:35.663225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.391359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:15:35.663972Z","caller":"traceutil/trace.go:171","msg":"trace[1108698569] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5343; }","duration":"357.168963ms","start":"2026-05-20T03:15:35.306788Z","end":"2026-05-20T03:15:35.663957Z","steps":["trace[1108698569] 'agreement among raft nodes before linearized reading' (duration: 356.363899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.664027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:35.306772Z","time spent":"357.240534ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-20T03:15:35.663475Z","caller":"traceutil/trace.go:171","msg":"trace[1054356941] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"410.537311ms","start":"2026-05-20T03:15:35.252603Z","end":"2026-05-20T03:15:35.66314Z","steps":["trace[1054356941] 'process raft request' (duration: 410.200575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.663717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.689409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:15:35.663745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.691278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:15:35.664784Z","caller":"traceutil/trace.go:171","msg":"trace[174623613] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5343; }","duration":"109.612266ms","start":"2026-05-20T03:15:35.554977Z","end":"2026-05-20T03:15:35.664589Z","steps":["trace[174623613] 'agreement among raft nodes before linearized reading' (duration: 108.682939ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:35.664845Z","caller":"traceutil/trace.go:171","msg":"trace[1970264181] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5343; }","duration":"109.804029ms","start":"2026-05-20T03:15:35.555026Z","end":"2026-05-20T03:15:35.66483Z","steps":["trace[1970264181] 'agreement among raft nodes before linearized reading' (duration: 108.673489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.66378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.998277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-05-20T03:15:35.664993Z","caller":"traceutil/trace.go:171","msg":"trace[1413785481] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5343; }","duration":"109.226058ms","start":"2026-05-20T03:15:35.555743Z","end":"2026-05-20T03:15:35.664969Z","steps":["trace[1413785481] 'agreement among raft nodes before linearized reading' (duration: 107.925185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:35.664201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:15:35.252583Z","time spent":"411.564969ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:15:35.981733Z","caller":"traceutil/trace.go:171","msg":"trace[811405518] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"144.698765ms","start":"2026-05-20T03:15:35.83701Z","end":"2026-05-20T03:15:35.981709Z","steps":["trace[811405518] 'process raft request' (duration: 144.539031ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:45.843037Z","caller":"traceutil/trace.go:171","msg":"trace[32894221] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"122.16977ms","start":"2026-05-20T03:15:45.720846Z","end":"2026-05-20T03:15:45.843015Z","steps":["trace[32894221] 'process raft request' (duration: 122.027778ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:45.843031Z","caller":"traceutil/trace.go:171","msg":"trace[592793782] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"122.029848ms","start":"2026-05-20T03:15:45.720981Z","end":"2026-05-20T03:15:45.843011Z","steps":["trace[592793782] 'process raft request' (duration: 121.977117ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:45.843553Z","caller":"traceutil/trace.go:171","msg":"trace[1918781705] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"122.251702ms","start":"2026-05-20T03:15:45.721291Z","end":"2026-05-20T03:15:45.843543Z","steps":["trace[1918781705] 'process raft request' (duration: 121.693362ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:56.042753Z","caller":"traceutil/trace.go:171","msg":"trace[609779837] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"103.18113ms","start":"2026-05-20T03:15:55.939547Z","end":"2026-05-20T03:15:56.042728Z","steps":["trace[609779837] 'process raft request' (duration: 98.535099ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:15:58.889948Z","caller":"traceutil/trace.go:171","msg":"trace[1048950460] linearizableReadLoop","detail":"{readStateIndex:5714; appliedIndex:5713; }","duration":"152.353618ms","start":"2026-05-20T03:15:58.737565Z","end":"2026-05-20T03:15:58.889919Z","steps":["trace[1048950460] 'read index received' (duration: 151.989482ms)","trace[1048950460] 'applied index is now lower than readState.Index' (duration: 361.456µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:15:58.890481Z","caller":"traceutil/trace.go:171","msg":"trace[61051835] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"186.248918ms","start":"2026-05-20T03:15:58.704215Z","end":"2026-05-20T03:15:58.890464Z","steps":["trace[61051835] 'process raft request' (duration: 185.260831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:15:58.891293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.713683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:15:58.891336Z","caller":"traceutil/trace.go:171","msg":"trace[1049038457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5513; }","duration":"153.796034ms","start":"2026-05-20T03:15:58.737521Z","end":"2026-05-20T03:15:58.891317Z","steps":["trace[1049038457] 'agreement among raft nodes before linearized reading' (duration: 153.114352ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:16:00.934094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2870} {"level":"info","ts":"2026-05-20T03:16:01.025407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2870,"took":"89.993391ms","hash":18143707,"current-db-size-bytes":53846016,"current-db-size":"54 MB","current-db-size-in-use-bytes":28753920,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-20T03:16:01.025459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":18143707,"revision":2870,"compact-revision":-1} {"level":"info","ts":"2026-05-20T03:16:13.734838Z","caller":"traceutil/trace.go:171","msg":"trace[1989052854] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"195.255295ms","start":"2026-05-20T03:16:13.539552Z","end":"2026-05-20T03:16:13.734808Z","steps":["trace[1989052854] 'process raft request' (duration: 195.146723ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:16:13.734875Z","caller":"traceutil/trace.go:171","msg":"trace[367696774] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"196.999835ms","start":"2026-05-20T03:16:13.537846Z","end":"2026-05-20T03:16:13.734846Z","steps":["trace[367696774] 'process raft request' (duration: 192.54991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:16:14.313467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.347745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:16:14.313467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.501407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:16:14.313536Z","caller":"traceutil/trace.go:171","msg":"trace[1398052610] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5623; }","duration":"106.460767ms","start":"2026-05-20T03:16:14.207059Z","end":"2026-05-20T03:16:14.313519Z","steps":["trace[1398052610] 'agreement among raft nodes before linearized reading' (duration: 20.923096ms)","trace[1398052610] 'count revisions from in-memory index tree' (duration: 85.444109ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:16:14.31355Z","caller":"traceutil/trace.go:171","msg":"trace[1648530053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5623; }","duration":"106.61022ms","start":"2026-05-20T03:16:14.206919Z","end":"2026-05-20T03:16:14.313529Z","steps":["trace[1648530053] 'agreement among raft nodes before linearized reading' (duration: 21.034929ms)","trace[1648530053] 'range keys from in-memory index tree' (duration: 85.365007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:16:14.313636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.946105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:16:14.313723Z","caller":"traceutil/trace.go:171","msg":"trace[1324404597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5623; }","duration":"107.076748ms","start":"2026-05-20T03:16:14.206625Z","end":"2026-05-20T03:16:14.313702Z","steps":["trace[1324404597] 'agreement among raft nodes before linearized reading' (duration: 21.314833ms)","trace[1324404597] 'range keys from in-memory index tree' (duration: 85.55012ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:18:23.018792Z","caller":"traceutil/trace.go:171","msg":"trace[723623150] linearizableReadLoop","detail":"{readStateIndex:6760; appliedIndex:6759; }","duration":"179.893255ms","start":"2026-05-20T03:18:22.838875Z","end":"2026-05-20T03:18:23.018768Z","steps":["trace[723623150] 'read index received' (duration: 179.845305ms)","trace[723623150] 'applied index is now lower than readState.Index' (duration: 46.72µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:18:23.018937Z","caller":"traceutil/trace.go:171","msg":"trace[435515673] transaction","detail":"{read_only:false; response_revision:6524; number_of_response:1; }","duration":"196.046696ms","start":"2026-05-20T03:18:22.822872Z","end":"2026-05-20T03:18:23.018919Z","steps":["trace[435515673] 'process raft request' (duration: 195.738941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:18:23.019258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.368632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T03:18:23.019378Z","caller":"traceutil/trace.go:171","msg":"trace[958619471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6524; }","duration":"180.531054ms","start":"2026-05-20T03:18:22.83883Z","end":"2026-05-20T03:18:23.019361Z","steps":["trace[958619471] 'agreement among raft nodes before linearized reading' (duration: 180.353412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:18:53.260402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.560058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:18:53.260515Z","caller":"traceutil/trace.go:171","msg":"trace[580446840] linearizableReadLoop","detail":"{readStateIndex:6983; appliedIndex:6982; }","duration":"143.104816ms","start":"2026-05-20T03:18:53.117396Z","end":"2026-05-20T03:18:53.260501Z","steps":["trace[580446840] 'read index received' (duration: 17.323896ms)","trace[580446840] 'applied index is now lower than readState.Index' (duration: 125.77989ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:18:53.261078Z","caller":"traceutil/trace.go:171","msg":"trace[1005138050] transaction","detail":"{read_only:false; response_revision:6737; number_of_response:1; }","duration":"181.081239ms","start":"2026-05-20T03:18:53.079981Z","end":"2026-05-20T03:18:53.261063Z","steps":["trace[1005138050] 'process raft request' (duration: 54.78027ms)","trace[1005138050] 'compare' (duration: 125.286863ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:18:53.261287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.884818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/dashboard.18b127d40871d007\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-05-20T03:18:53.261325Z","caller":"traceutil/trace.go:171","msg":"trace[689023559] range","detail":"{range_begin:/registry/events/openstack/dashboard.18b127d40871d007; range_end:; response_count:1; response_revision:6737; }","duration":"143.953798ms","start":"2026-05-20T03:18:53.117362Z","end":"2026-05-20T03:18:53.261315Z","steps":["trace[689023559] 'agreement among raft nodes before linearized reading' (duration: 143.850657ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:18:53.429334Z","caller":"traceutil/trace.go:171","msg":"trace[1376289101] transaction","detail":"{read_only:false; response_revision:6739; number_of_response:1; }","duration":"164.440283ms","start":"2026-05-20T03:18:53.264877Z","end":"2026-05-20T03:18:53.429317Z","steps":["trace[1376289101] 'process raft request' (duration: 104.128068ms)","trace[1376289101] 'compare' (duration: 60.159733ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:18:53.429463Z","caller":"traceutil/trace.go:171","msg":"trace[1827258772] transaction","detail":"{read_only:false; response_revision:6740; number_of_response:1; }","duration":"164.383632ms","start":"2026-05-20T03:18:53.265072Z","end":"2026-05-20T03:18:53.429456Z","steps":["trace[1827258772] 'process raft request' (duration: 164.22382ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:18:53.42971Z","caller":"traceutil/trace.go:171","msg":"trace[2003600485] transaction","detail":"{read_only:false; response_revision:6741; number_of_response:1; }","duration":"163.727023ms","start":"2026-05-20T03:18:53.265978Z","end":"2026-05-20T03:18:53.429705Z","steps":["trace[2003600485] 'process raft request' (duration: 163.458918ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:18:53.429797Z","caller":"traceutil/trace.go:171","msg":"trace[475767018] transaction","detail":"{read_only:false; response_revision:6742; number_of_response:1; }","duration":"163.423279ms","start":"2026-05-20T03:18:53.266369Z","end":"2026-05-20T03:18:53.429792Z","steps":["trace[475767018] 'process raft request' (duration: 163.167435ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:18:54.017208Z","caller":"traceutil/trace.go:171","msg":"trace[132587967] linearizableReadLoop","detail":"{readStateIndex:6991; appliedIndex:6990; }","duration":"101.543008ms","start":"2026-05-20T03:18:53.915647Z","end":"2026-05-20T03:18:54.01719Z","steps":["trace[132587967] 'read index received' (duration: 101.350056ms)","trace[132587967] 'applied index is now lower than readState.Index' (duration: 192.452µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:18:54.017296Z","caller":"traceutil/trace.go:171","msg":"trace[594276129] transaction","detail":"{read_only:false; response_revision:6744; number_of_response:1; }","duration":"115.667245ms","start":"2026-05-20T03:18:53.901622Z","end":"2026-05-20T03:18:54.017289Z","steps":["trace[594276129] 'process raft request' (duration: 115.438231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:18:54.017548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.897864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/queues.rabbitmq.com\" ","response":"range_response_count:1 size:6489"} {"level":"info","ts":"2026-05-20T03:18:54.017579Z","caller":"traceutil/trace.go:171","msg":"trace[705676998] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/queues.rabbitmq.com; range_end:; response_count:1; response_revision:6744; }","duration":"101.959654ms","start":"2026-05-20T03:18:53.915611Z","end":"2026-05-20T03:18:54.017571Z","steps":["trace[705676998] 'agreement among raft nodes before linearized reading' (duration: 101.830222ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:21:00.946005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5523} {"level":"info","ts":"2026-05-20T03:21:01.05711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5523,"took":"110.064731ms","hash":1444124535,"current-db-size-bytes":53846016,"current-db-size":"54 MB","current-db-size-in-use-bytes":18386944,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-20T03:21:01.057237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1444124535,"revision":5523,"compact-revision":2870}