{"level":"warn","ts":"2026-05-25T14:18:37.598748Z","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-25T14:18:37.598883Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-25T14:18:37.598963Z","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-25T14:18:37.598972Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-05-25T14:18:37.59899Z","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-25T14:18:37.599694Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-05-25T14:18:37.599774Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-25T14:18:37.604983Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.010996ms"} {"level":"info","ts":"2026-05-25T14:18:37.614151Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-05-25T14:18:37.614247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T14:18:37.614266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-05-25T14:18:37.61428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T14:18:37.614289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-05-25T14:18:37.614336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-05-25T14:18:37.625753Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T14:18:37.627787Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T14:18:37.629179Z","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-25T14:18:37.632134Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T14:18:37.632289Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T14:18:37.632487Z","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-25T14:18:37.632578Z","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-25T14:18:37.63259Z","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-25T14:18:37.634075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-05-25T14:18:37.634239Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-05-25T14:18:37.635374Z","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-25T14:18:37.635607Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T14:18:37.635631Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T14:18:37.635678Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-05-25T14:18:37.635684Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-05-25T14:18:37.815172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T14:18:37.815291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T14:18:37.815326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-05-25T14:18:37.815364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-05-25T14:18:37.81538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-05-25T14:18:37.815398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-05-25T14:18:37.815413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-05-25T14:18:37.81883Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T14:18:37.81896Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T14:18:37.819121Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T14:18:37.819117Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:18:37.819442Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T14:18:37.819515Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T14:18:37.820305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:18:37.820493Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:18:37.820562Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:18:37.822853Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-05-25T14:18:37.823571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T14:19:12.462811Z","caller":"traceutil/trace.go:171","msg":"trace[831051675] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"106.657288ms","start":"2026-05-25T14:19:12.355968Z","end":"2026-05-25T14:19:12.462625Z","steps":["trace[831051675] 'process raft request' (duration: 106.493466ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.590181Z","caller":"traceutil/trace.go:171","msg":"trace[1905671138] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"120.198427ms","start":"2026-05-25T14:19:14.469966Z","end":"2026-05-25T14:19:14.590164Z","steps":["trace[1905671138] 'process raft request' (duration: 120.095096ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859219Z","caller":"traceutil/trace.go:171","msg":"trace[944314293] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"330.806236ms","start":"2026-05-25T14:19:14.528357Z","end":"2026-05-25T14:19:14.859163Z","steps":["trace[944314293] 'process raft request' (duration: 330.459174ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859285Z","caller":"traceutil/trace.go:171","msg":"trace[404510153] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"325.439051ms","start":"2026-05-25T14:19:14.533773Z","end":"2026-05-25T14:19:14.859212Z","steps":["trace[404510153] 'process raft request' (duration: 325.185329ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859435Z","caller":"traceutil/trace.go:171","msg":"trace[741091060] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"315.013023ms","start":"2026-05-25T14:19:14.544413Z","end":"2026-05-25T14:19:14.859426Z","steps":["trace[741091060] 'process raft request' (duration: 314.832092ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859486Z","caller":"traceutil/trace.go:171","msg":"trace[1396619340] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"325.638992ms","start":"2026-05-25T14:19:14.533819Z","end":"2026-05-25T14:19:14.859458Z","steps":["trace[1396619340] 'process raft request' (duration: 325.20906ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859514Z","caller":"traceutil/trace.go:171","msg":"trace[1867664905] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"316.785894ms","start":"2026-05-25T14:19:14.542722Z","end":"2026-05-25T14:19:14.859508Z","steps":["trace[1867664905] 'process raft request' (duration: 316.466973ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859563Z","caller":"traceutil/trace.go:171","msg":"trace[813295343] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"304.171052ms","start":"2026-05-25T14:19:14.555368Z","end":"2026-05-25T14:19:14.859539Z","steps":["trace[813295343] 'process raft request' (duration: 304.020871ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.85959Z","caller":"traceutil/trace.go:171","msg":"trace[2010047342] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"310.000881ms","start":"2026-05-25T14:19:14.549584Z","end":"2026-05-25T14:19:14.859585Z","steps":["trace[2010047342] 'process raft request' (duration: 309.709168ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859564Z","caller":"traceutil/trace.go:171","msg":"trace[1508016845] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"331.028518ms","start":"2026-05-25T14:19:14.528505Z","end":"2026-05-25T14:19:14.859534Z","steps":["trace[1508016845] 'read index received' (duration: 62.007166ms)","trace[1508016845] 'applied index is now lower than readState.Index' (duration: 269.020422ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:14.859639Z","caller":"traceutil/trace.go:171","msg":"trace[1332171139] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"302.34355ms","start":"2026-05-25T14:19:14.557272Z","end":"2026-05-25T14:19:14.859616Z","steps":["trace[1332171139] 'process raft request' (duration: 302.161698ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859646Z","caller":"traceutil/trace.go:171","msg":"trace[25377392] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"304.293953ms","start":"2026-05-25T14:19:14.555322Z","end":"2026-05-25T14:19:14.859616Z","steps":["trace[25377392] 'process raft request' (duration: 304.02817ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.859729Z","caller":"traceutil/trace.go:171","msg":"trace[977081641] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"292.356725ms","start":"2026-05-25T14:19:14.567365Z","end":"2026-05-25T14:19:14.859722Z","steps":["trace[977081641] 'process raft request' (duration: 292.123032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:14.85972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.201449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:19:14.859792Z","caller":"traceutil/trace.go:171","msg":"trace[1273367087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"331.32541ms","start":"2026-05-25T14:19:14.528453Z","end":"2026-05-25T14:19:14.859779Z","steps":["trace[1273367087] 'agreement among raft nodes before linearized reading' (duration: 331.191679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:14.859848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.528434Z","time spent":"331.40193ms","remote":"127.0.0.1:56386","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-25T14:19:14.859896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.555312Z","time spent":"304.409124ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:14.859892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.528338Z","time spent":"330.981977ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:14.860098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.533762Z","time spent":"325.620332ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:14.860113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.557264Z","time spent":"302.39131ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:14.860216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.544392Z","time spent":"315.058393ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:14.860307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.533816Z","time spent":"325.683712ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:14.860402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.542712Z","time spent":"316.812794ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:14.860486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.555364Z","time spent":"304.213033ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:14.86055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:14.549573Z","time spent":"310.030591ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T14:19:14.875595Z","caller":"traceutil/trace.go:171","msg":"trace[454554082] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"172.75839ms","start":"2026-05-25T14:19:14.702821Z","end":"2026-05-25T14:19:14.875579Z","steps":["trace[454554082] 'process raft request' (duration: 172.70004ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:14.876016Z","caller":"traceutil/trace.go:171","msg":"trace[2038707472] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"273.732612ms","start":"2026-05-25T14:19:14.602275Z","end":"2026-05-25T14:19:14.876008Z","steps":["trace[2038707472] 'process raft request' (duration: 273.137958ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.052953Z","caller":"traceutil/trace.go:171","msg":"trace[1141019280] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"106.891319ms","start":"2026-05-25T14:19:14.946038Z","end":"2026-05-25T14:19:15.052929Z","steps":["trace[1141019280] 'compare' (duration: 98.771764ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.053275Z","caller":"traceutil/trace.go:171","msg":"trace[696725305] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"106.838887ms","start":"2026-05-25T14:19:14.946415Z","end":"2026-05-25T14:19:15.053254Z","steps":["trace[696725305] 'process raft request' (duration: 106.474275ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.053402Z","caller":"traceutil/trace.go:171","msg":"trace[213483530] transaction","detail":"{read_only:false; number_of_response:1; response_revision:515; }","duration":"104.269962ms","start":"2026-05-25T14:19:14.949119Z","end":"2026-05-25T14:19:15.053389Z","steps":["trace[213483530] 'process raft request' (duration: 104.028131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:15.417108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.134882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:19:15.417497Z","caller":"traceutil/trace.go:171","msg":"trace[809665711] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"292.752115ms","start":"2026-05-25T14:19:15.124725Z","end":"2026-05-25T14:19:15.417477Z","steps":["trace[809665711] 'process raft request' (duration: 292.554654ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.417511Z","caller":"traceutil/trace.go:171","msg":"trace[1088763646] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"346.628756ms","start":"2026-05-25T14:19:15.070839Z","end":"2026-05-25T14:19:15.417468Z","steps":["trace[1088763646] 'process raft request' (duration: 185.738899ms)","trace[1088763646] 'compare' (duration: 160.002741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:19:15.417718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:15.070829Z","time spent":"346.782717ms","remote":"127.0.0.1:56660","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-25T14:19:15.424183Z","caller":"traceutil/trace.go:171","msg":"trace[2093610263] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"281.661513ms","start":"2026-05-25T14:19:15.142512Z","end":"2026-05-25T14:19:15.424173Z","steps":["trace[2093610263] 'process raft request' (duration: 281.614713ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.424506Z","caller":"traceutil/trace.go:171","msg":"trace[1381904081] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"283.107722ms","start":"2026-05-25T14:19:15.14139Z","end":"2026-05-25T14:19:15.424498Z","steps":["trace[1381904081] 'process raft request' (duration: 282.653299ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.619013Z","caller":"traceutil/trace.go:171","msg":"trace[794086581] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"145.676108ms","start":"2026-05-25T14:19:15.473318Z","end":"2026-05-25T14:19:15.618994Z","steps":["trace[794086581] 'process raft request' (duration: 145.598368ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.619306Z","caller":"traceutil/trace.go:171","msg":"trace[1061880926] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"171.356825ms","start":"2026-05-25T14:19:15.447908Z","end":"2026-05-25T14:19:15.619265Z","steps":["trace[1061880926] 'process raft request' (duration: 151.696677ms)","trace[1061880926] 'compare' (duration: 19.176665ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:15.620444Z","caller":"traceutil/trace.go:171","msg":"trace[1364126285] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"112.25569ms","start":"2026-05-25T14:19:15.508152Z","end":"2026-05-25T14:19:15.620407Z","steps":["trace[1364126285] 'process raft request' (duration: 111.082833ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.797769Z","caller":"traceutil/trace.go:171","msg":"trace[698136898] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"155.750954ms","start":"2026-05-25T14:19:15.641524Z","end":"2026-05-25T14:19:15.797275Z","steps":["trace[698136898] 'process raft request' (duration: 142.983811ms)","trace[698136898] 'compare' (duration: 12.587212ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:15.801116Z","caller":"traceutil/trace.go:171","msg":"trace[149618276] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"154.433805ms","start":"2026-05-25T14:19:15.646651Z","end":"2026-05-25T14:19:15.801085Z","steps":["trace[149618276] 'process raft request' (duration: 154.192224ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:15.80124Z","caller":"traceutil/trace.go:171","msg":"trace[736059979] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"112.19078ms","start":"2026-05-25T14:19:15.688863Z","end":"2026-05-25T14:19:15.801054Z","steps":["trace[736059979] 'process raft request' (duration: 112.134399ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:16.071981Z","caller":"traceutil/trace.go:171","msg":"trace[761231137] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"254.879306ms","start":"2026-05-25T14:19:15.817072Z","end":"2026-05-25T14:19:16.071951Z","steps":["trace[761231137] 'process raft request' (duration: 158.988035ms)","trace[761231137] 'compare' (duration: 95.675029ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:16.095662Z","caller":"traceutil/trace.go:171","msg":"trace[1605609342] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:558; }","duration":"257.701353ms","start":"2026-05-25T14:19:15.837936Z","end":"2026-05-25T14:19:16.095638Z","steps":["trace[1605609342] 'read index received' (duration: 138.066358ms)","trace[1605609342] 'applied index is now lower than readState.Index' (duration: 119.634045ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:16.095714Z","caller":"traceutil/trace.go:171","msg":"trace[1267330259] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"277.19877ms","start":"2026-05-25T14:19:15.818467Z","end":"2026-05-25T14:19:16.095666Z","steps":["trace[1267330259] 'process raft request' (duration: 276.891358ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:16.095754Z","caller":"traceutil/trace.go:171","msg":"trace[1171048169] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"270.252305ms","start":"2026-05-25T14:19:15.825458Z","end":"2026-05-25T14:19:16.09571Z","steps":["trace[1171048169] 'process raft request' (duration: 270.137045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:16.095896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.927284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T14:19:16.095953Z","caller":"traceutil/trace.go:171","msg":"trace[1994094523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"258.065217ms","start":"2026-05-25T14:19:15.83787Z","end":"2026-05-25T14:19:16.095935Z","steps":["trace[1994094523] 'agreement among raft nodes before linearized reading' (duration: 257.913975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:16.096017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.013986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T14:19:16.096055Z","caller":"traceutil/trace.go:171","msg":"trace[1609248511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"258.095096ms","start":"2026-05-25T14:19:15.837947Z","end":"2026-05-25T14:19:16.096042Z","steps":["trace[1609248511] 'agreement among raft nodes before linearized reading' (duration: 257.994696ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:16.30914Z","caller":"traceutil/trace.go:171","msg":"trace[218060679] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"207.752795ms","start":"2026-05-25T14:19:16.101361Z","end":"2026-05-25T14:19:16.309114Z","steps":["trace[218060679] 'process raft request' (duration: 168.902853ms)","trace[218060679] 'compare' (duration: 38.657531ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:16.309472Z","caller":"traceutil/trace.go:171","msg":"trace[1642071011] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"193.259571ms","start":"2026-05-25T14:19:16.11619Z","end":"2026-05-25T14:19:16.309449Z","steps":["trace[1642071011] 'process raft request' (duration: 193.10762ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:16.309504Z","caller":"traceutil/trace.go:171","msg":"trace[244809892] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"206.066533ms","start":"2026-05-25T14:19:16.103426Z","end":"2026-05-25T14:19:16.309492Z","steps":["trace[244809892] 'process raft request' (duration: 205.6344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:16.559382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.580584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:19:16.560337Z","caller":"traceutil/trace.go:171","msg":"trace[597774927] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"215.128751ms","start":"2026-05-25T14:19:16.345185Z","end":"2026-05-25T14:19:16.560313Z","steps":["trace[597774927] 'process raft request' (duration: 66.51698ms)","trace[597774927] 'compare' (duration: 147.425633ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:16.563526Z","caller":"traceutil/trace.go:171","msg":"trace[1172399717] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"139.285432ms","start":"2026-05-25T14:19:16.424225Z","end":"2026-05-25T14:19:16.56351Z","steps":["trace[1172399717] 'process raft request' (duration: 138.941189ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:16.619351Z","caller":"traceutil/trace.go:171","msg":"trace[1068915709] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"166.848519ms","start":"2026-05-25T14:19:16.452485Z","end":"2026-05-25T14:19:16.619334Z","steps":["trace[1068915709] 'process raft request' (duration: 166.708718ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:16.850052Z","caller":"traceutil/trace.go:171","msg":"trace[23289579] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"255.871455ms","start":"2026-05-25T14:19:16.594155Z","end":"2026-05-25T14:19:16.850026Z","steps":["trace[23289579] 'read index received' (duration: 25.079732ms)","trace[23289579] 'applied index is now lower than readState.Index' (duration: 230.790773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:19:16.850194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.017546ms","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-25T14:19:16.850191Z","caller":"traceutil/trace.go:171","msg":"trace[1508912850] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"257.156503ms","start":"2026-05-25T14:19:16.593002Z","end":"2026-05-25T14:19:16.850158Z","steps":["trace[1508912850] 'process raft request' (duration: 158.920738ms)","trace[1508912850] 'compare' (duration: 97.971884ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:16.850236Z","caller":"traceutil/trace.go:171","msg":"trace[931245143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"256.113627ms","start":"2026-05-25T14:19:16.59411Z","end":"2026-05-25T14:19:16.850224Z","steps":["trace[931245143] 'agreement among raft nodes before linearized reading' (duration: 256.022866ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:16.8511Z","caller":"traceutil/trace.go:171","msg":"trace[1439206339] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"201.475194ms","start":"2026-05-25T14:19:16.649612Z","end":"2026-05-25T14:19:16.851087Z","steps":["trace[1439206339] 'process raft request' (duration: 201.396423ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:16.851315Z","caller":"traceutil/trace.go:171","msg":"trace[1951746807] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"211.835321ms","start":"2026-05-25T14:19:16.639444Z","end":"2026-05-25T14:19:16.851279Z","steps":["trace[1951746807] 'process raft request' (duration: 211.454478ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:17.610582Z","caller":"traceutil/trace.go:171","msg":"trace[1850089271] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"135.033838ms","start":"2026-05-25T14:19:17.475488Z","end":"2026-05-25T14:19:17.610522Z","steps":["trace[1850089271] 'process raft request' (duration: 79.795443ms)","trace[1850089271] 'compare' (duration: 55.010974ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:18.456103Z","caller":"traceutil/trace.go:171","msg":"trace[463018220] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"113.860348ms","start":"2026-05-25T14:19:18.342194Z","end":"2026-05-25T14:19:18.456055Z","steps":["trace[463018220] 'process raft request' (duration: 113.248444ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:21.905832Z","caller":"traceutil/trace.go:171","msg":"trace[702273339] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"446.842221ms","start":"2026-05-25T14:19:21.458963Z","end":"2026-05-25T14:19:21.905805Z","steps":["trace[702273339] 'process raft request' (duration: 444.221394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:21.905953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:21.458921Z","time spent":"446.980012ms","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:19:22.432911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.398161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:19:22.433023Z","caller":"traceutil/trace.go:171","msg":"trace[166869643] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"902.134352ms","start":"2026-05-25T14:19:21.530871Z","end":"2026-05-25T14:19:22.433006Z","steps":["trace[166869643] 'read index received' (duration: 371.151005ms)","trace[166869643] 'applied index is now lower than readState.Index' (duration: 530.982247ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:22.433223Z","caller":"traceutil/trace.go:171","msg":"trace[2023476041] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"971.834199ms","start":"2026-05-25T14:19:21.461377Z","end":"2026-05-25T14:19:22.433211Z","steps":["trace[2023476041] 'process raft request' (duration: 786.028846ms)","trace[2023476041] 'compare' (duration: 185.271009ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:19:22.433289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:21.46136Z","time spent":"971.89863ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:19:22.433525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.327372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-45k9x\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-25T14:19:22.433583Z","caller":"traceutil/trace.go:171","msg":"trace[378223116] range","detail":"{range_begin:/registry/pods/kube-system/cilium-45k9x; range_end:; response_count:1; response_revision:585; }","duration":"851.418942ms","start":"2026-05-25T14:19:21.582146Z","end":"2026-05-25T14:19:22.433565Z","steps":["trace[378223116] 'agreement among raft nodes before linearized reading' (duration: 851.115601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:22.433547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.38373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T14:19:22.433573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.334497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-25T14:19:22.43362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:21.582122Z","time spent":"851.489423ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-45k9x\" "} {"level":"warn","ts":"2026-05-25T14:19:22.433581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.668975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:19:22.433646Z","caller":"traceutil/trace.go:171","msg":"trace[1956725044] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:585; }","duration":"458.537531ms","start":"2026-05-25T14:19:21.975083Z","end":"2026-05-25T14:19:22.433621Z","steps":["trace[1956725044] 'agreement among raft nodes before linearized reading' (duration: 458.33952ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:22.433655Z","caller":"traceutil/trace.go:171","msg":"trace[625380137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"223.448028ms","start":"2026-05-25T14:19:22.210183Z","end":"2026-05-25T14:19:22.433631Z","steps":["trace[625380137] 'agreement among raft nodes before linearized reading' (duration: 223.111916ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:22.433688Z","caller":"traceutil/trace.go:171","msg":"trace[1932362547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"902.821936ms","start":"2026-05-25T14:19:21.530832Z","end":"2026-05-25T14:19:22.433654Z","steps":["trace[1932362547] 'agreement among raft nodes before linearized reading' (duration: 902.630605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:22.433693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:21.975062Z","time spent":"458.623641ms","remote":"127.0.0.1:56586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-25T14:19:22.43373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:21.530819Z","time spent":"902.904296ms","remote":"127.0.0.1:56394","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-25T14:19:22.433813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.271053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-25T14:19:22.43385Z","caller":"traceutil/trace.go:171","msg":"trace[1050591904] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:585; }","duration":"635.343293ms","start":"2026-05-25T14:19:21.798495Z","end":"2026-05-25T14:19:22.433839Z","steps":["trace[1050591904] 'agreement among raft nodes before linearized reading' (duration: 635.283483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:22.433876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:21.798476Z","time spent":"635.393883ms","remote":"127.0.0.1:56412","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.77\" "} {"level":"warn","ts":"2026-05-25T14:19:23.188987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.390924ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-25T14:19:23.189375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:22.43544Z","time spent":"753.925283ms","remote":"127.0.0.1:56412","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-25T14:19:23.189766Z","caller":"traceutil/trace.go:171","msg":"trace[1428854004] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"749.593055ms","start":"2026-05-25T14:19:22.440148Z","end":"2026-05-25T14:19:23.189741Z","steps":["trace[1428854004] 'process raft request' (duration: 749.221803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:23.189906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:22.44014Z","time spent":"749.665335ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:19:23.190188Z","caller":"traceutil/trace.go:171","msg":"trace[658637253] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"750.740402ms","start":"2026-05-25T14:19:22.439414Z","end":"2026-05-25T14:19:23.190155Z","steps":["trace[658637253] 'process raft request' (duration: 749.694627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:23.190339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:22.439393Z","time spent":"750.861724ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:19:23.190195Z","caller":"traceutil/trace.go:171","msg":"trace[634010378] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"741.570394ms","start":"2026-05-25T14:19:22.4486Z","end":"2026-05-25T14:19:23.19017Z","steps":["trace[634010378] 'process raft request' (duration: 740.81016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:23.190461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:22.448573Z","time spent":"741.839407ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:19:23.190718Z","caller":"traceutil/trace.go:171","msg":"trace[40251189] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:610; }","duration":"659.047099ms","start":"2026-05-25T14:19:22.531658Z","end":"2026-05-25T14:19:23.190705Z","steps":["trace[40251189] 'read index received' (duration: 346.007055ms)","trace[40251189] 'applied index is now lower than readState.Index' (duration: 313.038504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:19:23.190809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.14877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:19:23.190838Z","caller":"traceutil/trace.go:171","msg":"trace[275085379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"659.21134ms","start":"2026-05-25T14:19:22.53162Z","end":"2026-05-25T14:19:23.190832Z","steps":["trace[275085379] 'agreement among raft nodes before linearized reading' (duration: 659.14709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:23.190859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:22.531603Z","time spent":"659.25125ms","remote":"127.0.0.1:56394","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-25T14:19:23.191565Z","caller":"traceutil/trace.go:171","msg":"trace[1167068679] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"348.438804ms","start":"2026-05-25T14:19:22.843116Z","end":"2026-05-25T14:19:23.191555Z","steps":["trace[1167068679] 'process raft request' (duration: 346.900525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:23.191806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:22.843071Z","time spent":"348.539995ms","remote":"127.0.0.1:56470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:19:23.196621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.643788ms","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-25T14:19:23.197029Z","caller":"traceutil/trace.go:171","msg":"trace[507344697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"298.14426ms","start":"2026-05-25T14:19:22.898869Z","end":"2026-05-25T14:19:23.197013Z","steps":["trace[507344697] 'agreement among raft nodes before linearized reading' (duration: 297.473246ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:24.87714Z","caller":"traceutil/trace.go:171","msg":"trace[4645277] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"150.778894ms","start":"2026-05-25T14:19:24.726329Z","end":"2026-05-25T14:19:24.877108Z","steps":["trace[4645277] 'process raft request' (duration: 133.166426ms)","trace[4645277] 'compare' (duration: 17.171896ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:39.577101Z","caller":"traceutil/trace.go:171","msg":"trace[1413196066] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"285.681418ms","start":"2026-05-25T14:19:39.291376Z","end":"2026-05-25T14:19:39.577058Z","steps":["trace[1413196066] 'process raft request' (duration: 285.487433ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:39.921661Z","caller":"traceutil/trace.go:171","msg":"trace[1595620152] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:792; }","duration":"389.343475ms","start":"2026-05-25T14:19:39.532268Z","end":"2026-05-25T14:19:39.921612Z","steps":["trace[1595620152] 'read index received' (duration: 44.654518ms)","trace[1595620152] 'applied index is now lower than readState.Index' (duration: 344.687526ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:19:39.92181Z","caller":"traceutil/trace.go:171","msg":"trace[790200930] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"630.284144ms","start":"2026-05-25T14:19:39.291511Z","end":"2026-05-25T14:19:39.921795Z","steps":["trace[790200930] 'process raft request' (duration: 627.488089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:39.921976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:39.291472Z","time spent":"630.369066ms","remote":"127.0.0.1:56660","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-25T14:19:39.922082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.815866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:19:39.92213Z","caller":"traceutil/trace.go:171","msg":"trace[1607784918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"389.867197ms","start":"2026-05-25T14:19:39.53225Z","end":"2026-05-25T14:19:39.922117Z","steps":["trace[1607784918] 'agreement among raft nodes before linearized reading' (duration: 389.770385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:39.922156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:39.532111Z","time spent":"390.040261ms","remote":"127.0.0.1:56394","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-25T14:19:39.92216Z","caller":"traceutil/trace.go:171","msg":"trace[1217521147] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"625.485112ms","start":"2026-05-25T14:19:39.296664Z","end":"2026-05-25T14:19:39.922149Z","steps":["trace[1217521147] 'process raft request' (duration: 624.834646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:19:39.922216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:19:39.29665Z","time spent":"625.536172ms","remote":"127.0.0.1:56660","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-25T14:19:39.922244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.018825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bznf6\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-25T14:19:39.922318Z","caller":"traceutil/trace.go:171","msg":"trace[819902378] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bznf6; range_end:; response_count:1; response_revision:765; }","duration":"206.139888ms","start":"2026-05-25T14:19:39.716162Z","end":"2026-05-25T14:19:39.922302Z","steps":["trace[819902378] 'agreement among raft nodes before linearized reading' (duration: 205.937373ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:47.070135Z","caller":"traceutil/trace.go:171","msg":"trace[458978016] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"108.343773ms","start":"2026-05-25T14:19:46.961766Z","end":"2026-05-25T14:19:47.07011Z","steps":["trace[458978016] 'process raft request' (duration: 108.138289ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:47.321481Z","caller":"traceutil/trace.go:171","msg":"trace[477936802] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"151.513955ms","start":"2026-05-25T14:19:47.169942Z","end":"2026-05-25T14:19:47.321456Z","steps":["trace[477936802] 'process raft request' (duration: 145.958957ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:47.337744Z","caller":"traceutil/trace.go:171","msg":"trace[99753722] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:873; }","duration":"115.737801ms","start":"2026-05-25T14:19:47.221982Z","end":"2026-05-25T14:19:47.337719Z","steps":["trace[99753722] 'read index received' (duration: 93.892495ms)","trace[99753722] 'applied index is now lower than readState.Index' (duration: 21.843706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:19:47.33797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.963526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-05-25T14:19:47.337994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.978336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-25T14:19:47.338078Z","caller":"traceutil/trace.go:171","msg":"trace[1400884373] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:842; }","duration":"116.087308ms","start":"2026-05-25T14:19:47.221951Z","end":"2026-05-25T14:19:47.338038Z","steps":["trace[1400884373] 'agreement among raft nodes before linearized reading' (duration: 115.961566ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:47.338091Z","caller":"traceutil/trace.go:171","msg":"trace[1768872806] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:842; }","duration":"116.143849ms","start":"2026-05-25T14:19:47.221932Z","end":"2026-05-25T14:19:47.338076Z","steps":["trace[1768872806] 'agreement among raft nodes before linearized reading' (duration: 115.923975ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:19:57.615906Z","caller":"traceutil/trace.go:171","msg":"trace[233078991] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"252.408312ms","start":"2026-05-25T14:19:57.36344Z","end":"2026-05-25T14:19:57.615848Z","steps":["trace[233078991] 'process raft request' (duration: 252.093586ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:16.089746Z","caller":"traceutil/trace.go:171","msg":"trace[982380056] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"247.649195ms","start":"2026-05-25T14:20:15.842056Z","end":"2026-05-25T14:20:16.089705Z","steps":["trace[982380056] 'read index received' (duration: 247.37197ms)","trace[982380056] 'applied index is now lower than readState.Index' (duration: 275.244µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:20:16.090066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.974531ms","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-25T14:20:16.090117Z","caller":"traceutil/trace.go:171","msg":"trace[2075696598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"248.121724ms","start":"2026-05-25T14:20:15.841978Z","end":"2026-05-25T14:20:16.0901Z","steps":["trace[2075696598] 'agreement among raft nodes before linearized reading' (duration: 247.884369ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:16.09016Z","caller":"traceutil/trace.go:171","msg":"trace[237999074] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"380.233631ms","start":"2026-05-25T14:20:15.709855Z","end":"2026-05-25T14:20:16.090089Z","steps":["trace[237999074] 'process raft request' (duration: 379.603101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:20:16.090438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:20:15.709819Z","time spent":"380.474406ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:20:16.143394Z","caller":"traceutil/trace.go:171","msg":"trace[249909176] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"193.961611ms","start":"2026-05-25T14:20:15.949402Z","end":"2026-05-25T14:20:16.143364Z","steps":["trace[249909176] 'process raft request' (duration: 193.816418ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:33.010106Z","caller":"traceutil/trace.go:171","msg":"trace[1153385813] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"141.852626ms","start":"2026-05-25T14:20:32.868212Z","end":"2026-05-25T14:20:33.010065Z","steps":["trace[1153385813] 'process raft request' (duration: 141.613422ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:38.295551Z","caller":"traceutil/trace.go:171","msg":"trace[1463045734] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"119.248021ms","start":"2026-05-25T14:20:38.176265Z","end":"2026-05-25T14:20:38.295513Z","steps":["trace[1463045734] 'process raft request' (duration: 119.016128ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:42.532171Z","caller":"traceutil/trace.go:171","msg":"trace[1472496073] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"223.059237ms","start":"2026-05-25T14:20:42.309087Z","end":"2026-05-25T14:20:42.532146Z","steps":["trace[1472496073] 'process raft request' (duration: 222.905475ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:42.532371Z","caller":"traceutil/trace.go:171","msg":"trace[1712229636] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1505; }","duration":"187.352755ms","start":"2026-05-25T14:20:42.345003Z","end":"2026-05-25T14:20:42.532355Z","steps":["trace[1712229636] 'read index received' (duration: 187.348435ms)","trace[1712229636] 'applied index is now lower than readState.Index' (duration: 3.58µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:20:42.532543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.520217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-25T14:20:42.532952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.097142ms","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-25T14:20:42.533178Z","caller":"traceutil/trace.go:171","msg":"trace[1428773047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1455; }","duration":"187.353165ms","start":"2026-05-25T14:20:42.345796Z","end":"2026-05-25T14:20:42.533149Z","steps":["trace[1428773047] 'agreement among raft nodes before linearized reading' (duration: 186.9633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:20:42.532737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.631679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T14:20:42.533432Z","caller":"traceutil/trace.go:171","msg":"trace[138465931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1455; }","duration":"188.376799ms","start":"2026-05-25T14:20:42.345011Z","end":"2026-05-25T14:20:42.533387Z","steps":["trace[138465931] 'agreement among raft nodes before linearized reading' (duration: 187.593899ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:42.533039Z","caller":"traceutil/trace.go:171","msg":"trace[843808646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1455; }","duration":"187.981195ms","start":"2026-05-25T14:20:42.344958Z","end":"2026-05-25T14:20:42.532939Z","steps":["trace[843808646] 'agreement among raft nodes before linearized reading' (duration: 187.478557ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:58.145019Z","caller":"traceutil/trace.go:171","msg":"trace[1443561385] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"116.029841ms","start":"2026-05-25T14:20:58.028963Z","end":"2026-05-25T14:20:58.144993Z","steps":["trace[1443561385] 'process raft request' (duration: 87.32203ms)","trace[1443561385] 'compare' (duration: 28.58126ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:20:58.316142Z","caller":"traceutil/trace.go:171","msg":"trace[412730511] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"105.818949ms","start":"2026-05-25T14:20:58.210296Z","end":"2026-05-25T14:20:58.316115Z","steps":["trace[412730511] 'process raft request' (duration: 105.404334ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:20:58.316151Z","caller":"traceutil/trace.go:171","msg":"trace[1921829861] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"100.145511ms","start":"2026-05-25T14:20:58.215977Z","end":"2026-05-25T14:20:58.316123Z","steps":["trace[1921829861] 'process raft request' (duration: 99.904878ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:00.851439Z","caller":"traceutil/trace.go:171","msg":"trace[2101277] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"193.038269ms","start":"2026-05-25T14:21:00.658376Z","end":"2026-05-25T14:21:00.851415Z","steps":["trace[2101277] 'process raft request' (duration: 192.889727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:08.033457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812163559439637,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T14:21:08.199483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.270253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:21:08.199587Z","caller":"traceutil/trace.go:171","msg":"trace[1631002004] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"849.152446ms","start":"2026-05-25T14:21:07.350414Z","end":"2026-05-25T14:21:08.199566Z","steps":["trace[1631002004] 'process raft request' (duration: 126.710041ms)","trace[1631002004] 'compare' (duration: 722.104431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:21:08.199645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:07.350398Z","time spent":"849.220206ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:08.230327Z","caller":"traceutil/trace.go:171","msg":"trace[1892752451] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"875.893065ms","start":"2026-05-25T14:21:07.354408Z","end":"2026-05-25T14:21:08.230301Z","steps":["trace[1892752451] 'process raft request' (duration: 875.729843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:08.230484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:07.354383Z","time spent":"876.023507ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:08.230634Z","caller":"traceutil/trace.go:171","msg":"trace[1294689091] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"833.304454ms","start":"2026-05-25T14:21:07.397233Z","end":"2026-05-25T14:21:08.230537Z","steps":["trace[1294689091] 'process raft request' (duration: 833.023051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:08.230884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:07.397207Z","time spent":"833.540089ms","remote":"127.0.0.1:56660","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-25T14:21:08.337027Z","caller":"traceutil/trace.go:171","msg":"trace[1139466598] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1953; }","duration":"803.761975ms","start":"2026-05-25T14:21:07.533238Z","end":"2026-05-25T14:21:08.337Z","steps":["trace[1139466598] 'read index received' (duration: 696.988886ms)","trace[1139466598] 'applied index is now lower than readState.Index' (duration: 106.771989ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:21:08.337333Z","caller":"traceutil/trace.go:171","msg":"trace[289863069] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"454.073037ms","start":"2026-05-25T14:21:07.883028Z","end":"2026-05-25T14:21:08.337101Z","steps":["trace[289863069] 'process raft request' (duration: 420.753283ms)","trace[289863069] 'compare' (duration: 33.06527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:21:08.337559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.810355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-45ae3625-2539-43bb-ab69-1d4d2447caba\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-05-25T14:21:08.337617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.862684ms","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-25T14:21:08.337632Z","caller":"traceutil/trace.go:171","msg":"trace[2084023066] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-45ae3625-2539-43bb-ab69-1d4d2447caba; range_end:; response_count:1; response_revision:1877; }","duration":"656.930446ms","start":"2026-05-25T14:21:07.680676Z","end":"2026-05-25T14:21:08.337607Z","steps":["trace[2084023066] 'agreement among raft nodes before linearized reading' (duration: 656.598103ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:08.337686Z","caller":"traceutil/trace.go:171","msg":"trace[419990603] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1877; }","duration":"593.958456ms","start":"2026-05-25T14:21:07.743704Z","end":"2026-05-25T14:21:08.337662Z","steps":["trace[419990603] 'agreement among raft nodes before linearized reading' (duration: 593.670053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:08.337725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:07.743694Z","time spent":"594.021596ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-25T14:21:08.33774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:07.680653Z","time spent":"657.034308ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-45ae3625-2539-43bb-ab69-1d4d2447caba\" "} {"level":"warn","ts":"2026-05-25T14:21:08.337864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:07.883007Z","time spent":"454.494541ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:21:08.338007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.601575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:21:08.338124Z","caller":"traceutil/trace.go:171","msg":"trace[87564258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1877; }","duration":"804.925318ms","start":"2026-05-25T14:21:07.533172Z","end":"2026-05-25T14:21:08.338097Z","steps":["trace[87564258] 'agreement among raft nodes before linearized reading' (duration: 804.008669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:08.338186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:07.533152Z","time spent":"805.022989ms","remote":"127.0.0.1:56394","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-25T14:21:08.499866Z","caller":"traceutil/trace.go:171","msg":"trace[1127466562] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"153.769637ms","start":"2026-05-25T14:21:08.346069Z","end":"2026-05-25T14:21:08.499838Z","steps":["trace[1127466562] 'process raft request' (duration: 153.573975ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:09.091766Z","caller":"traceutil/trace.go:171","msg":"trace[1950306809] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"128.027902ms","start":"2026-05-25T14:21:08.963711Z","end":"2026-05-25T14:21:09.091739Z","steps":["trace[1950306809] 'process raft request' (duration: 127.97231ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:09.091906Z","caller":"traceutil/trace.go:171","msg":"trace[47387417] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"128.234424ms","start":"2026-05-25T14:21:08.96362Z","end":"2026-05-25T14:21:09.091854Z","steps":["trace[47387417] 'process raft request' (duration: 119.406665ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:17.044853Z","caller":"traceutil/trace.go:171","msg":"trace[508854239] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2041; }","duration":"115.110826ms","start":"2026-05-25T14:21:16.929701Z","end":"2026-05-25T14:21:17.044811Z","steps":["trace[508854239] 'read index received' (duration: 61.208198ms)","trace[508854239] 'applied index is now lower than readState.Index' (duration: 53.898478ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:21:17.04516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.42397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T14:21:17.045241Z","caller":"traceutil/trace.go:171","msg":"trace[1116900749] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1961; }","duration":"115.602431ms","start":"2026-05-25T14:21:16.929621Z","end":"2026-05-25T14:21:17.045223Z","steps":["trace[1116900749] 'agreement among raft nodes before linearized reading' (duration: 115.363079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:22.791703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812163559439898,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-25T14:21:22.847934Z","caller":"traceutil/trace.go:171","msg":"trace[1945745938] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"583.981345ms","start":"2026-05-25T14:21:22.26391Z","end":"2026-05-25T14:21:22.847892Z","steps":["trace[1945745938] 'process raft request' (duration: 583.687211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:22.848193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:22.263874Z","time spent":"584.182258ms","remote":"127.0.0.1:56660","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-25T14:21:22.851444Z","caller":"traceutil/trace.go:171","msg":"trace[1444239919] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"568.673374ms","start":"2026-05-25T14:21:22.282749Z","end":"2026-05-25T14:21:22.851422Z","steps":["trace[1444239919] 'process raft request' (duration: 568.445822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:22.851636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:22.282722Z","time spent":"568.804735ms","remote":"127.0.0.1:56660","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-25T14:21:22.851732Z","caller":"traceutil/trace.go:171","msg":"trace[2019559744] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"483.538474ms","start":"2026-05-25T14:21:22.368164Z","end":"2026-05-25T14:21:22.851703Z","steps":["trace[2019559744] 'process raft request' (duration: 483.18614ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:22.85144Z","caller":"traceutil/trace.go:171","msg":"trace[360313991] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2075; }","duration":"559.774351ms","start":"2026-05-25T14:21:22.29163Z","end":"2026-05-25T14:21:22.851405Z","steps":["trace[360313991] 'read index received' (duration: 555.781349ms)","trace[360313991] 'applied index is now lower than readState.Index' (duration: 3.991912ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:21:22.851931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:22.368146Z","time spent":"483.647435ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:21:22.852155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.812008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-cvmsb\" ","response":"range_response_count:1 size:5854"} {"level":"info","ts":"2026-05-25T14:21:22.852217Z","caller":"traceutil/trace.go:171","msg":"trace[1045583206] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-cvmsb; range_end:; response_count:1; response_revision:1995; }","duration":"507.91438ms","start":"2026-05-25T14:21:22.344285Z","end":"2026-05-25T14:21:22.852199Z","steps":["trace[1045583206] 'agreement among raft nodes before linearized reading' (duration: 507.695187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:22.852267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:22.344266Z","time spent":"507.99162ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":5877,"request content":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-cvmsb\" "} {"level":"warn","ts":"2026-05-25T14:21:22.852327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.955248ms","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-25T14:21:22.85244Z","caller":"traceutil/trace.go:171","msg":"trace[1115943388] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1995; }","duration":"417.08041ms","start":"2026-05-25T14:21:22.43532Z","end":"2026-05-25T14:21:22.852401Z","steps":["trace[1115943388] 'agreement among raft nodes before linearized reading' (duration: 416.784707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:22.852499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:22.435302Z","time spent":"417.18364ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-25T14:21:22.852573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.924344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-25T14:21:22.852804Z","caller":"traceutil/trace.go:171","msg":"trace[1231827189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1995; }","duration":"561.236066ms","start":"2026-05-25T14:21:22.291527Z","end":"2026-05-25T14:21:22.852763Z","steps":["trace[1231827189] 'agreement among raft nodes before linearized reading' (duration: 559.976283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:22.852961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:22.291507Z","time spent":"561.406279ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T14:21:23.100714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.377405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:21:23.100851Z","caller":"traceutil/trace.go:171","msg":"trace[134957788] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"243.047075ms","start":"2026-05-25T14:21:22.857758Z","end":"2026-05-25T14:21:23.100805Z","steps":["trace[134957788] 'process raft request' (duration: 74.468978ms)","trace[134957788] 'compare' (duration: 168.253434ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:21:23.106153Z","caller":"traceutil/trace.go:171","msg":"trace[1216177994] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"244.828015ms","start":"2026-05-25T14:21:22.861291Z","end":"2026-05-25T14:21:23.106119Z","steps":["trace[1216177994] 'process raft request' (duration: 244.618573ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:35.022497Z","caller":"traceutil/trace.go:171","msg":"trace[1890616018] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2157; }","duration":"107.358454ms","start":"2026-05-25T14:21:34.915115Z","end":"2026-05-25T14:21:35.022473Z","steps":["trace[1890616018] 'read index received' (duration: 107.153982ms)","trace[1890616018] 'applied index is now lower than readState.Index' (duration: 203.272µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:21:35.022711Z","caller":"traceutil/trace.go:171","msg":"trace[2031557511] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"120.868097ms","start":"2026-05-25T14:21:34.901819Z","end":"2026-05-25T14:21:35.022687Z","steps":["trace[2031557511] 'process raft request' (duration: 120.431103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:35.023224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.07992ms","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-25T14:21:35.023307Z","caller":"traceutil/trace.go:171","msg":"trace[1971684903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2073; }","duration":"108.193781ms","start":"2026-05-25T14:21:34.915094Z","end":"2026-05-25T14:21:35.023288Z","steps":["trace[1971684903] 'agreement among raft nodes before linearized reading' (duration: 107.850468ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:41.279454Z","caller":"traceutil/trace.go:171","msg":"trace[1806210562] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"326.458319ms","start":"2026-05-25T14:21:40.952974Z","end":"2026-05-25T14:21:41.279433Z","steps":["trace[1806210562] 'process raft request' (duration: 326.262417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.279605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:40.952961Z","time spent":"326.56246ms","remote":"127.0.0.1:56450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:41.534899Z","caller":"traceutil/trace.go:171","msg":"trace[2076362822] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"490.576099ms","start":"2026-05-25T14:21:41.044287Z","end":"2026-05-25T14:21:41.534863Z","steps":["trace[2076362822] 'process raft request' (duration: 490.397637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.535094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:41.044271Z","time spent":"490.7071ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:41.535322Z","caller":"traceutil/trace.go:171","msg":"trace[741999499] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"361.570865ms","start":"2026-05-25T14:21:41.173692Z","end":"2026-05-25T14:21:41.535263Z","steps":["trace[741999499] 'process raft request' (duration: 361.133971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.535497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:41.173674Z","time spent":"361.729628ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:41.535534Z","caller":"traceutil/trace.go:171","msg":"trace[1817524391] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"313.659114ms","start":"2026-05-25T14:21:41.221862Z","end":"2026-05-25T14:21:41.535521Z","steps":["trace[1817524391] 'process raft request' (duration: 313.305059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.535601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:41.221838Z","time spent":"313.727754ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:41.53568Z","caller":"traceutil/trace.go:171","msg":"trace[1361748801] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2194; }","duration":"470.828229ms","start":"2026-05-25T14:21:41.06484Z","end":"2026-05-25T14:21:41.535668Z","steps":["trace[1361748801] 'read index received' (duration: 214.526598ms)","trace[1361748801] 'applied index is now lower than readState.Index' (duration: 256.300331ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:21:41.535757Z","caller":"traceutil/trace.go:171","msg":"trace[1869296380] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"273.030192ms","start":"2026-05-25T14:21:41.262699Z","end":"2026-05-25T14:21:41.535729Z","steps":["trace[1869296380] 'process raft request' (duration: 272.77179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.535842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.009781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-25T14:21:41.535876Z","caller":"traceutil/trace.go:171","msg":"trace[569393527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2113; }","duration":"471.105692ms","start":"2026-05-25T14:21:41.06476Z","end":"2026-05-25T14:21:41.535866Z","steps":["trace[569393527] 'agreement among raft nodes before linearized reading' (duration: 470.9629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.535907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:41.064746Z","time spent":"471.154402ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T14:21:41.535914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.299926ms","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-25T14:21:41.535954Z","caller":"traceutil/trace.go:171","msg":"trace[1482819015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"465.400536ms","start":"2026-05-25T14:21:41.070543Z","end":"2026-05-25T14:21:41.535943Z","steps":["trace[1482819015] 'agreement among raft nodes before linearized reading' (duration: 465.216545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.535986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:41.070516Z","time spent":"465.463437ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-25T14:21:41.536193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.141409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-25T14:21:41.536226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.468801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-25T14:21:41.53625Z","caller":"traceutil/trace.go:171","msg":"trace[1474526066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2113; }","duration":"282.279681ms","start":"2026-05-25T14:21:41.253955Z","end":"2026-05-25T14:21:41.536235Z","steps":["trace[1474526066] 'agreement among raft nodes before linearized reading' (duration: 282.019109ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:41.536258Z","caller":"traceutil/trace.go:171","msg":"trace[394120144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2113; }","duration":"287.526811ms","start":"2026-05-25T14:21:41.248722Z","end":"2026-05-25T14:21:41.536249Z","steps":["trace[394120144] 'agreement among raft nodes before linearized reading' (duration: 287.42555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.536295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.805321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-25T14:21:41.536349Z","caller":"traceutil/trace.go:171","msg":"trace[144445992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2113; }","duration":"350.984063ms","start":"2026-05-25T14:21:41.185344Z","end":"2026-05-25T14:21:41.536328Z","steps":["trace[144445992] 'agreement among raft nodes before linearized reading' (duration: 350.879272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.536386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:41.185329Z","time spent":"351.049113ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T14:21:41.536529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.612181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-25T14:21:41.536559Z","caller":"traceutil/trace.go:171","msg":"trace[1287678876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2113; }","duration":"347.669641ms","start":"2026-05-25T14:21:41.188881Z","end":"2026-05-25T14:21:41.536551Z","steps":["trace[1287678876] 'agreement among raft nodes before linearized reading' (duration: 347.58388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:41.536586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:41.188867Z","time spent":"347.713832ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T14:21:43.354212Z","caller":"traceutil/trace.go:171","msg":"trace[892142294] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"316.553909ms","start":"2026-05-25T14:21:43.037632Z","end":"2026-05-25T14:21:43.354186Z","steps":["trace[892142294] 'process raft request' (duration: 316.403717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:43.354363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:43.037605Z","time spent":"316.680301ms","remote":"127.0.0.1:56660","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-25T14:21:43.68094Z","caller":"traceutil/trace.go:171","msg":"trace[633172576] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2207; }","duration":"131.479689ms","start":"2026-05-25T14:21:43.549436Z","end":"2026-05-25T14:21:43.680916Z","steps":["trace[633172576] 'read index received' (duration: 15.515619ms)","trace[633172576] 'applied index is now lower than readState.Index' (duration: 115.96035ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:21:43.681164Z","caller":"traceutil/trace.go:171","msg":"trace[1277640470] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"140.478574ms","start":"2026-05-25T14:21:43.540651Z","end":"2026-05-25T14:21:43.681129Z","steps":["trace[1277640470] 'process raft request' (duration: 139.912469ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:43.68122Z","caller":"traceutil/trace.go:171","msg":"trace[641420749] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"140.493044ms","start":"2026-05-25T14:21:43.540691Z","end":"2026-05-25T14:21:43.681184Z","steps":["trace[641420749] 'process raft request' (duration: 140.03907ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:43.68139Z","caller":"traceutil/trace.go:171","msg":"trace[2103937862] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"140.600435ms","start":"2026-05-25T14:21:43.540758Z","end":"2026-05-25T14:21:43.681359Z","steps":["trace[2103937862] 'process raft request' (duration: 140.09982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:43.681656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.198755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-25T14:21:43.681787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.704339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-25T14:21:43.681918Z","caller":"traceutil/trace.go:171","msg":"trace[1961257285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2124; }","duration":"111.87896ms","start":"2026-05-25T14:21:43.570015Z","end":"2026-05-25T14:21:43.681894Z","steps":["trace[1961257285] 'agreement among raft nodes before linearized reading' (duration: 111.222233ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:43.681799Z","caller":"traceutil/trace.go:171","msg":"trace[1331742196] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2124; }","duration":"132.324567ms","start":"2026-05-25T14:21:43.549414Z","end":"2026-05-25T14:21:43.681738Z","steps":["trace[1331742196] 'agreement among raft nodes before linearized reading' (duration: 131.721011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:44.112098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.801709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:21:44.112443Z","caller":"traceutil/trace.go:171","msg":"trace[1668144267] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"425.790329ms","start":"2026-05-25T14:21:43.686611Z","end":"2026-05-25T14:21:44.112401Z","steps":["trace[1668144267] 'process raft request' (duration: 229.578647ms)","trace[1668144267] 'compare' (duration: 195.659708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:21:44.112608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:43.686589Z","time spent":"425.939411ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:44.112671Z","caller":"traceutil/trace.go:171","msg":"trace[1583254181] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"426.013022ms","start":"2026-05-25T14:21:43.686626Z","end":"2026-05-25T14:21:44.112639Z","steps":["trace[1583254181] 'process raft request' (duration: 425.585538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:44.112794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:43.686616Z","time spent":"426.121362ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:44.112948Z","caller":"traceutil/trace.go:171","msg":"trace[2077196704] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"425.424976ms","start":"2026-05-25T14:21:43.687499Z","end":"2026-05-25T14:21:44.112924Z","steps":["trace[2077196704] 'process raft request' (duration: 424.788529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:44.113062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:43.687489Z","time spent":"425.515057ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:44.11321Z","caller":"traceutil/trace.go:171","msg":"trace[1601129037] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"425.432216ms","start":"2026-05-25T14:21:43.687756Z","end":"2026-05-25T14:21:44.113189Z","steps":["trace[1601129037] 'process raft request' (duration: 424.611818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:44.113324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:43.687749Z","time spent":"425.522157ms","remote":"127.0.0.1:56660","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-25T14:21:45.555987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024565388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:21:45.556089Z","caller":"traceutil/trace.go:171","msg":"trace[412851793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2129; }","duration":"1.024718479s","start":"2026-05-25T14:21:44.531342Z","end":"2026-05-25T14:21:45.556061Z","steps":["trace[412851793] 'range keys from in-memory index tree' (duration: 1.024406086s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:45.556145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:44.531321Z","time spent":"1.02480761s","remote":"127.0.0.1:56394","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-25T14:21:45.556838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.114711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:21:45.556893Z","caller":"traceutil/trace.go:171","msg":"trace[700024245] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2129; }","duration":"119.173672ms","start":"2026-05-25T14:21:45.437706Z","end":"2026-05-25T14:21:45.556879Z","steps":["trace[700024245] 'count revisions from in-memory index tree' (duration: 119.01478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:45.556825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.367177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-05-25T14:21:45.556991Z","caller":"traceutil/trace.go:171","msg":"trace[15536828] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2129; }","duration":"193.705749ms","start":"2026-05-25T14:21:45.363236Z","end":"2026-05-25T14:21:45.556942Z","steps":["trace[15536828] 'range keys from in-memory index tree' (duration: 193.123484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:45.557145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071462115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T14:21:45.557312Z","caller":"traceutil/trace.go:171","msg":"trace[210636027] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2129; }","duration":"1.073290461s","start":"2026-05-25T14:21:44.483996Z","end":"2026-05-25T14:21:45.557286Z","steps":["trace[210636027] 'count revisions from in-memory index tree' (duration: 1.071294233s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:45.557365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:44.48397Z","time spent":"1.073382662s","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-25T14:21:45.558752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.52741ms","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-25T14:21:45.558985Z","caller":"traceutil/trace.go:171","msg":"trace[205513304] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2129; }","duration":"868.808162ms","start":"2026-05-25T14:21:44.690154Z","end":"2026-05-25T14:21:45.558962Z","steps":["trace[205513304] 'range keys from in-memory index tree' (duration: 866.447191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:45.559113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:44.690114Z","time spent":"868.984393ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-25T14:21:45.966964Z","caller":"traceutil/trace.go:171","msg":"trace[2102709927] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"396.772519ms","start":"2026-05-25T14:21:45.570167Z","end":"2026-05-25T14:21:45.96694Z","steps":["trace[2102709927] 'process raft request' (duration: 396.587128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:45.967287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:45.570139Z","time spent":"396.966801ms","remote":"127.0.0.1:56660","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-25T14:21:46.106005Z","caller":"traceutil/trace.go:171","msg":"trace[398849320] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"418.780374ms","start":"2026-05-25T14:21:45.687202Z","end":"2026-05-25T14:21:46.105982Z","steps":["trace[398849320] 'process raft request' (duration: 418.682673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:46.106222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:45.687191Z","time spent":"418.934735ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:46.106384Z","caller":"traceutil/trace.go:171","msg":"trace[80458829] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"418.194017ms","start":"2026-05-25T14:21:45.688146Z","end":"2026-05-25T14:21:46.10634Z","steps":["trace[80458829] 'process raft request' (duration: 417.801724ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:46.106471Z","caller":"traceutil/trace.go:171","msg":"trace[1110146808] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2216; }","duration":"388.667077ms","start":"2026-05-25T14:21:45.717789Z","end":"2026-05-25T14:21:46.106456Z","steps":["trace[1110146808] 'read index received' (duration: 249.046405ms)","trace[1110146808] 'applied index is now lower than readState.Index' (duration: 139.618682ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:21:46.106557Z","caller":"traceutil/trace.go:171","msg":"trace[1391199471] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"229.476575ms","start":"2026-05-25T14:21:45.877072Z","end":"2026-05-25T14:21:46.106549Z","steps":["trace[1391199471] 'process raft request' (duration: 229.174232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:46.106669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:45.688135Z","time spent":"418.361308ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:21:46.10675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.957581ms","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-25T14:21:46.106777Z","caller":"traceutil/trace.go:171","msg":"trace[1734752319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2134; }","duration":"389.009561ms","start":"2026-05-25T14:21:45.71776Z","end":"2026-05-25T14:21:46.10677Z","steps":["trace[1734752319] 'agreement among raft nodes before linearized reading' (duration: 388.877619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:46.1068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:45.717747Z","time spent":"389.046932ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T14:21:46.106961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.837026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T14:21:46.107504Z","caller":"traceutil/trace.go:171","msg":"trace[81324514] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2134; }","duration":"335.432372ms","start":"2026-05-25T14:21:45.772057Z","end":"2026-05-25T14:21:46.107489Z","steps":["trace[81324514] 'agreement among raft nodes before linearized reading' (duration: 334.826957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:46.107104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.781596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b2d4e7f5ad4ba4\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-25T14:21:46.10766Z","caller":"traceutil/trace.go:171","msg":"trace[412074736] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b2d4e7f5ad4ba4; range_end:; response_count:1; response_revision:2134; }","duration":"157.32836ms","start":"2026-05-25T14:21:45.950267Z","end":"2026-05-25T14:21:46.107595Z","steps":["trace[412074736] 'agreement among raft nodes before linearized reading' (duration: 156.648034ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:46.106972Z","caller":"traceutil/trace.go:171","msg":"trace[1175021162] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"419.920054ms","start":"2026-05-25T14:21:45.687024Z","end":"2026-05-25T14:21:46.106944Z","steps":["trace[1175021162] 'process raft request' (duration: 412.702386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:21:46.107718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:45.772002Z","time spent":"335.569194ms","remote":"127.0.0.1:56812","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-25T14:21:46.107951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:21:45.687003Z","time spent":"420.794972ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:21:48.229422Z","caller":"traceutil/trace.go:171","msg":"trace[2070116796] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"117.146521ms","start":"2026-05-25T14:21:48.112235Z","end":"2026-05-25T14:21:48.229382Z","steps":["trace[2070116796] 'process raft request' (duration: 91.296887ms)","trace[2070116796] 'compare' (duration: 25.588361ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:21:48.229739Z","caller":"traceutil/trace.go:171","msg":"trace[297902138] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"115.98059ms","start":"2026-05-25T14:21:48.113703Z","end":"2026-05-25T14:21:48.229684Z","steps":["trace[297902138] 'process raft request' (duration: 115.843709ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:21:48.230302Z","caller":"traceutil/trace.go:171","msg":"trace[1783644798] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"116.694587ms","start":"2026-05-25T14:21:48.113595Z","end":"2026-05-25T14:21:48.23029Z","steps":["trace[1783644798] 'process raft request' (duration: 115.729738ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:22:16.498963Z","caller":"traceutil/trace.go:171","msg":"trace[1653850358] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"146.779439ms","start":"2026-05-25T14:22:16.352149Z","end":"2026-05-25T14:22:16.498928Z","steps":["trace[1653850358] 'process raft request' (duration: 102.9144ms)","trace[1653850358] 'compare' (duration: 43.625117ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:22:16.499252Z","caller":"traceutil/trace.go:171","msg":"trace[927446769] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"146.202736ms","start":"2026-05-25T14:22:16.353002Z","end":"2026-05-25T14:22:16.499205Z","steps":["trace[927446769] 'process raft request' (duration: 145.879152ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:22:16.499174Z","caller":"traceutil/trace.go:171","msg":"trace[1944672294] linearizableReadLoop","detail":"{readStateIndex:2388; appliedIndex:2386; }","duration":"117.573947ms","start":"2026-05-25T14:22:16.38155Z","end":"2026-05-25T14:22:16.499124Z","steps":["trace[1944672294] 'read index received' (duration: 73.433355ms)","trace[1944672294] 'applied index is now lower than readState.Index' (duration: 44.138492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:22:16.499411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.845349ms","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-25T14:22:16.499464Z","caller":"traceutil/trace.go:171","msg":"trace[1787305282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2294; }","duration":"117.9636ms","start":"2026-05-25T14:22:16.38149Z","end":"2026-05-25T14:22:16.499453Z","steps":["trace[1787305282] 'agreement among raft nodes before linearized reading' (duration: 117.739548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:22:16.499571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.771742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T14:22:16.499626Z","caller":"traceutil/trace.go:171","msg":"trace[128101231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2294; }","duration":"100.899503ms","start":"2026-05-25T14:22:16.398713Z","end":"2026-05-25T14:22:16.499612Z","steps":["trace[128101231] 'agreement among raft nodes before linearized reading' (duration: 100.715692ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:22:38.79358Z","caller":"traceutil/trace.go:171","msg":"trace[1047804005] linearizableReadLoop","detail":"{readStateIndex:2509; appliedIndex:2506; }","duration":"109.287793ms","start":"2026-05-25T14:22:38.684272Z","end":"2026-05-25T14:22:38.79356Z","steps":["trace[1047804005] 'read index received' (duration: 45.190243ms)","trace[1047804005] 'applied index is now lower than readState.Index' (duration: 64.09638ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:22:38.793638Z","caller":"traceutil/trace.go:171","msg":"trace[131900996] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"129.070836ms","start":"2026-05-25T14:22:38.664529Z","end":"2026-05-25T14:22:38.7936Z","steps":["trace[131900996] 'process raft request' (duration: 128.844194ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:22:38.793709Z","caller":"traceutil/trace.go:171","msg":"trace[189061392] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"118.061655ms","start":"2026-05-25T14:22:38.675636Z","end":"2026-05-25T14:22:38.793698Z","steps":["trace[189061392] 'process raft request' (duration: 117.885583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:22:38.79412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.11115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-25T14:22:38.79418Z","caller":"traceutil/trace.go:171","msg":"trace[1462373252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2410; }","duration":"104.22349ms","start":"2026-05-25T14:22:38.689941Z","end":"2026-05-25T14:22:38.794165Z","steps":["trace[1462373252] 'agreement among raft nodes before linearized reading' (duration: 103.986448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:22:38.794486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.203899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T14:22:38.79455Z","caller":"traceutil/trace.go:171","msg":"trace[694072809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2410; }","duration":"110.31942ms","start":"2026-05-25T14:22:38.684215Z","end":"2026-05-25T14:22:38.794535Z","steps":["trace[694072809] 'agreement among raft nodes before linearized reading' (duration: 110.145279ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:23:34.827369Z","caller":"traceutil/trace.go:171","msg":"trace[1008292881] linearizableReadLoop","detail":"{readStateIndex:2916; appliedIndex:2915; }","duration":"293.822443ms","start":"2026-05-25T14:23:34.533499Z","end":"2026-05-25T14:23:34.827321Z","steps":["trace[1008292881] 'read index received' (duration: 293.58234ms)","trace[1008292881] 'applied index is now lower than readState.Index' (duration: 238.733µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:23:34.827444Z","caller":"traceutil/trace.go:171","msg":"trace[1648515540] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"294.79366ms","start":"2026-05-25T14:23:34.532596Z","end":"2026-05-25T14:23:34.827389Z","steps":["trace[1648515540] 'process raft request' (duration: 294.492937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:23:34.827582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.073814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:23:34.827639Z","caller":"traceutil/trace.go:171","msg":"trace[597684787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2804; }","duration":"294.196525ms","start":"2026-05-25T14:23:34.533431Z","end":"2026-05-25T14:23:34.827628Z","steps":["trace[597684787] 'agreement among raft nodes before linearized reading' (duration: 294.042784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:23:34.828205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.521117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:23:34.828334Z","caller":"traceutil/trace.go:171","msg":"trace[482292074] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2805; }","duration":"260.631447ms","start":"2026-05-25T14:23:34.56763Z","end":"2026-05-25T14:23:34.828262Z","steps":["trace[482292074] 'agreement among raft nodes before linearized reading' (duration: 260.496626ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:24:01.297846Z","caller":"traceutil/trace.go:171","msg":"trace[1346167541] linearizableReadLoop","detail":"{readStateIndex:3069; appliedIndex:3066; }","duration":"244.999574ms","start":"2026-05-25T14:24:01.052814Z","end":"2026-05-25T14:24:01.297813Z","steps":["trace[1346167541] 'read index received' (duration: 1.247399ms)","trace[1346167541] 'applied index is now lower than readState.Index' (duration: 243.750705ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:24:01.298025Z","caller":"traceutil/trace.go:171","msg":"trace[673118522] transaction","detail":"{read_only:false; response_revision:2952; number_of_response:1; }","duration":"248.574365ms","start":"2026-05-25T14:24:01.049432Z","end":"2026-05-25T14:24:01.298006Z","steps":["trace[673118522] 'process raft request' (duration: 248.229311ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:24:01.298068Z","caller":"traceutil/trace.go:171","msg":"trace[1225438770] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"249.505922ms","start":"2026-05-25T14:24:01.04854Z","end":"2026-05-25T14:24:01.298046Z","steps":["trace[1225438770] 'process raft request' (duration: 170.107435ms)","trace[1225438770] 'compare' (duration: 78.631128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:24:01.298241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.611429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T14:24:01.298363Z","caller":"traceutil/trace.go:171","msg":"trace[24297115] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2952; }","duration":"161.804737ms","start":"2026-05-25T14:24:01.136534Z","end":"2026-05-25T14:24:01.298339Z","steps":["trace[24297115] 'agreement among raft nodes before linearized reading' (duration: 161.589817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:24:01.298372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.528026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-25T14:24:01.298408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.594062ms","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-25T14:24:01.298534Z","caller":"traceutil/trace.go:171","msg":"trace[930623017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2952; }","duration":"131.768728ms","start":"2026-05-25T14:24:01.166736Z","end":"2026-05-25T14:24:01.298505Z","steps":["trace[930623017] 'agreement among raft nodes before linearized reading' (duration: 131.455826ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:24:01.298452Z","caller":"traceutil/trace.go:171","msg":"trace[82158589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2952; }","duration":"245.665621ms","start":"2026-05-25T14:24:01.052767Z","end":"2026-05-25T14:24:01.298433Z","steps":["trace[82158589] 'agreement among raft nodes before linearized reading' (duration: 245.418862ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:24:05.513205Z","caller":"traceutil/trace.go:171","msg":"trace[1531381274] transaction","detail":"{read_only:false; response_revision:2978; number_of_response:1; }","duration":"143.774517ms","start":"2026-05-25T14:24:05.369411Z","end":"2026-05-25T14:24:05.513186Z","steps":["trace[1531381274] 'process raft request' (duration: 143.726465ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:24:05.513213Z","caller":"traceutil/trace.go:171","msg":"trace[1396848613] transaction","detail":"{read_only:false; response_revision:2977; number_of_response:1; }","duration":"144.492624ms","start":"2026-05-25T14:24:05.368684Z","end":"2026-05-25T14:24:05.513177Z","steps":["trace[1396848613] 'process raft request' (duration: 130.273735ms)","trace[1396848613] 'compare' (duration: 14.077904ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:24:05.513417Z","caller":"traceutil/trace.go:171","msg":"trace[780260473] transaction","detail":"{read_only:false; response_revision:2979; number_of_response:1; }","duration":"143.737785ms","start":"2026-05-25T14:24:05.369674Z","end":"2026-05-25T14:24:05.513412Z","steps":["trace[780260473] 'process raft request' (duration: 143.487956ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:24:05.513475Z","caller":"traceutil/trace.go:171","msg":"trace[1500356016] transaction","detail":"{read_only:false; response_revision:2980; number_of_response:1; }","duration":"143.413953ms","start":"2026-05-25T14:24:05.370057Z","end":"2026-05-25T14:24:05.513471Z","steps":["trace[1500356016] 'process raft request' (duration: 143.33291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:24:06.971425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.130292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:24:06.971509Z","caller":"traceutil/trace.go:171","msg":"trace[1789522312] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2981; }","duration":"180.257647ms","start":"2026-05-25T14:24:06.791233Z","end":"2026-05-25T14:24:06.971491Z","steps":["trace[1789522312] 'count revisions from in-memory index tree' (duration: 180.028739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:24:06.971573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.901719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:24:06.971641Z","caller":"traceutil/trace.go:171","msg":"trace[1795051640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2981; }","duration":"439.039115ms","start":"2026-05-25T14:24:06.532585Z","end":"2026-05-25T14:24:06.971624Z","steps":["trace[1795051640] 'range keys from in-memory index tree' (duration: 438.704491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:24:06.971676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:24:06.532561Z","time spent":"439.107548ms","remote":"127.0.0.1:56394","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-25T14:24:06.971844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.370608ms","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-25T14:24:06.971875Z","caller":"traceutil/trace.go:171","msg":"trace[1871246084] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2981; }","duration":"407.45655ms","start":"2026-05-25T14:24:06.56441Z","end":"2026-05-25T14:24:06.971867Z","steps":["trace[1871246084] 'count revisions from in-memory index tree' (duration: 407.282804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:24:06.971895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:24:06.564309Z","time spent":"407.579335ms","remote":"127.0.0.1:56744","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":"info","ts":"2026-05-25T14:24:13.075592Z","caller":"traceutil/trace.go:171","msg":"trace[239320974] linearizableReadLoop","detail":"{readStateIndex:3133; appliedIndex:3132; }","duration":"159.33369ms","start":"2026-05-25T14:24:12.916239Z","end":"2026-05-25T14:24:13.075572Z","steps":["trace[239320974] 'read index received' (duration: 159.215296ms)","trace[239320974] 'applied index is now lower than readState.Index' (duration: 117.154µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:24:13.075853Z","caller":"traceutil/trace.go:171","msg":"trace[617441061] transaction","detail":"{read_only:false; response_revision:3013; number_of_response:1; }","duration":"322.289957ms","start":"2026-05-25T14:24:12.753531Z","end":"2026-05-25T14:24:13.075821Z","steps":["trace[617441061] 'process raft request' (duration: 321.877942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:24:13.076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.688442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T14:24:13.076008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:24:12.753468Z","time spent":"322.455794ms","remote":"127.0.0.1:56450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:24:13.076053Z","caller":"traceutil/trace.go:171","msg":"trace[1627852442] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3013; }","duration":"159.817157ms","start":"2026-05-25T14:24:12.916221Z","end":"2026-05-25T14:24:13.076038Z","steps":["trace[1627852442] 'agreement among raft nodes before linearized reading' (duration: 159.440933ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:11.376126Z","caller":"traceutil/trace.go:171","msg":"trace[629311660] transaction","detail":"{read_only:false; response_revision:4101; number_of_response:1; }","duration":"101.371764ms","start":"2026-05-25T14:27:11.274728Z","end":"2026-05-25T14:27:11.3761Z","steps":["trace[629311660] 'process raft request' (duration: 96.143242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:57.094403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.369969ms","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-25T14:27:57.094469Z","caller":"traceutil/trace.go:171","msg":"trace[914371815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4721; }","duration":"112.491342ms","start":"2026-05-25T14:27:56.981964Z","end":"2026-05-25T14:27:57.094456Z","steps":["trace[914371815] 'range keys from in-memory index tree' (duration: 112.256367ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:58.415725Z","caller":"traceutil/trace.go:171","msg":"trace[2087547544] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"154.417177ms","start":"2026-05-25T14:27:58.261259Z","end":"2026-05-25T14:27:58.415676Z","steps":["trace[2087547544] 'process raft request' (duration: 122.853947ms)","trace[2087547544] 'compare' (duration: 31.258075ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:27:58.463942Z","caller":"traceutil/trace.go:171","msg":"trace[1424688774] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"130.619858ms","start":"2026-05-25T14:27:58.333296Z","end":"2026-05-25T14:27:58.463916Z","steps":["trace[1424688774] 'process raft request' (duration: 130.493176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:58.985494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.301469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:27:58.985818Z","caller":"traceutil/trace.go:171","msg":"trace[191705217] linearizableReadLoop","detail":"{readStateIndex:4983; appliedIndex:4981; }","duration":"548.084918ms","start":"2026-05-25T14:27:58.437708Z","end":"2026-05-25T14:27:58.985793Z","steps":["trace[191705217] 'read index received' (duration: 26.192607ms)","trace[191705217] 'applied index is now lower than readState.Index' (duration: 521.890521ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:27:58.985954Z","caller":"traceutil/trace.go:171","msg":"trace[1275725222] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"557.947791ms","start":"2026-05-25T14:27:58.427992Z","end":"2026-05-25T14:27:58.98594Z","steps":["trace[1275725222] 'process raft request' (duration: 444.056123ms)","trace[1275725222] 'compare' (duration: 112.518287ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:27:58.986112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:58.427958Z","time spent":"558.071263ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:27:58.986526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.774329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efb8b512-484d-4087-81a4-c5e621705965\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-05-25T14:27:58.986644Z","caller":"traceutil/trace.go:171","msg":"trace[824620025] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efb8b512-484d-4087-81a4-c5e621705965; range_end:; response_count:1; response_revision:4791; }","duration":"548.969952ms","start":"2026-05-25T14:27:58.437647Z","end":"2026-05-25T14:27:58.986617Z","steps":["trace[824620025] 'agreement among raft nodes before linearized reading' (duration: 548.630987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:58.986698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:58.437629Z","time spent":"549.058743ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3796,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efb8b512-484d-4087-81a4-c5e621705965\" "} {"level":"warn","ts":"2026-05-25T14:27:59.071576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.725986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T14:27:59.071595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.621831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-25T14:27:59.071654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.311584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-25T14:27:59.071706Z","caller":"traceutil/trace.go:171","msg":"trace[35294054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4792; }","duration":"234.797032ms","start":"2026-05-25T14:27:58.836881Z","end":"2026-05-25T14:27:59.071678Z","steps":["trace[35294054] 'agreement among raft nodes before linearized reading' (duration: 234.430696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:59.071723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.663826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:27:59.071725Z","caller":"traceutil/trace.go:171","msg":"trace[698257116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4792; }","duration":"192.401085ms","start":"2026-05-25T14:27:58.879304Z","end":"2026-05-25T14:27:59.071705Z","steps":["trace[698257116] 'agreement among raft nodes before linearized reading' (duration: 192.135781ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:59.071755Z","caller":"traceutil/trace.go:171","msg":"trace[448312990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4792; }","duration":"539.754338ms","start":"2026-05-25T14:27:58.531992Z","end":"2026-05-25T14:27:59.071746Z","steps":["trace[448312990] 'agreement among raft nodes before linearized reading' (duration: 539.708797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:59.0717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.609459ms","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-25T14:27:59.071792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:58.531961Z","time spent":"539.824458ms","remote":"127.0.0.1:56394","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-25T14:27:59.071813Z","caller":"traceutil/trace.go:171","msg":"trace[892781263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4792; }","duration":"205.748632ms","start":"2026-05-25T14:27:58.86604Z","end":"2026-05-25T14:27:59.071788Z","steps":["trace[892781263] 'agreement among raft nodes before linearized reading' (duration: 205.511849ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:59.071633Z","caller":"traceutil/trace.go:171","msg":"trace[1356032880] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"582.48209ms","start":"2026-05-25T14:27:58.489095Z","end":"2026-05-25T14:27:59.071577Z","steps":["trace[1356032880] 'process raft request' (duration: 582.002402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:59.07169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.273991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49fcfc3c-29d1-454c-ae85-66b078cb55d5\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-05-25T14:27:59.072173Z","caller":"traceutil/trace.go:171","msg":"trace[1520929382] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49fcfc3c-29d1-454c-ae85-66b078cb55d5; range_end:; response_count:1; response_revision:4792; }","duration":"255.784219ms","start":"2026-05-25T14:27:58.81632Z","end":"2026-05-25T14:27:59.072105Z","steps":["trace[1520929382] 'agreement among raft nodes before linearized reading' (duration: 255.089719ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:59.071664Z","caller":"traceutil/trace.go:171","msg":"trace[1289447644] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4792; }","duration":"452.906939ms","start":"2026-05-25T14:27:58.618734Z","end":"2026-05-25T14:27:59.071641Z","steps":["trace[1289447644] 'agreement among raft nodes before linearized reading' (duration: 452.744647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:59.072225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.652668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-05-25T14:27:59.072331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:58.618703Z","time spent":"453.615249ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-05-25T14:27:59.07236Z","caller":"traceutil/trace.go:171","msg":"trace[408300687] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4792; }","duration":"308.826771ms","start":"2026-05-25T14:27:58.763517Z","end":"2026-05-25T14:27:59.072344Z","steps":["trace[408300687] 'agreement among raft nodes before linearized reading' (duration: 307.841076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:59.0724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:58.763343Z","time spent":"309.043565ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T14:27:59.072308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.890785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207149"} {"level":"info","ts":"2026-05-25T14:27:59.072514Z","caller":"traceutil/trace.go:171","msg":"trace[779389175] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4792; }","duration":"581.1525ms","start":"2026-05-25T14:27:58.491349Z","end":"2026-05-25T14:27:59.072502Z","steps":["trace[779389175] 'agreement among raft nodes before linearized reading' (duration: 579.8972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:59.072557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:58.491336Z","time spent":"581.20643ms","remote":"127.0.0.1:56484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207172,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-05-25T14:27:59.073465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:58.489065Z","time spent":"583.000918ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:27:59.216049Z","caller":"traceutil/trace.go:171","msg":"trace[950413960] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"136.746928ms","start":"2026-05-25T14:27:59.079278Z","end":"2026-05-25T14:27:59.216025Z","steps":["trace[950413960] 'process raft request' (duration: 136.658717ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:59.21608Z","caller":"traceutil/trace.go:171","msg":"trace[407423022] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"136.594716ms","start":"2026-05-25T14:27:59.079467Z","end":"2026-05-25T14:27:59.216062Z","steps":["trace[407423022] 'process raft request' (duration: 136.529015ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:59.216099Z","caller":"traceutil/trace.go:171","msg":"trace[640759516] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"138.400104ms","start":"2026-05-25T14:27:59.077644Z","end":"2026-05-25T14:27:59.216044Z","steps":["trace[640759516] 'process raft request' (duration: 63.181268ms)","trace[640759516] 'compare' (duration: 74.8361ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:27:59.21622Z","caller":"traceutil/trace.go:171","msg":"trace[569954034] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4984; }","duration":"138.099559ms","start":"2026-05-25T14:27:59.078105Z","end":"2026-05-25T14:27:59.216205Z","steps":["trace[569954034] 'read index received' (duration: 62.810012ms)","trace[569954034] 'applied index is now lower than readState.Index' (duration: 75.287657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:27:59.216418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.563697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-05-25T14:27:59.216418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.307603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-25T14:27:59.216458Z","caller":"traceutil/trace.go:171","msg":"trace[635213567] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4795; }","duration":"109.663818ms","start":"2026-05-25T14:27:59.106784Z","end":"2026-05-25T14:27:59.216447Z","steps":["trace[635213567] 'agreement among raft nodes before linearized reading' (duration: 109.538287ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:59.216462Z","caller":"traceutil/trace.go:171","msg":"trace[1319684943] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4795; }","duration":"138.398974ms","start":"2026-05-25T14:27:59.078053Z","end":"2026-05-25T14:27:59.216452Z","steps":["trace[1319684943] 'agreement among raft nodes before linearized reading' (duration: 138.233831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:59.216938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.086818ms","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-25T14:27:59.217043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.7403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T14:27:59.21704Z","caller":"traceutil/trace.go:171","msg":"trace[1948409518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4795; }","duration":"109.215742ms","start":"2026-05-25T14:27:59.107802Z","end":"2026-05-25T14:27:59.217018Z","steps":["trace[1948409518] 'agreement among raft nodes before linearized reading' (duration: 108.912086ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:59.217085Z","caller":"traceutil/trace.go:171","msg":"trace[1241777574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4795; }","duration":"109.830192ms","start":"2026-05-25T14:27:59.107243Z","end":"2026-05-25T14:27:59.217073Z","steps":["trace[1241777574] 'agreement among raft nodes before linearized reading' (duration: 109.484026ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:01.446138Z","caller":"traceutil/trace.go:171","msg":"trace[1276492380] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"104.243748ms","start":"2026-05-25T14:28:01.341859Z","end":"2026-05-25T14:28:01.446102Z","steps":["trace[1276492380] 'process raft request' (duration: 103.946863ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:04.914205Z","caller":"traceutil/trace.go:171","msg":"trace[1793983160] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"139.934264ms","start":"2026-05-25T14:28:04.774242Z","end":"2026-05-25T14:28:04.914177Z","steps":["trace[1793983160] 'process raft request' (duration: 135.302532ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:11.957128Z","caller":"traceutil/trace.go:171","msg":"trace[1927477286] linearizableReadLoop","detail":"{readStateIndex:5231; appliedIndex:5230; }","duration":"214.105192ms","start":"2026-05-25T14:28:11.743004Z","end":"2026-05-25T14:28:11.957109Z","steps":["trace[1927477286] 'read index received' (duration: 214.031491ms)","trace[1927477286] 'applied index is now lower than readState.Index' (duration: 73.021µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:28:11.957262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.246615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:28:11.95725Z","caller":"traceutil/trace.go:171","msg":"trace[1305000290] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"299.35155ms","start":"2026-05-25T14:28:11.65787Z","end":"2026-05-25T14:28:11.957221Z","steps":["trace[1305000290] 'process raft request' (duration: 299.090846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:11.957414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.627308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1584cdf1-e663-4728-801d-ce65caaaf8d9\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-25T14:28:11.957446Z","caller":"traceutil/trace.go:171","msg":"trace[769101165] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1584cdf1-e663-4728-801d-ce65caaaf8d9; range_end:; response_count:1; response_revision:5032; }","duration":"192.70105ms","start":"2026-05-25T14:28:11.764735Z","end":"2026-05-25T14:28:11.957436Z","steps":["trace[769101165] 'agreement among raft nodes before linearized reading' (duration: 192.596148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:11.957525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.045408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-25T14:28:11.957558Z","caller":"traceutil/trace.go:171","msg":"trace[1418116447] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:5032; }","duration":"129.126519ms","start":"2026-05-25T14:28:11.828423Z","end":"2026-05-25T14:28:11.95755Z","steps":["trace[1418116447] 'agreement among raft nodes before linearized reading' (duration: 128.990327ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:11.957678Z","caller":"traceutil/trace.go:171","msg":"trace[1560827289] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:5032; }","duration":"214.345266ms","start":"2026-05-25T14:28:11.74295Z","end":"2026-05-25T14:28:11.957295Z","steps":["trace[1560827289] 'agreement among raft nodes before linearized reading' (duration: 214.265754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:12.374446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.350732ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-25T14:28:12.374553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:11.95875Z","time spent":"415.792465ms","remote":"127.0.0.1:56412","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-25T14:28:12.390188Z","caller":"traceutil/trace.go:171","msg":"trace[1924636154] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"180.58378ms","start":"2026-05-25T14:28:12.209583Z","end":"2026-05-25T14:28:12.390167Z","steps":["trace[1924636154] 'process raft request' (duration: 180.326547ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:12.390806Z","caller":"traceutil/trace.go:171","msg":"trace[205515578] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"154.795221ms","start":"2026-05-25T14:28:12.235995Z","end":"2026-05-25T14:28:12.39079Z","steps":["trace[205515578] 'process raft request' (duration: 154.080141ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:12.576963Z","caller":"traceutil/trace.go:171","msg":"trace[800153988] linearizableReadLoop","detail":"{readStateIndex:5236; appliedIndex:5235; }","duration":"133.312438ms","start":"2026-05-25T14:28:12.443624Z","end":"2026-05-25T14:28:12.576937Z","steps":["trace[800153988] 'read index received' (duration: 49.053869ms)","trace[800153988] 'applied index is now lower than readState.Index' (duration: 84.257339ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:28:12.577284Z","caller":"traceutil/trace.go:171","msg":"trace[2127128145] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"157.938849ms","start":"2026-05-25T14:28:12.419278Z","end":"2026-05-25T14:28:12.577216Z","steps":["trace[2127128145] 'process raft request' (duration: 73.389615ms)","trace[2127128145] 'compare' (duration: 84.137767ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:28:12.577401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.755816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:28:12.577449Z","caller":"traceutil/trace.go:171","msg":"trace[730543588] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:5036; }","duration":"133.860517ms","start":"2026-05-25T14:28:12.443574Z","end":"2026-05-25T14:28:12.577435Z","steps":["trace[730543588] 'agreement among raft nodes before linearized reading' (duration: 133.461351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:12.968571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.656513ms","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-25T14:28:12.968672Z","caller":"traceutil/trace.go:171","msg":"trace[900905334] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5036; }","duration":"208.809695ms","start":"2026-05-25T14:28:12.759842Z","end":"2026-05-25T14:28:12.968651Z","steps":["trace[900905334] 'count revisions from in-memory index tree' (duration: 208.604712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:12.96895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.217382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-25T14:28:12.969064Z","caller":"traceutil/trace.go:171","msg":"trace[52727711] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5036; }","duration":"223.390685ms","start":"2026-05-25T14:28:12.745655Z","end":"2026-05-25T14:28:12.969046Z","steps":["trace[52727711] 'range keys from in-memory index tree' (duration: 222.998369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:13.233971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.238808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:28:13.234213Z","caller":"traceutil/trace.go:171","msg":"trace[1814776014] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"103.427155ms","start":"2026-05-25T14:28:13.130764Z","end":"2026-05-25T14:28:13.234191Z","steps":["trace[1814776014] 'process raft request' (duration: 103.319993ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:13.23427Z","caller":"traceutil/trace.go:171","msg":"trace[1489472952] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"189.57684ms","start":"2026-05-25T14:28:13.044651Z","end":"2026-05-25T14:28:13.234227Z","steps":["trace[1489472952] 'process raft request' (duration: 80.995857ms)","trace[1489472952] 'compare' (duration: 108.107247ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:28:13.234287Z","caller":"traceutil/trace.go:171","msg":"trace[1620460143] linearizableReadLoop","detail":"{readStateIndex:5239; appliedIndex:5238; }","duration":"188.23167ms","start":"2026-05-25T14:28:13.046035Z","end":"2026-05-25T14:28:13.234267Z","steps":["trace[1620460143] 'read index received' (duration: 79.626637ms)","trace[1620460143] 'applied index is now lower than readState.Index' (duration: 108.602373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:28:13.234597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.545335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18b2d543fffabedf\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-05-25T14:28:13.234654Z","caller":"traceutil/trace.go:171","msg":"trace[1209663571] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18b2d543fffabedf; range_end:; response_count:1; response_revision:5040; }","duration":"188.651586ms","start":"2026-05-25T14:28:13.045984Z","end":"2026-05-25T14:28:13.234635Z","steps":["trace[1209663571] 'agreement among raft nodes before linearized reading' (duration: 188.390852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:16.758233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.555921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T14:28:16.758354Z","caller":"traceutil/trace.go:171","msg":"trace[1885875304] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5108; }","duration":"110.752144ms","start":"2026-05-25T14:28:16.647573Z","end":"2026-05-25T14:28:16.758325Z","steps":["trace[1885875304] 'count revisions from in-memory index tree' (duration: 110.41859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:16.758501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.25878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T14:28:16.758579Z","caller":"traceutil/trace.go:171","msg":"trace[796472314] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:5108; }","duration":"240.402463ms","start":"2026-05-25T14:28:16.518156Z","end":"2026-05-25T14:28:16.758558Z","steps":["trace[796472314] 'count revisions from in-memory index tree' (duration: 240.148799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:27.873154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812163559447675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T14:28:28.373605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342812163559447675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T14:28:28.411014Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.128722254s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T14:28:28.411668Z","caller":"traceutil/trace.go:171","msg":"trace[616728658] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"1.129440645s","start":"2026-05-25T14:28:27.282186Z","end":"2026-05-25T14:28:28.411627Z","steps":["trace[616728658] 'process raft request' (duration: 1.129218342s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.411948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.282161Z","time spent":"1.129674989s","remote":"127.0.0.1:56660","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-25T14:28:28.453166Z","caller":"traceutil/trace.go:171","msg":"trace[1267664377] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"1.167217196s","start":"2026-05-25T14:28:27.285911Z","end":"2026-05-25T14:28:28.453128Z","steps":["trace[1267664377] 'process raft request' (duration: 1.166939301s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.453413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.285899Z","time spent":"1.167365258s","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:28:28.600355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.310731ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T14:28:28.600516Z","caller":"traceutil/trace.go:171","msg":"trace[301765665] linearizableReadLoop","detail":"{readStateIndex:5441; appliedIndex:5438; }","duration":"1.228177853s","start":"2026-05-25T14:28:27.372312Z","end":"2026-05-25T14:28:28.60049Z","steps":["trace[301765665] 'read index received' (duration: 1.039056393s)","trace[301765665] 'applied index is now lower than readState.Index' (duration: 189.11951ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:28:28.600934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228589899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-25T14:28:28.601034Z","caller":"traceutil/trace.go:171","msg":"trace[1159992563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5235; }","duration":"1.228744601s","start":"2026-05-25T14:28:27.372269Z","end":"2026-05-25T14:28:28.601013Z","steps":["trace[1159992563] 'agreement among raft nodes before linearized reading' (duration: 1.228314705s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.601085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.372253Z","time spent":"1.228822402s","remote":"127.0.0.1:56660","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":"info","ts":"2026-05-25T14:28:28.637148Z","caller":"traceutil/trace.go:171","msg":"trace[1404801084] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"164.485241ms","start":"2026-05-25T14:28:28.472636Z","end":"2026-05-25T14:28:28.637122Z","steps":["trace[1404801084] 'process raft request' (duration: 164.325419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.658056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12685802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:28:28.658126Z","caller":"traceutil/trace.go:171","msg":"trace[1227370500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5238; }","duration":"1.126985682s","start":"2026-05-25T14:28:27.531126Z","end":"2026-05-25T14:28:28.658111Z","steps":["trace[1227370500] 'agreement among raft nodes before linearized reading' (duration: 1.126881711s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.658158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.531102Z","time spent":"1.127049073s","remote":"127.0.0.1:56394","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-25T14:28:28.658181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120665029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17147"} {"level":"info","ts":"2026-05-25T14:28:28.658233Z","caller":"traceutil/trace.go:171","msg":"trace[1977481795] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5238; }","duration":"1.120782311s","start":"2026-05-25T14:28:27.537435Z","end":"2026-05-25T14:28:28.658217Z","steps":["trace[1977481795] 'agreement among raft nodes before linearized reading' (duration: 1.120571208s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.658247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285273977s","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-25T14:28:28.658277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.537387Z","time spent":"1.120881852s","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":17170,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-25T14:28:28.658298Z","caller":"traceutil/trace.go:171","msg":"trace[1616446718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5238; }","duration":"1.285360298s","start":"2026-05-25T14:28:27.372925Z","end":"2026-05-25T14:28:28.658285Z","steps":["trace[1616446718] 'agreement among raft nodes before linearized reading' (duration: 1.285176595s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.658333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.372917Z","time spent":"1.285406928s","remote":"127.0.0.1:56660","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-25T14:28:28.658301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.397247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T14:28:28.658483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.537227ms","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-25T14:28:28.658553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285914575s","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-25T14:28:28.658588Z","caller":"traceutil/trace.go:171","msg":"trace[1946482515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5238; }","duration":"1.285969676s","start":"2026-05-25T14:28:27.37261Z","end":"2026-05-25T14:28:28.65858Z","steps":["trace[1946482515] 'agreement among raft nodes before linearized reading' (duration: 1.285878885s)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:28.658596Z","caller":"traceutil/trace.go:171","msg":"trace[1977998520] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"180.290621ms","start":"2026-05-25T14:28:28.478295Z","end":"2026-05-25T14:28:28.658586Z","steps":["trace[1977998520] 'process raft request' (duration: 179.51599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.658614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.372597Z","time spent":"1.286008357s","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T14:28:28.658544Z","caller":"traceutil/trace.go:171","msg":"trace[1998000862] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5238; }","duration":"605.657109ms","start":"2026-05-25T14:28:28.052872Z","end":"2026-05-25T14:28:28.65853Z","steps":["trace[1998000862] 'agreement among raft nodes before linearized reading' (duration: 605.206623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.658654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227746946s","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-25T14:28:28.658655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189634058s","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-25T14:28:28.658716Z","caller":"traceutil/trace.go:171","msg":"trace[389953842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5238; }","duration":"1.18975481s","start":"2026-05-25T14:28:27.468948Z","end":"2026-05-25T14:28:28.658703Z","steps":["trace[389953842] 'agreement among raft nodes before linearized reading' (duration: 1.189591647s)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:28.658708Z","caller":"traceutil/trace.go:171","msg":"trace[1725363928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5238; }","duration":"1.227859617s","start":"2026-05-25T14:28:27.430833Z","end":"2026-05-25T14:28:28.658692Z","steps":["trace[1725363928] 'agreement among raft nodes before linearized reading' (duration: 1.227658285s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.65877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.46892Z","time spent":"1.18984021s","remote":"127.0.0.1:56660","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-25T14:28:28.65878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.430803Z","time spent":"1.227961628s","remote":"127.0.0.1:56660","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-25T14:28:28.658768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:28.052844Z","time spent":"605.834472ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-25T14:28:28.658428Z","caller":"traceutil/trace.go:171","msg":"trace[1507419782] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5238; }","duration":"982.59285ms","start":"2026-05-25T14:28:27.675811Z","end":"2026-05-25T14:28:28.658404Z","steps":["trace[1507419782] 'agreement among raft nodes before linearized reading' (duration: 982.399078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.658943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.675786Z","time spent":"983.143099ms","remote":"127.0.0.1:45808","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/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-25T14:28:28.659295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089750988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-25T14:28:28.659445Z","caller":"traceutil/trace.go:171","msg":"trace[1928294318] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5238; }","duration":"1.089969651s","start":"2026-05-25T14:28:27.569445Z","end":"2026-05-25T14:28:28.659414Z","steps":["trace[1928294318] 'agreement among raft nodes before linearized reading' (duration: 1.089044438s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:28.659592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:28:27.569423Z","time spent":"1.090152274s","remote":"127.0.0.1:56546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-25T14:28:28.942437Z","caller":"traceutil/trace.go:171","msg":"trace[549650195] linearizableReadLoop","detail":"{readStateIndex:5451; appliedIndex:5450; }","duration":"147.603124ms","start":"2026-05-25T14:28:28.794804Z","end":"2026-05-25T14:28:28.942407Z","steps":["trace[549650195] 'read index received' (duration: 54.84966ms)","trace[549650195] 'applied index is now lower than readState.Index' (duration: 92.752014ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:28:28.942518Z","caller":"traceutil/trace.go:171","msg":"trace[632440034] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"155.878725ms","start":"2026-05-25T14:28:28.786607Z","end":"2026-05-25T14:28:28.942486Z","steps":["trace[632440034] 'process raft request' (duration: 63.132761ms)","trace[632440034] 'compare' (duration: 92.534631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:28:28.942712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.884738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-05-25T14:28:28.942755Z","caller":"traceutil/trace.go:171","msg":"trace[59455003] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5245; }","duration":"147.946179ms","start":"2026-05-25T14:28:28.794801Z","end":"2026-05-25T14:28:28.942747Z","steps":["trace[59455003] 'agreement among raft nodes before linearized reading' (duration: 147.769886ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:38.280937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2823} {"level":"info","ts":"2026-05-25T14:28:38.371013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2823,"took":"87.91327ms","hash":3486096480,"current-db-size-bytes":52441088,"current-db-size":"52 MB","current-db-size-in-use-bytes":27435008,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-25T14:28:38.371139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3486096480,"revision":2823,"compact-revision":-1} {"level":"info","ts":"2026-05-25T14:29:12.843107Z","caller":"traceutil/trace.go:171","msg":"trace[1618798758] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"214.39404ms","start":"2026-05-25T14:29:12.628677Z","end":"2026-05-25T14:29:12.843071Z","steps":["trace[1618798758] 'process raft request' (duration: 214.189138ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:29:12.909973Z","caller":"traceutil/trace.go:171","msg":"trace[1944818006] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"233.488029ms","start":"2026-05-25T14:29:12.676459Z","end":"2026-05-25T14:29:12.909947Z","steps":["trace[1944818006] 'process raft request' (duration: 188.464978ms)","trace[1944818006] 'compare' (duration: 44.795837ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:29:12.910574Z","caller":"traceutil/trace.go:171","msg":"trace[2090786383] linearizableReadLoop","detail":"{readStateIndex:5815; appliedIndex:5813; }","duration":"233.755103ms","start":"2026-05-25T14:29:12.676795Z","end":"2026-05-25T14:29:12.91055Z","steps":["trace[2090786383] 'read index received' (duration: 166.188756ms)","trace[2090786383] 'applied index is now lower than readState.Index' (duration: 67.565347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:29:12.911917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.105481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207149"} {"level":"info","ts":"2026-05-25T14:29:12.911968Z","caller":"traceutil/trace.go:171","msg":"trace[157558626] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5599; }","duration":"235.180692ms","start":"2026-05-25T14:29:12.676774Z","end":"2026-05-25T14:29:12.911954Z","steps":["trace[157558626] 'agreement among raft nodes before linearized reading' (duration: 233.894855ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:32:08.379662Z","caller":"traceutil/trace.go:171","msg":"trace[1016517021] linearizableReadLoop","detail":"{readStateIndex:7084; appliedIndex:7083; }","duration":"115.779786ms","start":"2026-05-25T14:32:08.263854Z","end":"2026-05-25T14:32:08.379633Z","steps":["trace[1016517021] 'read index received' (duration: 34.721267ms)","trace[1016517021] 'applied index is now lower than readState.Index' (duration: 81.057249ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:32:08.380029Z","caller":"traceutil/trace.go:171","msg":"trace[1434991159] transaction","detail":"{read_only:false; response_revision:6826; number_of_response:1; }","duration":"197.874398ms","start":"2026-05-25T14:32:08.182138Z","end":"2026-05-25T14:32:08.380012Z","steps":["trace[1434991159] 'process raft request' (duration: 116.445964ms)","trace[1434991159] 'compare' (duration: 80.879578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:32:08.380307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.437594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-05-25T14:32:08.380349Z","caller":"traceutil/trace.go:171","msg":"trace[535424344] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6826; }","duration":"116.523685ms","start":"2026-05-25T14:32:08.263815Z","end":"2026-05-25T14:32:08.380338Z","steps":["trace[535424344] 'agreement among raft nodes before linearized reading' (duration: 116.382284ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:32:14.337846Z","caller":"traceutil/trace.go:171","msg":"trace[507854512] linearizableReadLoop","detail":"{readStateIndex:7111; appliedIndex:7110; }","duration":"167.818741ms","start":"2026-05-25T14:32:14.169999Z","end":"2026-05-25T14:32:14.337818Z","steps":["trace[507854512] 'read index received' (duration: 167.612989ms)","trace[507854512] 'applied index is now lower than readState.Index' (duration: 204.832µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:32:14.338183Z","caller":"traceutil/trace.go:171","msg":"trace[1268457256] transaction","detail":"{read_only:false; response_revision:6852; number_of_response:1; }","duration":"227.114256ms","start":"2026-05-25T14:32:14.111054Z","end":"2026-05-25T14:32:14.338169Z","steps":["trace[1268457256] 'process raft request' (duration: 226.631879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:32:14.33852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.505449ms","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-25T14:32:14.338571Z","caller":"traceutil/trace.go:171","msg":"trace[175748101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6852; }","duration":"168.594231ms","start":"2026-05-25T14:32:14.169965Z","end":"2026-05-25T14:32:14.338559Z","steps":["trace[175748101] 'agreement among raft nodes before linearized reading' (duration: 168.393959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:32:14.338846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.605381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T14:32:14.338894Z","caller":"traceutil/trace.go:171","msg":"trace[246677898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6852; }","duration":"156.696461ms","start":"2026-05-25T14:32:14.182187Z","end":"2026-05-25T14:32:14.338883Z","steps":["trace[246677898] 'agreement among raft nodes before linearized reading' (duration: 156.57296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:32:14.339428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.586477ms","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-25T14:32:14.339475Z","caller":"traceutil/trace.go:171","msg":"trace[71152854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6852; }","duration":"148.656498ms","start":"2026-05-25T14:32:14.190808Z","end":"2026-05-25T14:32:14.339465Z","steps":["trace[71152854] 'agreement among raft nodes before linearized reading' (duration: 148.560437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:32:36.650833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.84142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:32:36.651713Z","caller":"traceutil/trace.go:171","msg":"trace[1290053592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7025; }","duration":"118.798496ms","start":"2026-05-25T14:32:36.532886Z","end":"2026-05-25T14:32:36.651685Z","steps":["trace[1290053592] 'range keys from in-memory index tree' (duration: 117.738618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:32:36.650968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.04886ms","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-25T14:32:36.651986Z","caller":"traceutil/trace.go:171","msg":"trace[2009129165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7025; }","duration":"104.111348ms","start":"2026-05-25T14:32:36.547843Z","end":"2026-05-25T14:32:36.651954Z","steps":["trace[2009129165] 'range keys from in-memory index tree' (duration: 102.871525ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:38.287559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5302} {"level":"info","ts":"2026-05-25T14:33:38.369654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5302,"took":"80.401923ms","hash":1715173585,"current-db-size-bytes":52441088,"current-db-size":"52 MB","current-db-size-in-use-bytes":18817024,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-05-25T14:33:38.369732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1715173585,"revision":5302,"compact-revision":2823}