{"level":"warn","ts":"2026-05-25T22:02:01.060478Z","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-25T22:02:01.060541Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-25T22:02:01.06059Z","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-25T22:02:01.060598Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-25T22:02:01.060615Z","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-25T22:02:01.061207Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-05-25T22:02:01.061286Z","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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-25T22:02:01.066465Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.964159ms"} {"level":"info","ts":"2026-05-25T22:02:01.08135Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-05-25T22:02:01.081545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T22:02:01.081575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-05-25T22:02:01.081587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T22:02:01.081599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-05-25T22:02:01.081633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-05-25T22:02:01.087148Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T22:02:01.088353Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T22:02:01.089479Z","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-25T22:02:01.094749Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T22:02:01.095121Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T22:02:01.095147Z","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-25T22:02:01.095253Z","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-25T22:02:01.095278Z","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-25T22:02:01.095781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-05-25T22:02:01.095892Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-25T22:02:01.106753Z","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-25T22:02:01.106917Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-25T22:02:01.106964Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-25T22:02:01.107182Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T22:02:01.107221Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T22:02:01.382821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T22:02:01.382894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T22:02:01.382938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-05-25T22:02:01.38296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-05-25T22:02:01.382971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-25T22:02:01.382988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-05-25T22:02:01.383008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-25T22:02:01.384225Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:02:01.384928Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T22:02:01.385159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T22:02:01.385328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T22:02:01.386641Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T22:02:01.386714Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T22:02:01.388759Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T22:02:01.38883Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-05-25T22:02:01.389001Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:02:01.389189Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:02:01.389312Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-25T22:02:04.096557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.406598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-25T22:02:04.096833Z","caller":"traceutil/trace.go:171","msg":"trace[525473642] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"414.882615ms","start":"2026-05-25T22:02:03.681872Z","end":"2026-05-25T22:02:04.096755Z","steps":["trace[525473642] 'read index received' (duration: 134.930441ms)","trace[525473642] 'applied index is now lower than readState.Index' (duration: 279.949334ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:02:04.096939Z","caller":"traceutil/trace.go:171","msg":"trace[1383600036] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"415.691204ms","start":"2026-05-25T22:02:03.681199Z","end":"2026-05-25T22:02:04.09689Z","steps":["trace[1383600036] 'process raft request' (duration: 135.546255ms)","trace[1383600036] 'compare' (duration: 278.278505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:02:04.097201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:03.681179Z","time spent":"415.867909ms","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:02:04.097811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.930451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T22:02:04.097912Z","caller":"traceutil/trace.go:171","msg":"trace[727648060] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:77; }","duration":"416.051754ms","start":"2026-05-25T22:02:03.68184Z","end":"2026-05-25T22:02:04.097892Z","steps":["trace[727648060] 'agreement among raft nodes before linearized reading' (duration: 415.160142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:02:04.097984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:03.68183Z","time spent":"416.139226ms","remote":"127.0.0.1:44100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-05-25T22:02:04.098074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.925979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T22:02:04.098126Z","caller":"traceutil/trace.go:171","msg":"trace[447662150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"412.98095ms","start":"2026-05-25T22:02:03.685122Z","end":"2026-05-25T22:02:04.098103Z","steps":["trace[447662150] 'agreement among raft nodes before linearized reading' (duration: 412.912068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:02:04.098155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:03.685115Z","time spent":"413.034671ms","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T22:02:43.592532Z","caller":"traceutil/trace.go:171","msg":"trace[1437290100] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"109.349196ms","start":"2026-05-25T22:02:43.483155Z","end":"2026-05-25T22:02:43.592504Z","steps":["trace[1437290100] 'read index received' (duration: 108.585248ms)","trace[1437290100] 'applied index is now lower than readState.Index' (duration: 763.098µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:02:43.592616Z","caller":"traceutil/trace.go:171","msg":"trace[1115111866] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"144.621901ms","start":"2026-05-25T22:02:43.447946Z","end":"2026-05-25T22:02:43.592568Z","steps":["trace[1115111866] 'process raft request' (duration: 144.031677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:02:43.592814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.625124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-25T22:02:43.592884Z","caller":"traceutil/trace.go:171","msg":"trace[901051462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"109.747676ms","start":"2026-05-25T22:02:43.483118Z","end":"2026-05-25T22:02:43.592866Z","steps":["trace[901051462] 'agreement among raft nodes before linearized reading' (duration: 109.50383ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:02:43.777787Z","caller":"traceutil/trace.go:171","msg":"trace[359352068] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"155.981963ms","start":"2026-05-25T22:02:43.621785Z","end":"2026-05-25T22:02:43.777767Z","steps":["trace[359352068] 'process raft request' (duration: 155.84612ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:02:43.842632Z","caller":"traceutil/trace.go:171","msg":"trace[1463332830] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"145.695866ms","start":"2026-05-25T22:02:43.696919Z","end":"2026-05-25T22:02:43.842615Z","steps":["trace[1463332830] 'process raft request' (duration: 145.633985ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:02:43.842663Z","caller":"traceutil/trace.go:171","msg":"trace[1984974090] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"165.989151ms","start":"2026-05-25T22:02:43.676631Z","end":"2026-05-25T22:02:43.84262Z","steps":["trace[1984974090] 'process raft request' (duration: 136.178758ms)","trace[1984974090] 'compare' (duration: 29.561087ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:16.329262Z","caller":"traceutil/trace.go:171","msg":"trace[434776510] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"256.90127ms","start":"2026-05-25T22:04:16.072325Z","end":"2026-05-25T22:04:16.329227Z","steps":["trace[434776510] 'read index received' (duration: 256.545598ms)","trace[434776510] 'applied index is now lower than readState.Index' (duration: 354.372µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:16.329628Z","caller":"traceutil/trace.go:171","msg":"trace[211089896] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"258.998653ms","start":"2026-05-25T22:04:16.070573Z","end":"2026-05-25T22:04:16.329572Z","steps":["trace[211089896] 'process raft request' (duration: 258.440913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:16.330295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.945557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:16.330355Z","caller":"traceutil/trace.go:171","msg":"trace[1234103497] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1830; }","duration":"258.075831ms","start":"2026-05-25T22:04:16.072265Z","end":"2026-05-25T22:04:16.330341Z","steps":["trace[1234103497] 'agreement among raft nodes before linearized reading' (duration: 257.277144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:45.905618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.117698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:04:45.90585Z","caller":"traceutil/trace.go:171","msg":"trace[453802304] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"128.968759ms","start":"2026-05-25T22:04:45.77686Z","end":"2026-05-25T22:04:45.905828Z","steps":["trace[453802304] 'process raft request' (duration: 128.908267ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:45.906088Z","caller":"traceutil/trace.go:171","msg":"trace[861778601] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"129.395463ms","start":"2026-05-25T22:04:45.776676Z","end":"2026-05-25T22:04:45.906071Z","steps":["trace[861778601] 'process raft request' (duration: 15.526291ms)","trace[861778601] 'compare' (duration: 113.010614ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:46.100783Z","caller":"traceutil/trace.go:171","msg":"trace[1910126123] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"142.78622ms","start":"2026-05-25T22:04:45.957972Z","end":"2026-05-25T22:04:46.100759Z","steps":["trace[1910126123] 'process raft request' (duration: 102.901786ms)","trace[1910126123] 'compare' (duration: 39.776491ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:46.742668Z","caller":"traceutil/trace.go:171","msg":"trace[1810649875] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"139.010186ms","start":"2026-05-25T22:04:46.603629Z","end":"2026-05-25T22:04:46.742639Z","steps":["trace[1810649875] 'process raft request' (duration: 121.40426ms)","trace[1810649875] 'compare' (duration: 17.464592ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:46.796735Z","caller":"traceutil/trace.go:171","msg":"trace[1766814654] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"176.323978ms","start":"2026-05-25T22:04:46.620388Z","end":"2026-05-25T22:04:46.796712Z","steps":["trace[1766814654] 'process raft request' (duration: 176.202154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:47.006847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.732459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:47.007387Z","caller":"traceutil/trace.go:171","msg":"trace[1615677920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2055; }","duration":"104.311898ms","start":"2026-05-25T22:04:46.903038Z","end":"2026-05-25T22:04:47.007349Z","steps":["trace[1615677920] 'range keys from in-memory index tree' (duration: 103.653837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:47.549334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.291798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:04:47.549407Z","caller":"traceutil/trace.go:171","msg":"trace[1504899233] linearizableReadLoop","detail":"{readStateIndex:2135; appliedIndex:2134; }","duration":"219.504626ms","start":"2026-05-25T22:04:47.329893Z","end":"2026-05-25T22:04:47.549397Z","steps":["trace[1504899233] 'read index received' (duration: 29.032423ms)","trace[1504899233] 'applied index is now lower than readState.Index' (duration: 190.471303ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:47.54947Z","caller":"traceutil/trace.go:171","msg":"trace[1439830740] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"273.935066ms","start":"2026-05-25T22:04:47.275511Z","end":"2026-05-25T22:04:47.549447Z","steps":["trace[1439830740] 'process raft request' (duration: 83.466813ms)","trace[1439830740] 'compare' (duration: 190.103272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:04:47.549866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.973781ms","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-25T22:04:47.5499Z","caller":"traceutil/trace.go:171","msg":"trace[360200213] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2057; }","duration":"220.010203ms","start":"2026-05-25T22:04:47.329881Z","end":"2026-05-25T22:04:47.549891Z","steps":["trace[360200213] 'agreement among raft nodes before linearized reading' (duration: 219.569689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:47.550287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.770554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-25T22:04:47.550331Z","caller":"traceutil/trace.go:171","msg":"trace[169699207] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2057; }","duration":"214.851376ms","start":"2026-05-25T22:04:47.335472Z","end":"2026-05-25T22:04:47.550323Z","steps":["trace[169699207] 'agreement among raft nodes before linearized reading' (duration: 214.269228ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:11.095049Z","caller":"traceutil/trace.go:171","msg":"trace[1361520217] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"113.132266ms","start":"2026-05-25T22:10:10.981897Z","end":"2026-05-25T22:10:11.095029Z","steps":["trace[1361520217] 'process raft request' (duration: 24.87853ms)","trace[1361520217] 'compare' (duration: 85.806362ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:10:11.23607Z","caller":"traceutil/trace.go:171","msg":"trace[249710471] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"109.670885ms","start":"2026-05-25T22:10:11.126357Z","end":"2026-05-25T22:10:11.236028Z","steps":["trace[249710471] 'process raft request' (duration: 81.072828ms)","trace[249710471] 'compare' (duration: 28.381622ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:10:17.399789Z","caller":"traceutil/trace.go:171","msg":"trace[654264946] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"103.79901ms","start":"2026-05-25T22:10:17.295958Z","end":"2026-05-25T22:10:17.399757Z","steps":["trace[654264946] 'process raft request' (duration: 103.601544ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:17.402199Z","caller":"traceutil/trace.go:171","msg":"trace[288762828] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"103.321807ms","start":"2026-05-25T22:10:17.298862Z","end":"2026-05-25T22:10:17.402184Z","steps":["trace[288762828] 'process raft request' (duration: 102.923917ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:18.006804Z","caller":"traceutil/trace.go:171","msg":"trace[2130986231] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"101.682584ms","start":"2026-05-25T22:10:17.905092Z","end":"2026-05-25T22:10:18.006775Z","steps":["trace[2130986231] 'process raft request' (duration: 99.954978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:31.083005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.597493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:10:31.083105Z","caller":"traceutil/trace.go:171","msg":"trace[592170768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4865; }","duration":"178.761669ms","start":"2026-05-25T22:10:30.904321Z","end":"2026-05-25T22:10:31.083083Z","steps":["trace[592170768] 'range keys from in-memory index tree' (duration: 178.410059ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:31.083477Z","caller":"traceutil/trace.go:171","msg":"trace[1833912943] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"114.806151ms","start":"2026-05-25T22:10:30.968635Z","end":"2026-05-25T22:10:31.083442Z","steps":["trace[1833912943] 'process raft request' (duration: 94.793579ms)","trace[1833912943] 'compare' (duration: 19.799976ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:10:31.415523Z","caller":"traceutil/trace.go:171","msg":"trace[1885892661] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5049; }","duration":"275.671032ms","start":"2026-05-25T22:10:31.139822Z","end":"2026-05-25T22:10:31.415493Z","steps":["trace[1885892661] 'read index received' (duration: 193.619245ms)","trace[1885892661] 'applied index is now lower than readState.Index' (duration: 82.049857ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:10:31.415665Z","caller":"traceutil/trace.go:171","msg":"trace[1174957886] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"318.703353ms","start":"2026-05-25T22:10:31.096946Z","end":"2026-05-25T22:10:31.415649Z","steps":["trace[1174957886] 'process raft request' (duration: 236.571033ms)","trace[1174957886] 'compare' (duration: 81.704088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:10:31.415778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:10:31.09693Z","time spent":"318.761855ms","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:10:31.415956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.750816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-05-25T22:10:31.416016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.189776ms","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-25T22:10:31.416037Z","caller":"traceutil/trace.go:171","msg":"trace[1333212298] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4867; }","duration":"143.878339ms","start":"2026-05-25T22:10:31.272136Z","end":"2026-05-25T22:10:31.416015Z","steps":["trace[1333212298] 'agreement among raft nodes before linearized reading' (duration: 143.640883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:31.416131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.163126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:10:31.416086Z","caller":"traceutil/trace.go:171","msg":"trace[1352537334] 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:4867; }","duration":"276.32729ms","start":"2026-05-25T22:10:31.139739Z","end":"2026-05-25T22:10:31.416067Z","steps":["trace[1352537334] 'agreement among raft nodes before linearized reading' (duration: 276.147645ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:31.416172Z","caller":"traceutil/trace.go:171","msg":"trace[1240222640] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:4867; }","duration":"111.227048ms","start":"2026-05-25T22:10:31.304932Z","end":"2026-05-25T22:10:31.416159Z","steps":["trace[1240222640] 'agreement among raft nodes before linearized reading' (duration: 111.155796ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:31.640908Z","caller":"traceutil/trace.go:171","msg":"trace[951697654] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"142.315638ms","start":"2026-05-25T22:10:31.498555Z","end":"2026-05-25T22:10:31.64087Z","steps":["trace[951697654] 'process raft request' (duration: 56.347618ms)","trace[951697654] 'compare' (duration: 85.605611ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:10:31.640952Z","caller":"traceutil/trace.go:171","msg":"trace[2120636625] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5055; }","duration":"137.903722ms","start":"2026-05-25T22:10:31.503019Z","end":"2026-05-25T22:10:31.640922Z","steps":["trace[2120636625] 'read index received' (duration: 51.85583ms)","trace[2120636625] 'applied index is now lower than readState.Index' (duration: 86.044522ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:10:31.641271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.400512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-05-25T22:10:31.641393Z","caller":"traceutil/trace.go:171","msg":"trace[2003026233] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4873; }","duration":"149.599007ms","start":"2026-05-25T22:10:31.491775Z","end":"2026-05-25T22:10:31.641374Z","steps":["trace[2003026233] 'agreement among raft nodes before linearized reading' (duration: 149.33733ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:31.641324Z","caller":"traceutil/trace.go:171","msg":"trace[854642067] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"134.059563ms","start":"2026-05-25T22:10:31.507202Z","end":"2026-05-25T22:10:31.641261Z","steps":["trace[854642067] 'process raft request' (duration: 133.56049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:31.641568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.865604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d4f55675-6d14-4dcd-bc73-9cc3fca9a560\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-25T22:10:31.641791Z","caller":"traceutil/trace.go:171","msg":"trace[303865564] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d4f55675-6d14-4dcd-bc73-9cc3fca9a560; range_end:; response_count:1; response_revision:4874; }","duration":"135.094059ms","start":"2026-05-25T22:10:31.506634Z","end":"2026-05-25T22:10:31.641728Z","steps":["trace[303865564] 'agreement among raft nodes before linearized reading' (duration: 134.559265ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:31.847268Z","caller":"traceutil/trace.go:171","msg":"trace[1500665285] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5059; }","duration":"129.395192ms","start":"2026-05-25T22:10:31.71783Z","end":"2026-05-25T22:10:31.847226Z","steps":["trace[1500665285] 'read index received' (duration: 66.205025ms)","trace[1500665285] 'applied index is now lower than readState.Index' (duration: 63.187497ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:10:31.847298Z","caller":"traceutil/trace.go:171","msg":"trace[532574320] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"155.089252ms","start":"2026-05-25T22:10:31.692173Z","end":"2026-05-25T22:10:31.847262Z","steps":["trace[532574320] 'process raft request' (duration: 91.895155ms)","trace[532574320] 'compare' (duration: 62.899338ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:10:31.847513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.676669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13873"} {"level":"info","ts":"2026-05-25T22:10:31.847576Z","caller":"traceutil/trace.go:171","msg":"trace[1903849138] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4877; }","duration":"129.786582ms","start":"2026-05-25T22:10:31.717773Z","end":"2026-05-25T22:10:31.847559Z","steps":["trace[1903849138] 'agreement among raft nodes before linearized reading' (duration: 129.616018ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:10:32.060952Z","caller":"traceutil/trace.go:171","msg":"trace[1651398723] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5060; }","duration":"197.386283ms","start":"2026-05-25T22:10:31.863539Z","end":"2026-05-25T22:10:32.060926Z","steps":["trace[1651398723] 'read index received' (duration: 146.786794ms)","trace[1651398723] 'applied index is now lower than readState.Index' (duration: 50.598479ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:10:32.061087Z","caller":"traceutil/trace.go:171","msg":"trace[1768870359] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"202.949688ms","start":"2026-05-25T22:10:31.858114Z","end":"2026-05-25T22:10:32.061063Z","steps":["trace[1768870359] 'process raft request' (duration: 152.33301ms)","trace[1768870359] 'compare' (duration: 50.358281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:10:32.061357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.801194ms","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-25T22:10:32.061407Z","caller":"traceutil/trace.go:171","msg":"trace[1618946264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4878; }","duration":"197.897616ms","start":"2026-05-25T22:10:31.863498Z","end":"2026-05-25T22:10:32.061396Z","steps":["trace[1618946264] 'agreement among raft nodes before linearized reading' (duration: 197.585958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:10:32.061495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.601378ms","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-25T22:10:32.061597Z","caller":"traceutil/trace.go:171","msg":"trace[1223721627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4878; }","duration":"159.739571ms","start":"2026-05-25T22:10:31.901833Z","end":"2026-05-25T22:10:32.061573Z","steps":["trace[1223721627] 'agreement among raft nodes before linearized reading' (duration: 159.331761ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:55.517281Z","caller":"traceutil/trace.go:171","msg":"trace[694999913] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"400.258746ms","start":"2026-05-25T22:11:55.116978Z","end":"2026-05-25T22:11:55.517236Z","steps":["trace[694999913] 'process raft request' (duration: 400.020698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:55.517539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:55.116955Z","time spent":"400.444443ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:11:55.518273Z","caller":"traceutil/trace.go:171","msg":"trace[1646164598] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"346.930111ms","start":"2026-05-25T22:11:55.171328Z","end":"2026-05-25T22:11:55.518258Z","steps":["trace[1646164598] 'process raft request' (duration: 346.652531ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:55.518291Z","caller":"traceutil/trace.go:171","msg":"trace[694697098] linearizableReadLoop","detail":"{readStateIndex:5728; appliedIndex:5726; }","duration":"313.665431ms","start":"2026-05-25T22:11:55.204569Z","end":"2026-05-25T22:11:55.518234Z","steps":["trace[694697098] 'read index received' (duration: 312.508931ms)","trace[694697098] 'applied index is now lower than readState.Index' (duration: 1.15533ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:11:55.518357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:55.171309Z","time spent":"347.005424ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:11:55.518604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.030003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-05-25T22:11:55.518747Z","caller":"traceutil/trace.go:171","msg":"trace[1029190121] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5527; }","duration":"314.144217ms","start":"2026-05-25T22:11:55.204552Z","end":"2026-05-25T22:11:55.518696Z","steps":["trace[1029190121] 'agreement among raft nodes before linearized reading' (duration: 313.793815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:55.518843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:11:55.20454Z","time spent":"314.256492ms","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-05-25T22:12:00.75487Z","caller":"traceutil/trace.go:171","msg":"trace[1328096385] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"141.032768ms","start":"2026-05-25T22:12:00.613782Z","end":"2026-05-25T22:12:00.754815Z","steps":["trace[1328096385] 'process raft request' (duration: 76.119155ms)","trace[1328096385] 'compare' (duration: 64.820108ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:01.403889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609019561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:01.904669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609019561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:02.4056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609019561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:02.903388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999984577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-25T22:12:02.903462Z","caller":"traceutil/trace.go:171","msg":"trace[1950630572] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000098802s","start":"2026-05-25T22:12:00.903347Z","end":"2026-05-25T22:12:02.903446Z","steps":["trace[1950630572] 'agreement among raft nodes before linearized reading' (duration: 2.000009368s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:02.903502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.903334Z","time spent":"2.000159425s","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/25 22:12:02 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:02.906687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609019561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:03.407818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609019561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:03.908284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609019561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:04.409015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609019561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:04.903915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000123215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-25T22:12:04.903999Z","caller":"traceutil/trace.go:171","msg":"trace[2027674994] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000238249s","start":"2026-05-25T22:12:02.903741Z","end":"2026-05-25T22:12:04.903979Z","steps":["trace[2027674994] 'agreement among raft nodes before linearized reading' (duration: 2.000119625s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:04.904047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.903699Z","time spent":"2.000337853s","remote":"127.0.0.1:43768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T22:12:04.909686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609019561,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:04.93049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000474416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-25T22:12:04.930626Z","caller":"traceutil/trace.go:171","msg":"trace[2069014439] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000658132s","start":"2026-05-25T22:12:02.929946Z","end":"2026-05-25T22:12:04.930604Z","steps":["trace[2069014439] 'agreement among raft nodes before linearized reading' (duration: 2.000494037s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:04.930741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.929905Z","time spent":"2.000822388s","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/25 22:12:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:05.405771Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.52098825s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T22:12:05.406072Z","caller":"traceutil/trace.go:171","msg":"trace[991022310] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"4.521359373s","start":"2026-05-25T22:12:00.884691Z","end":"2026-05-25T22:12:05.406051Z","steps":["trace[991022310] 'process raft request' (duration: 4.521257199s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.406187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.884668Z","time spent":"4.521459196s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:05.458877Z","caller":"traceutil/trace.go:171","msg":"trace[171010089] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"3.579738842s","start":"2026-05-25T22:12:01.879114Z","end":"2026-05-25T22:12:05.458853Z","steps":["trace[171010089] 'process raft request' (duration: 3.579666459s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.459047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.879095Z","time spent":"3.579854776s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:05.459056Z","caller":"traceutil/trace.go:171","msg":"trace[250484700] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"4.049391657s","start":"2026-05-25T22:12:01.409642Z","end":"2026-05-25T22:12:05.459034Z","steps":["trace[250484700] 'process raft request' (duration: 4.048950192s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.459144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.409619Z","time spent":"4.04948506s","remote":"127.0.0.1:43774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:05.459168Z","caller":"traceutil/trace.go:171","msg":"trace[442408303] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"3.920342064s","start":"2026-05-25T22:12:01.538785Z","end":"2026-05-25T22:12:05.459127Z","steps":["trace[442408303] 'process raft request' (duration: 3.919963421s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.45926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.538773Z","time spent":"3.920451327s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:05.45932Z","caller":"traceutil/trace.go:171","msg":"trace[725477657] transaction","detail":"{read_only:false; response_revision:5557; number_of_response:1; }","duration":"2.251916731s","start":"2026-05-25T22:12:03.207342Z","end":"2026-05-25T22:12:05.459259Z","steps":["trace[725477657] 'process raft request' (duration: 2.251472065s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.459404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:03.207317Z","time spent":"2.252042385s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:05.459661Z","caller":"traceutil/trace.go:171","msg":"trace[1615522989] linearizableReadLoop","detail":"{readStateIndex:5761; appliedIndex:5755; }","duration":"4.556261006s","start":"2026-05-25T22:12:00.903379Z","end":"2026-05-25T22:12:05.45964Z","steps":["trace[1615522989] 'read index received' (duration: 4.50270937s)","trace[1615522989] 'applied index is now lower than readState.Index' (duration: 53.550496ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:05.459777Z","caller":"traceutil/trace.go:171","msg":"trace[1310243164] transaction","detail":"{read_only:false; response_revision:5558; number_of_response:1; }","duration":"552.06405ms","start":"2026-05-25T22:12:04.907697Z","end":"2026-05-25T22:12:05.459761Z","steps":["trace[1310243164] 'process raft request' (duration: 551.49714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.459868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:04.907679Z","time spent":"552.141112ms","remote":"127.0.0.1:43836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:12:05.460235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.54267738s","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-25T22:12:05.46062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.282564399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:05.460658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.182722096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-25T22:12:05.46069Z","caller":"traceutil/trace.go:171","msg":"trace[405028161] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5559; }","duration":"2.282675092s","start":"2026-05-25T22:12:03.177996Z","end":"2026-05-25T22:12:05.460671Z","steps":["trace[405028161] 'agreement among raft nodes before linearized reading' (duration: 2.282567399s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.460748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:03.177974Z","time spent":"2.282759716s","remote":"127.0.0.1:48734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:05.460774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.630971688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8314"} {"level":"info","ts":"2026-05-25T22:12:05.460838Z","caller":"traceutil/trace.go:171","msg":"trace[783042437] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5559; }","duration":"2.631070081s","start":"2026-05-25T22:12:02.829761Z","end":"2026-05-25T22:12:05.460831Z","steps":["trace[783042437] 'agreement among raft nodes before linearized reading' (duration: 2.630963297s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.460857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.829739Z","time spent":"2.631111853s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":8337,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-05-25T22:12:05.461015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.687083217s","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-25T22:12:05.461042Z","caller":"traceutil/trace.go:171","msg":"trace[1300865605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5559; }","duration":"2.687134329s","start":"2026-05-25T22:12:02.773901Z","end":"2026-05-25T22:12:05.461036Z","steps":["trace[1300865605] 'agreement among raft nodes before linearized reading' (duration: 2.687067167s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.461088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.773888Z","time spent":"2.687192382s","remote":"127.0.0.1:44032","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-25T22:12:05.461118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706378988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:05.461167Z","caller":"traceutil/trace.go:171","msg":"trace[20379124] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5559; }","duration":"1.70645806s","start":"2026-05-25T22:12:03.754695Z","end":"2026-05-25T22:12:05.461153Z","steps":["trace[20379124] 'agreement among raft nodes before linearized reading' (duration: 1.706382136s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.461207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:03.754675Z","time spent":"1.706519533s","remote":"127.0.0.1:40744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:05.461297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.847155352s","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-25T22:12:05.461324Z","caller":"traceutil/trace.go:171","msg":"trace[1836409456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5559; }","duration":"2.847211244s","start":"2026-05-25T22:12:02.614107Z","end":"2026-05-25T22:12:05.461318Z","steps":["trace[1836409456] 'agreement among raft nodes before linearized reading' (duration: 2.847127291s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.461373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.614093Z","time spent":"2.847272605s","remote":"127.0.0.1:44032","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-25T22:12:05.461499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.009440042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:12:05.461526Z","caller":"traceutil/trace.go:171","msg":"trace[1495732156] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5559; }","duration":"3.009504214s","start":"2026-05-25T22:12:02.452015Z","end":"2026-05-25T22:12:05.46152Z","steps":["trace[1495732156] 'agreement among raft nodes before linearized reading' (duration: 3.009458602s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.461576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.451996Z","time spent":"3.009570276s","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:05.461002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.831429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:12:05.461688Z","caller":"traceutil/trace.go:171","msg":"trace[2021752115] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5559; }","duration":"853.553045ms","start":"2026-05-25T22:12:04.608121Z","end":"2026-05-25T22:12:05.461674Z","steps":["trace[2021752115] 'agreement among raft nodes before linearized reading' (duration: 852.837269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.461719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:04.608101Z","time spent":"853.608387ms","remote":"127.0.0.1:54934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:05.460685Z","caller":"traceutil/trace.go:171","msg":"trace[1675947606] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5559; }","duration":"3.182749847s","start":"2026-05-25T22:12:02.277927Z","end":"2026-05-25T22:12:05.460677Z","steps":["trace[1675947606] 'agreement among raft nodes before linearized reading' (duration: 3.182637623s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.461044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.265856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-25T22:12:05.461834Z","caller":"traceutil/trace.go:171","msg":"trace[26531234] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:5559; }","duration":"637.098336ms","start":"2026-05-25T22:12:04.824728Z","end":"2026-05-25T22:12:05.461826Z","steps":["trace[26531234] 'agreement among raft nodes before linearized reading' (duration: 636.193994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.461856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:04.824707Z","time spent":"637.143047ms","remote":"127.0.0.1:43802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"info","ts":"2026-05-25T22:12:05.460373Z","caller":"traceutil/trace.go:171","msg":"trace[446138001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5559; }","duration":"4.542848686s","start":"2026-05-25T22:12:00.917503Z","end":"2026-05-25T22:12:05.460352Z","steps":["trace[446138001] 'agreement among raft nodes before linearized reading' (duration: 4.542395641s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.461057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113874093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:05.461998Z","caller":"traceutil/trace.go:171","msg":"trace[1833538773] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:5559; }","duration":"1.114854758s","start":"2026-05-25T22:12:04.347135Z","end":"2026-05-25T22:12:05.46199Z","steps":["trace[1833538773] 'agreement among raft nodes before linearized reading' (duration: 1.113895184s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.462032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:04.347114Z","time spent":"1.11490872s","remote":"127.0.0.1:40892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:05.462045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.917491Z","time spent":"4.544487364s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T22:12:05.462095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.954947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:05.461834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.277875Z","time spent":"3.183950059s","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-25T22:12:05.462166Z","caller":"traceutil/trace.go:171","msg":"trace[1039071937] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:5559; }","duration":"332.091612ms","start":"2026-05-25T22:12:05.13006Z","end":"2026-05-25T22:12:05.462152Z","steps":["trace[1039071937] 'agreement among raft nodes before linearized reading' (duration: 331.953877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.462114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.723053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:05.462214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.130039Z","time spent":"332.161404ms","remote":"127.0.0.1:53184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:05.462285Z","caller":"traceutil/trace.go:171","msg":"trace[1389512375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5559; }","duration":"550.92183ms","start":"2026-05-25T22:12:04.911356Z","end":"2026-05-25T22:12:05.462277Z","steps":["trace[1389512375] 'agreement among raft nodes before linearized reading' (duration: 550.694722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.462289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.889580188s","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":"warn","ts":"2026-05-25T22:12:05.462308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:04.911345Z","time spent":"550.957611ms","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T22:12:05.462315Z","caller":"traceutil/trace.go:171","msg":"trace[1179598042] 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:5559; }","duration":"3.88964262s","start":"2026-05-25T22:12:01.572664Z","end":"2026-05-25T22:12:05.462306Z","steps":["trace[1179598042] 'agreement among raft nodes before linearized reading' (duration: 3.889532046s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.46244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.918168407s","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-25T22:12:05.462463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.572643Z","time spent":"3.889807426s","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-25T22:12:05.462481Z","caller":"traceutil/trace.go:171","msg":"trace[900557841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5559; }","duration":"3.918228239s","start":"2026-05-25T22:12:01.544236Z","end":"2026-05-25T22:12:05.462465Z","steps":["trace[900557841] 'agreement among raft nodes before linearized reading' (duration: 3.918087094s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.462543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.544226Z","time spent":"3.918311193s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T22:12:05.462582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.298853074s","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-25T22:12:05.462636Z","caller":"traceutil/trace.go:171","msg":"trace[1043107804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5559; }","duration":"3.298942437s","start":"2026-05-25T22:12:02.163678Z","end":"2026-05-25T22:12:05.462621Z","steps":["trace[1043107804] 'agreement among raft nodes before linearized reading' (duration: 3.298803172s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.462678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.163656Z","time spent":"3.299011609s","remote":"127.0.0.1:44032","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-25T22:12:05.462684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.24796475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:05.462757Z","caller":"traceutil/trace.go:171","msg":"trace[1575119168] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:5559; }","duration":"4.248074294s","start":"2026-05-25T22:12:01.214669Z","end":"2026-05-25T22:12:05.462743Z","steps":["trace[1575119168] 'agreement among raft nodes before linearized reading' (duration: 4.2479547s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.462807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.214652Z","time spent":"4.248136927s","remote":"127.0.0.1:40738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:05.45907Z","caller":"traceutil/trace.go:171","msg":"trace[1851497942] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"3.921293357s","start":"2026-05-25T22:12:01.53776Z","end":"2026-05-25T22:12:05.459053Z","steps":["trace[1851497942] 'process raft request' (duration: 3.920933944s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:05.463258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.53773Z","time spent":"3.925379199s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:05.501123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2883} {"level":"warn","ts":"2026-05-25T22:12:06.478968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.095578ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-25T22:12:06.479107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.466173Z","time spent":"1.012929781s","remote":"127.0.0.1:43802","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-25T22:12:06.543748Z","caller":"traceutil/trace.go:171","msg":"trace[1014292052] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"1.076673709s","start":"2026-05-25T22:12:05.467022Z","end":"2026-05-25T22:12:06.543695Z","steps":["trace[1014292052] 'process raft request' (duration: 1.012029199s)","trace[1014292052] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:487; } (duration: 64.453534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:06.543964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.467012Z","time spent":"1.076811244s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:06.544482Z","caller":"traceutil/trace.go:171","msg":"trace[1251724111] transaction","detail":"{read_only:false; response_revision:5565; number_of_response:1; }","duration":"1.075211299s","start":"2026-05-25T22:12:05.469254Z","end":"2026-05-25T22:12:06.544465Z","steps":["trace[1251724111] 'process raft request' (duration: 1.074823926s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:06.544522Z","caller":"traceutil/trace.go:171","msg":"trace[2098026569] linearizableReadLoop","detail":"{readStateIndex:5770; appliedIndex:5764; }","duration":"1.043268125s","start":"2026-05-25T22:12:05.501238Z","end":"2026-05-25T22:12:06.544506Z","steps":["trace[2098026569] 'read index received' (duration: 11.549783ms)","trace[2098026569] 'applied index is now lower than readState.Index' (duration: 1.031715212s)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:06.544611Z","caller":"traceutil/trace.go:171","msg":"trace[1964425443] transaction","detail":"{read_only:false; response_revision:5566; number_of_response:1; }","duration":"689.847941ms","start":"2026-05-25T22:12:05.854752Z","end":"2026-05-25T22:12:06.5446Z","steps":["trace[1964425443] 'process raft request' (duration: 689.362834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:06.544585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.469236Z","time spent":"1.075300911s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:06.544682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.85472Z","time spent":"689.920723ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:06.545201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076276105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f2b1f198-d82c-400f-a72b-00cdcdae9082\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-25T22:12:06.54521Z","caller":"traceutil/trace.go:171","msg":"trace[1707649760] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"1.07788487s","start":"2026-05-25T22:12:05.46709Z","end":"2026-05-25T22:12:06.544974Z","steps":["trace[1707649760] 'process raft request' (duration: 1.076591306s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:06.545243Z","caller":"traceutil/trace.go:171","msg":"trace[1880558346] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f2b1f198-d82c-400f-a72b-00cdcdae9082; range_end:; response_count:1; response_revision:5567; }","duration":"1.076328537s","start":"2026-05-25T22:12:05.468904Z","end":"2026-05-25T22:12:06.545232Z","steps":["trace[1880558346] 'agreement among raft nodes before linearized reading' (duration: 1.076186012s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:06.545273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.468898Z","time spent":"1.076368s","remote":"127.0.0.1:43900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumes/pvc-f2b1f198-d82c-400f-a72b-00cdcdae9082\" "} {"level":"warn","ts":"2026-05-25T22:12:06.545469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077386504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-25T22:12:06.545504Z","caller":"traceutil/trace.go:171","msg":"trace[1244475968] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5567; }","duration":"1.077443766s","start":"2026-05-25T22:12:05.468051Z","end":"2026-05-25T22:12:06.545495Z","steps":["trace[1244475968] 'agreement among raft nodes before linearized reading' (duration: 1.077353213s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:06.545529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.468043Z","time spent":"1.077481027s","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T22:12:06.545758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.302467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:06.54581Z","caller":"traceutil/trace.go:171","msg":"trace[2076848624] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5567; }","duration":"421.396221ms","start":"2026-05-25T22:12:06.124404Z","end":"2026-05-25T22:12:06.545801Z","steps":["trace[2076848624] 'agreement among raft nodes before linearized reading' (duration: 421.257956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:06.545847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:06.124381Z","time spent":"421.457802ms","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:06.546027Z","caller":"traceutil/trace.go:171","msg":"trace[980329854] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"1.078776793s","start":"2026-05-25T22:12:05.46722Z","end":"2026-05-25T22:12:06.545997Z","steps":["trace[980329854] 'process raft request' (duration: 1.076799864s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:06.546183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.467132Z","time spent":"1.078965359s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:06.546195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.438709ms","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-25T22:12:06.546231Z","caller":"traceutil/trace.go:171","msg":"trace[782003019] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5567; }","duration":"192.511252ms","start":"2026-05-25T22:12:06.35371Z","end":"2026-05-25T22:12:06.546221Z","steps":["trace[782003019] 'agreement among raft nodes before linearized reading' (duration: 192.45318ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:06.546324Z","caller":"traceutil/trace.go:171","msg":"trace[1970169450] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"1.078191323s","start":"2026-05-25T22:12:05.468121Z","end":"2026-05-25T22:12:06.546312Z","steps":["trace[1970169450] 'process raft request' (duration: 1.075929004s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:06.546389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.468104Z","time spent":"1.078254224s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:06.548196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:05.467083Z","time spent":"1.078183613s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:06.587539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2883,"took":"1.083652901s","hash":3813849816,"current-db-size-bytes":53882880,"current-db-size":"54 MB","current-db-size-in-use-bytes":29175808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-25T22:12:06.587618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3813849816,"revision":2883,"compact-revision":-1} {"level":"warn","ts":"2026-05-25T22:12:34.274124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:34.774492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:35.274897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:35.77603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:35.90523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000628732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-25T22:12:35.905352Z","caller":"traceutil/trace.go:171","msg":"trace[1228659332] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000792448s","start":"2026-05-25T22:12:33.904528Z","end":"2026-05-25T22:12:35.90532Z","steps":["trace[1228659332] 'agreement among raft nodes before linearized reading' (duration: 2.000645013s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.905461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.904516Z","time spent":"2.000930322s","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/25 22:12:35 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:36.276215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:36.776801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:37.277177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:37.778062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:37.906435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000642631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-25T22:12:37.906611Z","caller":"traceutil/trace.go:171","msg":"trace[872799227] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000873659s","start":"2026-05-25T22:12:35.905718Z","end":"2026-05-25T22:12:37.906592Z","steps":["trace[872799227] 'agreement among raft nodes before linearized reading' (duration: 2.000679303s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:37.906692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:35.905696Z","time spent":"2.000982812s","remote":"127.0.0.1:43768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/25 22:12:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:38.279021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:38.513687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.515978Z","time spent":"4.997672994s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/25 22:12:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:38.559166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.560707Z","time spent":"4.998451064s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/25 22:12:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:38.560385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.561512Z","time spent":"4.998869019s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/25 22:12:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:38.563102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.564142Z","time spent":"4.998953371s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-25T22:12:38.779226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:39.279458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:39.680331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998202849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-25T22:12:39.680765Z","caller":"traceutil/trace.go:171","msg":"trace[117781654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.998713856s","start":"2026-05-25T22:12:34.682023Z","end":"2026-05-25T22:12:39.680737Z","steps":["trace[117781654] 'agreement among raft nodes before linearized reading' (duration: 4.9982544s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:39.680946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.681993Z","time spent":"4.998919343s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/05/25 22:12:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:39.68899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999337546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-25T22:12:39.689137Z","caller":"traceutil/trace.go:171","msg":"trace[833643940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999530843s","start":"2026-05-25T22:12:34.689567Z","end":"2026-05-25T22:12:39.689098Z","steps":["trace[833643940] 'agreement among raft nodes before linearized reading' (duration: 4.999354107s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:39.689212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.689556Z","time spent":"4.999643436s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/05/25 22:12:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:39.690144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999915666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-25T22:12:39.690271Z","caller":"traceutil/trace.go:171","msg":"trace[1088684504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000150383s","start":"2026-05-25T22:12:34.690092Z","end":"2026-05-25T22:12:39.690242Z","steps":["trace[1088684504] 'agreement among raft nodes before linearized reading' (duration: 4.999929216s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:39.690351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.690083Z","time spent":"5.000249796s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/05/25 22:12:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:39.780494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:39.914592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000357378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-25T22:12:39.914733Z","caller":"traceutil/trace.go:171","msg":"trace[1036754695] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000529864s","start":"2026-05-25T22:12:37.914178Z","end":"2026-05-25T22:12:39.914708Z","steps":["trace[1036754695] 'agreement among raft nodes before linearized reading' (duration: 2.000376979s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:39.91487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:37.914167Z","time spent":"2.00068266s","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/25 22:12:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:40.281098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:40.774771Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-05-25T22:12:40.774991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.001069542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-05-25T22:12:40.77506Z","caller":"traceutil/trace.go:171","msg":"trace[2054224262] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; }","duration":"7.001192126s","start":"2026-05-25T22:12:33.773847Z","end":"2026-05-25T22:12:40.77504Z","steps":["trace[2054224262] 'agreement among raft nodes before linearized reading' (duration: 7.001103673s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:40.775122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.773829Z","time spent":"7.001282699s","remote":"127.0.0.1:43920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":0,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-25T22:12:41.275165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:41.776043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567290609020017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:41.914824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999807631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-25T22:12:41.914951Z","caller":"traceutil/trace.go:171","msg":"trace[440528914] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000066689s","start":"2026-05-25T22:12:39.914857Z","end":"2026-05-25T22:12:41.914924Z","steps":["trace[440528914] 'agreement among raft nodes before linearized reading' (duration: 1.999895143s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:41.915014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:39.914833Z","time spent":"2.000169373s","remote":"127.0.0.1:43768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/25 22:12:41 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:42.250063Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"8.73394871s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T22:12:42.300482Z","caller":"traceutil/trace.go:171","msg":"trace[1162519745] transaction","detail":"{read_only:false; response_revision:5755; number_of_response:1; }","duration":"5.141014006s","start":"2026-05-25T22:12:37.159441Z","end":"2026-05-25T22:12:42.300455Z","steps":["trace[1162519745] 'process raft request' (duration: 5.140947203s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.300642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:37.159423Z","time spent":"5.141133459s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:42.300768Z","caller":"traceutil/trace.go:171","msg":"trace[330305035] transaction","detail":"{read_only:false; response_revision:5753; number_of_response:1; }","duration":"6.816044744s","start":"2026-05-25T22:12:35.484713Z","end":"2026-05-25T22:12:42.300758Z","steps":["trace[330305035] 'process raft request' (duration: 6.815616829s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.300802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:35.484689Z","time spent":"6.816096816s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:42.300862Z","caller":"traceutil/trace.go:171","msg":"trace[683047847] transaction","detail":"{read_only:false; response_revision:5754; number_of_response:1; }","duration":"6.241914824s","start":"2026-05-25T22:12:36.058941Z","end":"2026-05-25T22:12:42.300856Z","steps":["trace[683047847] 'process raft request' (duration: 6.241417957s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.300896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:36.058888Z","time spent":"6.241990357s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:42.35188Z","caller":"traceutil/trace.go:171","msg":"trace[721347040] linearizableReadLoop","detail":"{readStateIndex:5966; appliedIndex:5958; }","duration":"1.576930119s","start":"2026-05-25T22:12:40.774919Z","end":"2026-05-25T22:12:42.351849Z","steps":["trace[721347040] 'read index received' (duration: 1.475457584s)","trace[721347040] 'applied index is now lower than readState.Index' (duration: 101.470795ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:42.352172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.200865466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-25T22:12:42.352218Z","caller":"traceutil/trace.go:171","msg":"trace[798649273] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5756; }","duration":"5.200963469s","start":"2026-05-25T22:12:37.151243Z","end":"2026-05-25T22:12:42.352206Z","steps":["trace[798649273] 'agreement among raft nodes before linearized reading' (duration: 5.200758192s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.352177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.564919042s","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":"warn","ts":"2026-05-25T22:12:42.352263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:37.151232Z","time spent":"5.201019401s","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T22:12:42.35228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.526229197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:42.352315Z","caller":"traceutil/trace.go:171","msg":"trace[1403078434] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5756; }","duration":"4.565196981s","start":"2026-05-25T22:12:37.787087Z","end":"2026-05-25T22:12:42.352284Z","steps":["trace[1403078434] 'agreement among raft nodes before linearized reading' (duration: 4.56487177s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:42.352344Z","caller":"traceutil/trace.go:171","msg":"trace[172266380] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:0; response_revision:5756; }","duration":"7.526312741s","start":"2026-05-25T22:12:34.826007Z","end":"2026-05-25T22:12:42.35232Z","steps":["trace[172266380] 'agreement among raft nodes before linearized reading' (duration: 7.526251419s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.352386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:37.787067Z","time spent":"4.565305205s","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:42.352468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.663828983s","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-25T22:12:42.352527Z","caller":"traceutil/trace.go:171","msg":"trace[747459058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5756; }","duration":"7.663880175s","start":"2026-05-25T22:12:34.688617Z","end":"2026-05-25T22:12:42.352497Z","steps":["trace[747459058] 'agreement among raft nodes before linearized reading' (duration: 7.663794502s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.352546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.672311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T22:12:42.352545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.052006442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:12:42.352583Z","caller":"traceutil/trace.go:171","msg":"trace[191373463] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:5756; }","duration":"753.748344ms","start":"2026-05-25T22:12:41.598823Z","end":"2026-05-25T22:12:42.352571Z","steps":["trace[191373463] 'agreement among raft nodes before linearized reading' (duration: 753.662271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.352613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:41.598801Z","time spent":"753.805725ms","remote":"127.0.0.1:43920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":38,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:42.35263Z","caller":"traceutil/trace.go:171","msg":"trace[609326657] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5756; }","duration":"8.052126656s","start":"2026-05-25T22:12:34.300476Z","end":"2026-05-25T22:12:42.352603Z","steps":["trace[609326657] 'agreement among raft nodes before linearized reading' (duration: 8.052020533s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.352657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.829566384s","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-25T22:12:42.352207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.666562146s","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-25T22:12:42.352681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.300442Z","time spent":"8.052228449s","remote":"127.0.0.1:43874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:42.3527Z","caller":"traceutil/trace.go:171","msg":"trace[1007822907] 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:5756; }","duration":"3.829655327s","start":"2026-05-25T22:12:38.52303Z","end":"2026-05-25T22:12:42.352685Z","steps":["trace[1007822907] 'agreement among raft nodes before linearized reading' (duration: 3.829555524s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:42.35272Z","caller":"traceutil/trace.go:171","msg":"trace[2003689974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5756; }","duration":"7.667135595s","start":"2026-05-25T22:12:34.685569Z","end":"2026-05-25T22:12:42.352704Z","steps":["trace[2003689974] 'agreement among raft nodes before linearized reading' (duration: 7.666465202s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.352539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.663388681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T22:12:42.352559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.688606Z","time spent":"7.663946897s","remote":"127.0.0.1:44032","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-25T22:12:42.352872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.944735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:42.352428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.107585481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:42.352874Z","caller":"traceutil/trace.go:171","msg":"trace[1227075431] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:5756; }","duration":"6.663818886s","start":"2026-05-25T22:12:35.68903Z","end":"2026-05-25T22:12:42.352849Z","steps":["trace[1227075431] 'agreement among raft nodes before linearized reading' (duration: 6.663123762s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.352738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:38.523009Z","time spent":"3.829721918s","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-25T22:12:42.353055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.789952132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-25T22:12:42.353095Z","caller":"traceutil/trace.go:171","msg":"trace[846049747] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5756; }","duration":"3.790043125s","start":"2026-05-25T22:12:38.563042Z","end":"2026-05-25T22:12:42.353085Z","steps":["trace[846049747] 'agreement among raft nodes before linearized reading' (duration: 3.789964183s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.353122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.354297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-25T22:12:42.353131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:38.56301Z","time spent":"3.790114438s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-25T22:12:42.353158Z","caller":"traceutil/trace.go:171","msg":"trace[742260935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5756; }","duration":"670.415189ms","start":"2026-05-25T22:12:41.682735Z","end":"2026-05-25T22:12:42.35315Z","steps":["trace[742260935] 'agreement among raft nodes before linearized reading' (duration: 670.337317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.353057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.44357722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b2ee94a1fcff76\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-05-25T22:12:42.353192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:41.682706Z","time spent":"670.480122ms","remote":"127.0.0.1:44032","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-25T22:12:42.353062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:35.689012Z","time spent":"6.663993373s","remote":"127.0.0.1:54694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:42.353056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.182611ms","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-25T22:12:42.353281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.792033913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-25T22:12:42.353241Z","caller":"traceutil/trace.go:171","msg":"trace[476444905] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18b2ee94a1fcff76; range_end:; response_count:1; response_revision:5756; }","duration":"4.443783767s","start":"2026-05-25T22:12:37.909431Z","end":"2026-05-25T22:12:42.353215Z","steps":["trace[476444905] 'agreement among raft nodes before linearized reading' (duration: 4.443217407s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:42.353309Z","caller":"traceutil/trace.go:171","msg":"trace[1118216029] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5756; }","duration":"3.792083994s","start":"2026-05-25T22:12:38.561216Z","end":"2026-05-25T22:12:42.3533Z","steps":["trace[1118216029] 'agreement among raft nodes before linearized reading' (duration: 3.792036763s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:42.353311Z","caller":"traceutil/trace.go:171","msg":"trace[1931158142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5756; }","duration":"662.46662ms","start":"2026-05-25T22:12:41.690831Z","end":"2026-05-25T22:12:42.353298Z","steps":["trace[1931158142] 'agreement among raft nodes before linearized reading' (duration: 662.176951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.353343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:38.561202Z","time spent":"3.792135937s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-25T22:12:42.353359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:41.690812Z","time spent":"662.532853ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T22:12:42.352389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.825986Z","time spent":"7.526395074s","remote":"127.0.0.1:43802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-05-25T22:12:42.3531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.849939098s","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-25T22:12:42.352906Z","caller":"traceutil/trace.go:171","msg":"trace[213852776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5756; }","duration":"430.015878ms","start":"2026-05-25T22:12:41.922881Z","end":"2026-05-25T22:12:42.352896Z","steps":["trace[213852776] 'agreement among raft nodes before linearized reading' (duration: 429.958476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.353421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:37.909373Z","time spent":"4.443961583s","remote":"127.0.0.1:43836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b2ee94a1fcff76\" "} {"level":"warn","ts":"2026-05-25T22:12:42.353472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.008830104s","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-25T22:12:42.352986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.787602943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-25T22:12:42.353501Z","caller":"traceutil/trace.go:171","msg":"trace[1911372787] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5756; }","duration":"4.008892056s","start":"2026-05-25T22:12:38.344599Z","end":"2026-05-25T22:12:42.353491Z","steps":["trace[1911372787] 'agreement among raft nodes before linearized reading' (duration: 4.008814373s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:42.353511Z","caller":"traceutil/trace.go:171","msg":"trace[1834179549] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5756; }","duration":"3.788165441s","start":"2026-05-25T22:12:38.565334Z","end":"2026-05-25T22:12:42.3535Z","steps":["trace[1834179549] 'agreement among raft nodes before linearized reading' (duration: 3.787593512s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.35351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.837413019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:551"} {"level":"warn","ts":"2026-05-25T22:12:42.353536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:38.344569Z","time spent":"4.008959208s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-25T22:12:42.353543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:38.565315Z","time spent":"3.788219723s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-05-25T22:12:42.353555Z","caller":"traceutil/trace.go:171","msg":"trace[852524436] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:5756; }","duration":"3.837457811s","start":"2026-05-25T22:12:38.516084Z","end":"2026-05-25T22:12:42.353542Z","steps":["trace[852524436] 'agreement among raft nodes before linearized reading' (duration: 3.837290285s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.353564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.219096884s","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-25T22:12:42.353471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:41.922856Z","time spent":"430.599758ms","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T22:12:42.35359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:38.516031Z","time spent":"3.837549764s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-05-25T22:12:42.352985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.032908ms","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-25T22:12:42.353607Z","caller":"traceutil/trace.go:171","msg":"trace[1266229827] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5756; }","duration":"5.219165866s","start":"2026-05-25T22:12:37.134427Z","end":"2026-05-25T22:12:42.353593Z","steps":["trace[1266229827] 'agreement among raft nodes before linearized reading' (duration: 5.219100844s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:42.353634Z","caller":"traceutil/trace.go:171","msg":"trace[71309811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5756; }","duration":"660.728161ms","start":"2026-05-25T22:12:41.692895Z","end":"2026-05-25T22:12:42.353623Z","steps":["trace[71309811] 'agreement among raft nodes before linearized reading' (duration: 660.029088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.353739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.291986365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T22:12:42.353748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:41.692873Z","time spent":"660.864896ms","remote":"127.0.0.1:44032","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-25T22:12:42.353761Z","caller":"traceutil/trace.go:171","msg":"trace[1891020119] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5756; }","duration":"7.850407603s","start":"2026-05-25T22:12:34.503039Z","end":"2026-05-25T22:12:42.353446Z","steps":["trace[1891020119] 'agreement among raft nodes before linearized reading' (duration: 7.849386568s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.353882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.503017Z","time spent":"7.850850058s","remote":"127.0.0.1:43898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:42.353642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:37.134406Z","time spent":"5.219229689s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-25T22:12:42.352792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.68554Z","time spent":"7.667231448s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T22:12:42.352947Z","caller":"traceutil/trace.go:171","msg":"trace[578910998] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5756; }","duration":"8.108174181s","start":"2026-05-25T22:12:34.244759Z","end":"2026-05-25T22:12:42.352934Z","steps":["trace[578910998] 'agreement among raft nodes before linearized reading' (duration: 8.107513808s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.355333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.244737Z","time spent":"8.110579702s","remote":"127.0.0.1:48686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:42.353771Z","caller":"traceutil/trace.go:171","msg":"trace[1324796349] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5756; }","duration":"5.292070478s","start":"2026-05-25T22:12:37.061692Z","end":"2026-05-25T22:12:42.353763Z","steps":["trace[1324796349] 'agreement among raft nodes before linearized reading' (duration: 5.291998786s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.35662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:37.061635Z","time spent":"5.294970446s","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":38,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:42.352905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.735483378s","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-25T22:12:42.358189Z","caller":"traceutil/trace.go:171","msg":"trace[1701251265] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5756; }","duration":"3.740825167s","start":"2026-05-25T22:12:38.617348Z","end":"2026-05-25T22:12:42.358174Z","steps":["trace[1701251265] 'agreement among raft nodes before linearized reading' (duration: 3.735421875s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:42.358313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:38.617319Z","time spent":"3.740945681s","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":39,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:14:22.883321Z","caller":"traceutil/trace.go:171","msg":"trace[497328341] transaction","detail":"{read_only:false; response_revision:6470; number_of_response:1; }","duration":"105.488681ms","start":"2026-05-25T22:14:22.777806Z","end":"2026-05-25T22:14:22.883295Z","steps":["trace[497328341] 'process raft request' (duration: 105.330656ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:14:22.883607Z","caller":"traceutil/trace.go:171","msg":"trace[1794786187] linearizableReadLoop","detail":"{readStateIndex:6707; appliedIndex:6705; }","duration":"101.036961ms","start":"2026-05-25T22:14:22.782536Z","end":"2026-05-25T22:14:22.883573Z","steps":["trace[1794786187] 'read index received' (duration: 80.634479ms)","trace[1794786187] 'applied index is now lower than readState.Index' (duration: 20.401182ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:14:22.883829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.273918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-db-sync-2z5qx\" ","response":"range_response_count:1 size:6544"} {"level":"info","ts":"2026-05-25T22:14:22.883867Z","caller":"traceutil/trace.go:171","msg":"trace[1080706496] range","detail":"{range_begin:/registry/pods/openstack/horizon-db-sync-2z5qx; range_end:; response_count:1; response_revision:6472; }","duration":"101.366942ms","start":"2026-05-25T22:14:22.782489Z","end":"2026-05-25T22:14:22.883856Z","steps":["trace[1080706496] 'agreement among raft nodes before linearized reading' (duration: 101.186956ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:14:37.071445Z","caller":"traceutil/trace.go:171","msg":"trace[1464170542] transaction","detail":"{read_only:false; response_revision:6599; number_of_response:1; }","duration":"138.066941ms","start":"2026-05-25T22:14:36.933359Z","end":"2026-05-25T22:14:37.071426Z","steps":["trace[1464170542] 'process raft request' (duration: 90.520377ms)","trace[1464170542] 'compare' (duration: 47.275376ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:14:37.071584Z","caller":"traceutil/trace.go:171","msg":"trace[371296490] transaction","detail":"{read_only:false; response_revision:6600; number_of_response:1; }","duration":"136.218584ms","start":"2026-05-25T22:14:36.935359Z","end":"2026-05-25T22:14:37.071578Z","steps":["trace[371296490] 'process raft request' (duration: 135.884373ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:14:37.072229Z","caller":"traceutil/trace.go:171","msg":"trace[1323474793] transaction","detail":"{read_only:false; response_revision:6601; number_of_response:1; }","duration":"134.972594ms","start":"2026-05-25T22:14:36.937225Z","end":"2026-05-25T22:14:37.072198Z","steps":["trace[1323474793] 'process raft request' (duration: 134.037135ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:17:05.509088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5553} {"level":"info","ts":"2026-05-25T22:17:05.612084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5553,"took":"101.590602ms","hash":522574064,"current-db-size-bytes":53882880,"current-db-size":"54 MB","current-db-size-in-use-bytes":18165760,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-25T22:17:05.612146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":522574064,"revision":5553,"compact-revision":2883}