{"level":"warn","ts":"2026-07-25T07:39:57.291398Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T07:39:57.291519Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-25T07:39:57.291602Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T07:39:57.291619Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-25T07:39:57.291645Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T07:39:57.292231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-07-25T07:39:57.292305Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-25T07:39:57.295871Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.349411ms"} {"level":"info","ts":"2026-07-25T07:39:57.302033Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-07-25T07:39:57.302141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T07:39:57.302174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-07-25T07:39:57.302197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T07:39:57.302218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-07-25T07:39:57.302278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-07-25T07:39:57.319253Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T07:39:57.320892Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T07:39:57.322502Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-25T07:39:57.326724Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T07:39:57.326926Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T07:39:57.327123Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T07:39:57.327389Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T07:39:57.327487Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T07:39:57.327958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-07-25T07:39:57.328091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-25T07:39:57.330149Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T07:39:57.330216Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-25T07:39:57.330237Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-25T07:39:57.33038Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T07:39:57.330408Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T07:39:57.603082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T07:39:57.603142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T07:39:57.603202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-07-25T07:39:57.603223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-07-25T07:39:57.603235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-25T07:39:57.603251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-07-25T07:39:57.603265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-25T07:39:57.604143Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T07:39:57.604224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T07:39:57.604202Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T07:39:57.604339Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T07:39:57.604459Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T07:39:57.604495Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T07:39:57.605374Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T07:39:57.605957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T07:39:57.606057Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T07:39:57.609395Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-07-25T07:39:57.609461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T07:40:20.92523Z","caller":"traceutil/trace.go:171","msg":"trace[695625122] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"163.200313ms","start":"2026-07-25T07:40:20.762004Z","end":"2026-07-25T07:40:20.925205Z","steps":["trace[695625122] 'read index received' (duration: 128.089983ms)","trace[695625122] 'applied index is now lower than readState.Index' (duration: 35.10904ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:40:20.925261Z","caller":"traceutil/trace.go:171","msg":"trace[1508328122] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"195.209359ms","start":"2026-07-25T07:40:20.730009Z","end":"2026-07-25T07:40:20.925218Z","steps":["trace[1508328122] 'process raft request' (duration: 160.055497ms)","trace[1508328122] 'compare' (duration: 35.015796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:20.925467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.436114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-07-25T07:40:20.92556Z","caller":"traceutil/trace.go:171","msg":"trace[2106768994] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:395; }","duration":"163.560518ms","start":"2026-07-25T07:40:20.76198Z","end":"2026-07-25T07:40:20.92554Z","steps":["trace[2106768994] 'agreement among raft nodes before linearized reading' (duration: 163.320068ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:29.350633Z","caller":"traceutil/trace.go:171","msg":"trace[457522857] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"109.957519ms","start":"2026-07-25T07:40:29.240655Z","end":"2026-07-25T07:40:29.350613Z","steps":["trace[457522857] 'process raft request' (duration: 109.853125ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:29.519634Z","caller":"traceutil/trace.go:171","msg":"trace[1164208551] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:458; }","duration":"192.940071ms","start":"2026-07-25T07:40:29.326669Z","end":"2026-07-25T07:40:29.519609Z","steps":["trace[1164208551] 'read index received' (duration: 23.826411ms)","trace[1164208551] 'applied index is now lower than readState.Index' (duration: 169.11269ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:29.519784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.098108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-25T07:40:29.519817Z","caller":"traceutil/trace.go:171","msg":"trace[28062291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:444; }","duration":"193.17969ms","start":"2026-07-25T07:40:29.326627Z","end":"2026-07-25T07:40:29.519807Z","steps":["trace[28062291] 'agreement among raft nodes before linearized reading' (duration: 193.073836ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:29.519822Z","caller":"traceutil/trace.go:171","msg":"trace[1455899942] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"278.894933ms","start":"2026-07-25T07:40:29.240908Z","end":"2026-07-25T07:40:29.519803Z","steps":["trace[1455899942] 'process raft request' (duration: 208.544482ms)","trace[1455899942] 'compare' (duration: 70.065261ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:40:33.566079Z","caller":"traceutil/trace.go:171","msg":"trace[1162032735] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"198.714605ms","start":"2026-07-25T07:40:33.367343Z","end":"2026-07-25T07:40:33.566058Z","steps":["trace[1162032735] 'process raft request' (duration: 198.57677ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:33.569288Z","caller":"traceutil/trace.go:171","msg":"trace[648511348] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:503; }","duration":"172.292887ms","start":"2026-07-25T07:40:33.396972Z","end":"2026-07-25T07:40:33.569265Z","steps":["trace[648511348] 'read index received' (duration: 168.967891ms)","trace[648511348] 'applied index is now lower than readState.Index' (duration: 3.324056ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:40:33.569437Z","caller":"traceutil/trace.go:171","msg":"trace[473622499] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"177.1974ms","start":"2026-07-25T07:40:33.392191Z","end":"2026-07-25T07:40:33.569388Z","steps":["trace[473622499] 'process raft request' (duration: 176.959391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:33.569437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.440442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:40:33.569579Z","caller":"traceutil/trace.go:171","msg":"trace[512449741] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:488; }","duration":"172.622019ms","start":"2026-07-25T07:40:33.396942Z","end":"2026-07-25T07:40:33.569564Z","steps":["trace[512449741] 'agreement among raft nodes before linearized reading' (duration: 172.442242ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.20158Z","caller":"traceutil/trace.go:171","msg":"trace[1227459158] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"446.199141ms","start":"2026-07-25T07:40:33.755358Z","end":"2026-07-25T07:40:34.201557Z","steps":["trace[1227459158] 'process raft request' (duration: 446.082087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.202037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.755337Z","time spent":"446.309925ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.220724Z","caller":"traceutil/trace.go:171","msg":"trace[23089106] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"453.909825ms","start":"2026-07-25T07:40:33.766786Z","end":"2026-07-25T07:40:34.220695Z","steps":["trace[23089106] 'process raft request' (duration: 453.777091ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.220806Z","caller":"traceutil/trace.go:171","msg":"trace[1218943571] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"444.925561ms","start":"2026-07-25T07:40:33.775858Z","end":"2026-07-25T07:40:34.220783Z","steps":["trace[1218943571] 'process raft request' (duration: 444.778615ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.220787Z","caller":"traceutil/trace.go:171","msg":"trace[1347663614] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"445.243271ms","start":"2026-07-25T07:40:33.775496Z","end":"2026-07-25T07:40:34.22074Z","steps":["trace[1347663614] 'process raft request' (duration: 445.100456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.220881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.766781Z","time spent":"454.024349ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.220908Z","caller":"traceutil/trace.go:171","msg":"trace[846199433] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"442.583788ms","start":"2026-07-25T07:40:33.77827Z","end":"2026-07-25T07:40:34.220854Z","steps":["trace[846199433] 'process raft request' (duration: 442.427503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.220948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.775485Z","time spent":"445.391877ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T07:40:34.220897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.77585Z","time spent":"445.000323ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.221024Z","caller":"traceutil/trace.go:171","msg":"trace[186403938] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"406.656826ms","start":"2026-07-25T07:40:33.814354Z","end":"2026-07-25T07:40:34.221011Z","steps":["trace[186403938] 'process raft request' (duration: 406.486041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.221084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.814336Z","time spent":"406.717469ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T07:40:34.221077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.778254Z","time spent":"442.743333ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.221099Z","caller":"traceutil/trace.go:171","msg":"trace[923947876] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"404.588104ms","start":"2026-07-25T07:40:33.816496Z","end":"2026-07-25T07:40:34.221084Z","steps":["trace[923947876] 'process raft request' (duration: 404.393127ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.221106Z","caller":"traceutil/trace.go:171","msg":"trace[1124351470] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"418.213072ms","start":"2026-07-25T07:40:33.802883Z","end":"2026-07-25T07:40:34.221096Z","steps":["trace[1124351470] 'process raft request' (duration: 417.915552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.221158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.816477Z","time spent":"404.651067ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.221142Z","caller":"traceutil/trace.go:171","msg":"trace[1105336429] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"304.297464ms","start":"2026-07-25T07:40:33.916827Z","end":"2026-07-25T07:40:34.221124Z","steps":["trace[1105336429] 'process raft request' (duration: 304.18234ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.221176Z","caller":"traceutil/trace.go:171","msg":"trace[227812787] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"391.36517ms","start":"2026-07-25T07:40:33.8298Z","end":"2026-07-25T07:40:34.221166Z","steps":["trace[227812787] 'process raft request' (duration: 391.145422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.221153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.802859Z","time spent":"418.274305ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T07:40:34.221223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.829772Z","time spent":"391.431992ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T07:40:34.221235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.9168Z","time spent":"304.400638ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.220753Z","caller":"traceutil/trace.go:171","msg":"trace[410406660] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"453.935756ms","start":"2026-07-25T07:40:33.766712Z","end":"2026-07-25T07:40:34.220648Z","steps":["trace[410406660] 'process raft request' (duration: 453.747849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.22139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.766694Z","time spent":"454.661301ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.221043Z","caller":"traceutil/trace.go:171","msg":"trace[703345439] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"441.039624ms","start":"2026-07-25T07:40:33.779864Z","end":"2026-07-25T07:40:34.220904Z","steps":["trace[703345439] 'process raft request' (duration: 440.869468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.221656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:33.779858Z","time spent":"441.745778ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T07:40:34.672948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.49802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:40:34.673515Z","caller":"traceutil/trace.go:171","msg":"trace[1078852723] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"427.492016ms","start":"2026-07-25T07:40:34.246008Z","end":"2026-07-25T07:40:34.6735Z","steps":["trace[1078852723] 'process raft request' (duration: 427.419063ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.673564Z","caller":"traceutil/trace.go:171","msg":"trace[730613034] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"433.001747ms","start":"2026-07-25T07:40:34.240516Z","end":"2026-07-25T07:40:34.673518Z","steps":["trace[730613034] 'process raft request' (duration: 432.679796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.673768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.246002Z","time spent":"427.553847ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:40:34.673752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.24051Z","time spent":"433.160872ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.673789Z","caller":"traceutil/trace.go:171","msg":"trace[917999062] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"435.697661ms","start":"2026-07-25T07:40:34.23807Z","end":"2026-07-25T07:40:34.673767Z","steps":["trace[917999062] 'process raft request' (duration: 435.051778ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.673809Z","caller":"traceutil/trace.go:171","msg":"trace[436362872] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"431.05615ms","start":"2026-07-25T07:40:34.242708Z","end":"2026-07-25T07:40:34.673765Z","steps":["trace[436362872] 'process raft request' (duration: 430.577403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.673901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.238065Z","time spent":"435.768053ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:40:34.673937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.242703Z","time spent":"431.166943ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:40:34.673951Z","caller":"traceutil/trace.go:171","msg":"trace[1832702610] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"428.140318ms","start":"2026-07-25T07:40:34.245794Z","end":"2026-07-25T07:40:34.673934Z","steps":["trace[1832702610] 'process raft request' (duration: 427.593959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.674011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.245782Z","time spent":"428.19677ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:40:34.67407Z","caller":"traceutil/trace.go:171","msg":"trace[1961379293] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"431.489805ms","start":"2026-07-25T07:40:34.242566Z","end":"2026-07-25T07:40:34.674055Z","steps":["trace[1961379293] 'process raft request' (duration: 430.661886ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.674136Z","caller":"traceutil/trace.go:171","msg":"trace[1423627052] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"444.565409ms","start":"2026-07-25T07:40:34.229552Z","end":"2026-07-25T07:40:34.674117Z","steps":["trace[1423627052] 'process raft request' (duration: 113.714732ms)","trace[1423627052] 'compare' (duration: 329.366145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:34.674146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.242547Z","time spent":"431.561957ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:40:34.67422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.229533Z","time spent":"444.651641ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.674361Z","caller":"traceutil/trace.go:171","msg":"trace[2019826297] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"442.462436ms","start":"2026-07-25T07:40:34.231882Z","end":"2026-07-25T07:40:34.674345Z","steps":["trace[2019826297] 'process raft request' (duration: 441.157701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.67445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.231869Z","time spent":"442.541788ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.67452Z","caller":"traceutil/trace.go:171","msg":"trace[1877567064] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"436.55616ms","start":"2026-07-25T07:40:34.237948Z","end":"2026-07-25T07:40:34.674504Z","steps":["trace[1877567064] 'process raft request' (duration: 435.138191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.674601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.237929Z","time spent":"436.637594ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:34.675972Z","caller":"traceutil/trace.go:171","msg":"trace[1823395406] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"413.269812ms","start":"2026-07-25T07:40:34.262682Z","end":"2026-07-25T07:40:34.675951Z","steps":["trace[1823395406] 'process raft request' (duration: 410.782285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.676254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.262666Z","time spent":"413.360484ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:40:34.676472Z","caller":"traceutil/trace.go:171","msg":"trace[461092308] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"413.622794ms","start":"2026-07-25T07:40:34.26282Z","end":"2026-07-25T07:40:34.676443Z","steps":["trace[461092308] 'process raft request' (duration: 412.980412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.676629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.262816Z","time spent":"413.732908ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:40:34.676862Z","caller":"traceutil/trace.go:171","msg":"trace[233673051] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:522; }","duration":"257.480628ms","start":"2026-07-25T07:40:34.419367Z","end":"2026-07-25T07:40:34.676847Z","steps":["trace[233673051] 'read index received' (duration: 251.017093ms)","trace[233673051] 'applied index is now lower than readState.Index' (duration: 6.462665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:34.676949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.577421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:40:34.676997Z","caller":"traceutil/trace.go:171","msg":"trace[845947192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"257.660064ms","start":"2026-07-25T07:40:34.419323Z","end":"2026-07-25T07:40:34.676983Z","steps":["trace[845947192] 'agreement among raft nodes before linearized reading' (duration: 257.573491ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:34.67723Z","caller":"traceutil/trace.go:171","msg":"trace[561420585] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"395.608398ms","start":"2026-07-25T07:40:34.281608Z","end":"2026-07-25T07:40:34.677216Z","steps":["trace[561420585] 'process raft request' (duration: 394.744388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:34.677302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:34.281584Z","time spent":"395.68615ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:40:40.922829Z","caller":"traceutil/trace.go:171","msg":"trace[662386068] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"203.431096ms","start":"2026-07-25T07:40:40.719368Z","end":"2026-07-25T07:40:40.922799Z","steps":["trace[662386068] 'read index received' (duration: 203.404525ms)","trace[662386068] 'applied index is now lower than readState.Index' (duration: 24.881µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:40.923059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.675044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tqb9r\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-25T07:40:40.923101Z","caller":"traceutil/trace.go:171","msg":"trace[1655120235] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tqb9r; range_end:; response_count:1; response_revision:597; }","duration":"203.772597ms","start":"2026-07-25T07:40:40.719317Z","end":"2026-07-25T07:40:40.92309Z","steps":["trace[1655120235] 'agreement among raft nodes before linearized reading' (duration: 203.591252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:41.272012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.06626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:40:41.272237Z","caller":"traceutil/trace.go:171","msg":"trace[1045796276] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"373.502128ms","start":"2026-07-25T07:40:40.89871Z","end":"2026-07-25T07:40:41.272212Z","steps":["trace[1045796276] 'process raft request' (duration: 122.123317ms)","trace[1045796276] 'compare' (duration: 250.703529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:41.27233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:40.898668Z","time spent":"373.615631ms","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:40:41.595196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021990270925630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T07:40:41.818758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.80895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:40:41.819162Z","caller":"traceutil/trace.go:171","msg":"trace[1092983328] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"894.995428ms","start":"2026-07-25T07:40:40.924134Z","end":"2026-07-25T07:40:41.81913Z","steps":["trace[1092983328] 'process raft request' (duration: 717.716174ms)","trace[1092983328] 'compare' (duration: 176.481358ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:40:41.819294Z","caller":"traceutil/trace.go:171","msg":"trace[463246584] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"879.417058ms","start":"2026-07-25T07:40:40.939856Z","end":"2026-07-25T07:40:41.819273Z","steps":["trace[463246584] 'process raft request' (duration: 879.020215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:41.819351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:40.924103Z","time spent":"895.168984ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:40:41.819439Z","caller":"traceutil/trace.go:171","msg":"trace[1872446043] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"476.709085ms","start":"2026-07-25T07:40:41.342689Z","end":"2026-07-25T07:40:41.819398Z","steps":["trace[1872446043] 'process raft request' (duration: 476.423676ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:41.819339Z","caller":"traceutil/trace.go:171","msg":"trace[1946737146] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"724.931953ms","start":"2026-07-25T07:40:41.094376Z","end":"2026-07-25T07:40:41.819308Z","steps":["trace[1946737146] 'read index received' (duration: 547.503953ms)","trace[1946737146] 'applied index is now lower than readState.Index' (duration: 177.42582ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:41.819478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:40.939826Z","time spent":"879.567473ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:40:41.819572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:41.342672Z","time spent":"476.83411ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T07:40:41.819775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.809742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T07:40:41.819824Z","caller":"traceutil/trace.go:171","msg":"trace[1999632695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"185.913396ms","start":"2026-07-25T07:40:41.633897Z","end":"2026-07-25T07:40:41.81981Z","steps":["trace[1999632695] 'agreement among raft nodes before linearized reading' (duration: 185.667968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:41.819923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.805863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-25T07:40:41.819837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.898513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T07:40:41.820077Z","caller":"traceutil/trace.go:171","msg":"trace[1972787159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"104.175812ms","start":"2026-07-25T07:40:41.715883Z","end":"2026-07-25T07:40:41.820059Z","steps":["trace[1972787159] 'agreement among raft nodes before linearized reading' (duration: 103.81017ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:41.819979Z","caller":"traceutil/trace.go:171","msg":"trace[343998755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"157.903507ms","start":"2026-07-25T07:40:41.66206Z","end":"2026-07-25T07:40:41.819963Z","steps":["trace[343998755] 'agreement among raft nodes before linearized reading' (duration: 157.755692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:41.820297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.922335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-25T07:40:41.820385Z","caller":"traceutil/trace.go:171","msg":"trace[406340486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"726.036809ms","start":"2026-07-25T07:40:41.094335Z","end":"2026-07-25T07:40:41.820372Z","steps":["trace[406340486] 'agreement among raft nodes before linearized reading' (duration: 725.097308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:41.820468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:41.094312Z","time spent":"726.105131ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T07:40:43.207977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.266334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:40:43.208185Z","caller":"traceutil/trace.go:171","msg":"trace[1684194112] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.382922001s","start":"2026-07-25T07:40:41.825235Z","end":"2026-07-25T07:40:43.208157Z","steps":["trace[1684194112] 'process raft request' (duration: 780.387708ms)","trace[1684194112] 'compare' (duration: 601.880512ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:43.208369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:41.825217Z","time spent":"1.383042515s","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:40:43.208207Z","caller":"traceutil/trace.go:171","msg":"trace[295006920] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:625; }","duration":"1.382419905s","start":"2026-07-25T07:40:41.82577Z","end":"2026-07-25T07:40:43.20819Z","steps":["trace[295006920] 'read index received' (duration: 14.001525ms)","trace[295006920] 'applied index is now lower than readState.Index' (duration: 1.36841754s)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:43.208378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382608921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-25T07:40:43.208563Z","caller":"traceutil/trace.go:171","msg":"trace[215941659] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:605; }","duration":"1.382795397s","start":"2026-07-25T07:40:41.825742Z","end":"2026-07-25T07:40:43.208537Z","steps":["trace[215941659] 'agreement among raft nodes before linearized reading' (duration: 1.382491688s)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:43.208574Z","caller":"traceutil/trace.go:171","msg":"trace[57726123] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.383328734s","start":"2026-07-25T07:40:41.825233Z","end":"2026-07-25T07:40:43.208562Z","steps":["trace[57726123] 'process raft request' (duration: 1.382833738s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:43.208607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:41.825733Z","time spent":"1.382863319s","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-07-25T07:40:43.208644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:41.825209Z","time spent":"1.383397357s","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:40:43.208784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381280148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-25T07:40:43.208827Z","caller":"traceutil/trace.go:171","msg":"trace[812176743] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:605; }","duration":"1.381337149s","start":"2026-07-25T07:40:41.827481Z","end":"2026-07-25T07:40:43.208818Z","steps":["trace[812176743] 'agreement among raft nodes before linearized reading' (duration: 1.381259667s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:43.208855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:41.827472Z","time spent":"1.381375951s","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-25T07:40:43.208996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.58393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:40:43.208796Z","caller":"traceutil/trace.go:171","msg":"trace[1713929733] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.383543071s","start":"2026-07-25T07:40:41.825244Z","end":"2026-07-25T07:40:43.208787Z","steps":["trace[1713929733] 'process raft request' (duration: 1.382906711s)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:40:43.209026Z","caller":"traceutil/trace.go:171","msg":"trace[1485008377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"790.649643ms","start":"2026-07-25T07:40:42.418366Z","end":"2026-07-25T07:40:43.209016Z","steps":["trace[1485008377] 'agreement among raft nodes before linearized reading' (duration: 790.605751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:43.209054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:42.418334Z","time spent":"790.714415ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T07:40:43.209144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:41.82523Z","time spent":"1.38381622s","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:40:45.179395Z","caller":"traceutil/trace.go:171","msg":"trace[1466592536] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"278.841387ms","start":"2026-07-25T07:40:44.90053Z","end":"2026-07-25T07:40:45.179371Z","steps":["trace[1466592536] 'process raft request' (duration: 278.699753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:48.064132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.593363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-25T07:40:48.064203Z","caller":"traceutil/trace.go:171","msg":"trace[2069621579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"148.740558ms","start":"2026-07-25T07:40:47.915444Z","end":"2026-07-25T07:40:48.064185Z","steps":["trace[2069621579] 'range keys from in-memory index tree' (duration: 148.4517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:58.682119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.787634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:40:58.682386Z","caller":"traceutil/trace.go:171","msg":"trace[1488186820] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"1.356339598s","start":"2026-07-25T07:40:57.325985Z","end":"2026-07-25T07:40:58.682325Z","steps":["trace[1488186820] 'process raft request' (duration: 993.208895ms)","trace[1488186820] 'compare' (duration: 361.094666ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:40:58.68241Z","caller":"traceutil/trace.go:171","msg":"trace[1471721401] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:792; }","duration":"568.587945ms","start":"2026-07-25T07:40:58.113802Z","end":"2026-07-25T07:40:58.68239Z","steps":["trace[1471721401] 'read index received' (duration: 205.401591ms)","trace[1471721401] 'applied index is now lower than readState.Index' (duration: 363.185414ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:40:58.682558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:57.32597Z","time spent":"1.356513012s","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:40:58.682586Z","caller":"traceutil/trace.go:171","msg":"trace[618977756] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"852.638178ms","start":"2026-07-25T07:40:57.829909Z","end":"2026-07-25T07:40:58.682547Z","steps":["trace[618977756] 'process raft request' (duration: 852.35917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:58.682826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.860183ms","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-07-25T07:40:58.682861Z","caller":"traceutil/trace.go:171","msg":"trace[1239100565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"569.095169ms","start":"2026-07-25T07:40:58.113754Z","end":"2026-07-25T07:40:58.682849Z","steps":["trace[1239100565] 'agreement among raft nodes before linearized reading' (duration: 568.782241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:58.682895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:58.113732Z","time spent":"569.155021ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T07:40:58.682933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:40:57.829886Z","time spent":"852.948897ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:40:58.682967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.632844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:40:58.682995Z","caller":"traceutil/trace.go:171","msg":"trace[1429550819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"262.697166ms","start":"2026-07-25T07:40:58.420288Z","end":"2026-07-25T07:40:58.682985Z","steps":["trace[1429550819] 'agreement among raft nodes before linearized reading' (duration: 262.645445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:40:58.683122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.350563ms","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-07-25T07:40:58.683175Z","caller":"traceutil/trace.go:171","msg":"trace[1594736629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"128.442436ms","start":"2026-07-25T07:40:58.55472Z","end":"2026-07-25T07:40:58.683162Z","steps":["trace[1594736629] 'agreement among raft nodes before linearized reading' (duration: 128.307152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:41:11.162391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.512877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:41:11.162483Z","caller":"traceutil/trace.go:171","msg":"trace[1021914810] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:921; }","duration":"187.678042ms","start":"2026-07-25T07:41:10.974787Z","end":"2026-07-25T07:41:11.162465Z","steps":["trace[1021914810] 'count revisions from in-memory index tree' (duration: 187.395305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:42:37.280128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.574547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:42:37.280355Z","caller":"traceutil/trace.go:171","msg":"trace[203863372] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"125.366285ms","start":"2026-07-25T07:42:37.154966Z","end":"2026-07-25T07:42:37.280333Z","steps":["trace[203863372] 'compare' (duration: 118.248221ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.280762Z","caller":"traceutil/trace.go:171","msg":"trace[1922815962] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1443; }","duration":"110.329094ms","start":"2026-07-25T07:42:37.170415Z","end":"2026-07-25T07:42:37.280745Z","steps":["trace[1922815962] 'read index received' (duration: 76.073776ms)","trace[1922815962] 'applied index is now lower than readState.Index' (duration: 34.254268ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:42:37.280957Z","caller":"traceutil/trace.go:171","msg":"trace[63865429] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"115.233743ms","start":"2026-07-25T07:42:37.165604Z","end":"2026-07-25T07:42:37.280838Z","steps":["trace[63865429] 'process raft request' (duration: 114.863437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:42:37.281455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.017376ms","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-07-25T07:42:37.281579Z","caller":"traceutil/trace.go:171","msg":"trace[1002887850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1392; }","duration":"111.188628ms","start":"2026-07-25T07:42:37.170368Z","end":"2026-07-25T07:42:37.281557Z","steps":["trace[1002887850] 'agreement among raft nodes before linearized reading' (duration: 110.861463ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.355684Z","caller":"traceutil/trace.go:171","msg":"trace[1285266539] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"165.510248ms","start":"2026-07-25T07:42:37.190155Z","end":"2026-07-25T07:42:37.355665Z","steps":["trace[1285266539] 'process raft request' (duration: 165.304364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:42:37.355766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.558722ms","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-07-25T07:42:37.355817Z","caller":"traceutil/trace.go:171","msg":"trace[764093046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1393; }","duration":"159.662764ms","start":"2026-07-25T07:42:37.196141Z","end":"2026-07-25T07:42:37.355803Z","steps":["trace[764093046] 'agreement among raft nodes before linearized reading' (duration: 159.505981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:42:37.356143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.959963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T07:42:37.356235Z","caller":"traceutil/trace.go:171","msg":"trace[146687487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1393; }","duration":"154.111534ms","start":"2026-07-25T07:42:37.202106Z","end":"2026-07-25T07:42:37.356218Z","steps":["trace[146687487] 'agreement among raft nodes before linearized reading' (duration: 153.604986ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.476534Z","caller":"traceutil/trace.go:171","msg":"trace[1901456479] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"174.518241ms","start":"2026-07-25T07:42:37.301997Z","end":"2026-07-25T07:42:37.476515Z","steps":["trace[1901456479] 'process raft request' (duration: 174.47943ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.476933Z","caller":"traceutil/trace.go:171","msg":"trace[1079849634] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"191.40173ms","start":"2026-07-25T07:42:37.28552Z","end":"2026-07-25T07:42:37.476922Z","steps":["trace[1079849634] 'process raft request' (duration: 189.830875ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.477189Z","caller":"traceutil/trace.go:171","msg":"trace[281814957] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"191.650494ms","start":"2026-07-25T07:42:37.285518Z","end":"2026-07-25T07:42:37.477169Z","steps":["trace[281814957] 'process raft request' (duration: 190.794081ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.477304Z","caller":"traceutil/trace.go:171","msg":"trace[736997648] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"116.906789ms","start":"2026-07-25T07:42:37.360378Z","end":"2026-07-25T07:42:37.477285Z","steps":["trace[736997648] 'process raft request' (duration: 116.628895ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.477333Z","caller":"traceutil/trace.go:171","msg":"trace[1351516896] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"191.669605ms","start":"2026-07-25T07:42:37.285653Z","end":"2026-07-25T07:42:37.477323Z","steps":["trace[1351516896] 'process raft request' (duration: 190.709059ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.477551Z","caller":"traceutil/trace.go:171","msg":"trace[1001250776] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"191.517622ms","start":"2026-07-25T07:42:37.286025Z","end":"2026-07-25T07:42:37.477542Z","steps":["trace[1001250776] 'process raft request' (duration: 190.374104ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.477407Z","caller":"traceutil/trace.go:171","msg":"trace[93810874] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"116.188868ms","start":"2026-07-25T07:42:37.361202Z","end":"2026-07-25T07:42:37.47739Z","steps":["trace[93810874] 'process raft request' (duration: 115.866063ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:37.477691Z","caller":"traceutil/trace.go:171","msg":"trace[1821411769] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"191.591534ms","start":"2026-07-25T07:42:37.286091Z","end":"2026-07-25T07:42:37.477683Z","steps":["trace[1821411769] 'process raft request' (duration: 190.350534ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:39.40153Z","caller":"traceutil/trace.go:171","msg":"trace[367944636] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"235.89797ms","start":"2026-07-25T07:42:39.165606Z","end":"2026-07-25T07:42:39.401504Z","steps":["trace[367944636] 'process raft request' (duration: 235.716747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:42:47.688408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.202211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:42:47.688512Z","caller":"traceutil/trace.go:171","msg":"trace[476380753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1596; }","duration":"269.349333ms","start":"2026-07-25T07:42:47.419137Z","end":"2026-07-25T07:42:47.688486Z","steps":["trace[476380753] 'range keys from in-memory index tree' (duration: 269.10495ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:47.688685Z","caller":"traceutil/trace.go:171","msg":"trace[508692762] linearizableReadLoop","detail":"{readStateIndex:1652; appliedIndex:1651; }","duration":"162.462483ms","start":"2026-07-25T07:42:47.526176Z","end":"2026-07-25T07:42:47.688638Z","steps":["trace[508692762] 'read index received' (duration: 158.635624ms)","trace[508692762] 'applied index is now lower than readState.Index' (duration: 3.824749ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:42:47.688892Z","caller":"traceutil/trace.go:171","msg":"trace[664397339] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"176.44011ms","start":"2026-07-25T07:42:47.512438Z","end":"2026-07-25T07:42:47.688878Z","steps":["trace[664397339] 'process raft request' (duration: 172.429098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:42:47.689012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.837169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-25T07:42:47.689017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.781413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T07:42:47.689066Z","caller":"traceutil/trace.go:171","msg":"trace[2122325940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1597; }","duration":"162.924851ms","start":"2026-07-25T07:42:47.526127Z","end":"2026-07-25T07:42:47.689052Z","steps":["trace[2122325940] 'agreement among raft nodes before linearized reading' (duration: 162.765788ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:42:47.689079Z","caller":"traceutil/trace.go:171","msg":"trace[1670543381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1597; }","duration":"161.878234ms","start":"2026-07-25T07:42:47.527183Z","end":"2026-07-25T07:42:47.689061Z","steps":["trace[1670543381] 'agreement among raft nodes before linearized reading' (duration: 161.720742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:42:47.689134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.265184ms","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-07-25T07:42:47.689219Z","caller":"traceutil/trace.go:171","msg":"trace[79318398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1597; }","duration":"159.386475ms","start":"2026-07-25T07:42:47.529803Z","end":"2026-07-25T07:42:47.689189Z","steps":["trace[79318398] 'agreement among raft nodes before linearized reading' (duration: 159.163962ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:06.66223Z","caller":"traceutil/trace.go:171","msg":"trace[1587537749] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"106.970786ms","start":"2026-07-25T07:43:06.555234Z","end":"2026-07-25T07:43:06.662205Z","steps":["trace[1587537749] 'process raft request' (duration: 106.797334ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:08.61865Z","caller":"traceutil/trace.go:171","msg":"trace[1511897176] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"182.421535ms","start":"2026-07-25T07:43:08.436195Z","end":"2026-07-25T07:43:08.618617Z","steps":["trace[1511897176] 'read index received' (duration: 178.327174ms)","trace[1511897176] 'applied index is now lower than readState.Index' (duration: 4.093221ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:08.618892Z","caller":"traceutil/trace.go:171","msg":"trace[815881350] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"183.527471ms","start":"2026-07-25T07:43:08.435333Z","end":"2026-07-25T07:43:08.618861Z","steps":["trace[815881350] 'process raft request' (duration: 179.208787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:08.618926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.709999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-07-25T07:43:08.618978Z","caller":"traceutil/trace.go:171","msg":"trace[709660441] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:2114; }","duration":"182.804791ms","start":"2026-07-25T07:43:08.436161Z","end":"2026-07-25T07:43:08.618965Z","steps":["trace[709660441] 'agreement among raft nodes before linearized reading' (duration: 182.574717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:10.533266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.770669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:2259"} {"level":"info","ts":"2026-07-25T07:43:10.53359Z","caller":"traceutil/trace.go:171","msg":"trace[122810240] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"487.33061ms","start":"2026-07-25T07:43:10.046231Z","end":"2026-07-25T07:43:10.533561Z","steps":["trace[122810240] 'read index received' (duration: 60.170185ms)","trace[122810240] 'applied index is now lower than readState.Index' (duration: 427.157135ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:10.533692Z","caller":"traceutil/trace.go:171","msg":"trace[1880012412] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"357.255276ms","start":"2026-07-25T07:43:10.176416Z","end":"2026-07-25T07:43:10.533671Z","steps":["trace[1880012412] 'process raft request' (duration: 357.095884ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:10.533734Z","caller":"traceutil/trace.go:171","msg":"trace[1690580414] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2149; }","duration":"494.324573ms","start":"2026-07-25T07:43:10.039392Z","end":"2026-07-25T07:43:10.533717Z","steps":["trace[1690580414] 'process raft request' (duration: 67.025636ms)","trace[1690580414] 'compare' (duration: 426.657317ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:10.533758Z","caller":"traceutil/trace.go:171","msg":"trace[1446705627] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"487.067786ms","start":"2026-07-25T07:43:10.046663Z","end":"2026-07-25T07:43:10.533731Z","steps":["trace[1446705627] 'process raft request' (duration: 486.718821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:10.533799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.176388Z","time spent":"357.366608ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:10.533846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.039383Z","time spent":"494.383724ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":66,"response count":0,"response size":2281,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:10.533901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.046654Z","time spent":"487.173028ms","remote":"127.0.0.1:54534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":728,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T07:43:10.533913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.293445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/valkey-server-certs\" ","response":"range_response_count:1 size:5406"} {"level":"info","ts":"2026-07-25T07:43:10.533989Z","caller":"traceutil/trace.go:171","msg":"trace[1292761003] range","detail":"{range_begin:/registry/secrets/openstack/valkey-server-certs; range_end:; response_count:1; response_revision:2151; }","duration":"484.393827ms","start":"2026-07-25T07:43:10.049578Z","end":"2026-07-25T07:43:10.533971Z","steps":["trace[1292761003] 'agreement among raft nodes before linearized reading' (duration: 484.157953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:10.534007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.273562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2\" ","response":"range_response_count:1 size:3616"} {"level":"warn","ts":"2026-07-25T07:43:10.534035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.04957Z","time spent":"484.455988ms","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":5428,"request content":"key:\"/registry/secrets/openstack/valkey-server-certs\" "} {"level":"info","ts":"2026-07-25T07:43:10.534071Z","caller":"traceutil/trace.go:171","msg":"trace[1245240317] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2; range_end:; response_count:1; response_revision:2151; }","duration":"112.372193ms","start":"2026-07-25T07:43:10.421684Z","end":"2026-07-25T07:43:10.534056Z","steps":["trace[1245240317] 'agreement among raft nodes before linearized reading' (duration: 112.16701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:10.534186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.944629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/valkey-server-sbldk\" ","response":"range_response_count:1 size:2450"} {"level":"warn","ts":"2026-07-25T07:43:10.53425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.47324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T07:43:10.534275Z","caller":"traceutil/trace.go:171","msg":"trace[2076472054] range","detail":"{range_begin:/registry/secrets/openstack/valkey-server-sbldk; range_end:; response_count:1; response_revision:2151; }","duration":"488.04411ms","start":"2026-07-25T07:43:10.046202Z","end":"2026-07-25T07:43:10.534246Z","steps":["trace[2076472054] 'agreement among raft nodes before linearized reading' (duration: 487.833487ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:10.53432Z","caller":"traceutil/trace.go:171","msg":"trace[97777207] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2151; }","duration":"217.552281ms","start":"2026-07-25T07:43:10.316727Z","end":"2026-07-25T07:43:10.53428Z","steps":["trace[97777207] 'agreement among raft nodes before linearized reading' (duration: 217.47298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:10.534362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.04619Z","time spent":"488.160572ms","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":2472,"request content":"key:\"/registry/secrets/openstack/valkey-server-sbldk\" "} {"level":"warn","ts":"2026-07-25T07:43:10.534448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.550887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0623da54-63cd-4cb0-a5dc-ad7074a10573\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-25T07:43:10.534508Z","caller":"traceutil/trace.go:171","msg":"trace[1937492582] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0623da54-63cd-4cb0-a5dc-ad7074a10573; range_end:; response_count:1; response_revision:2151; }","duration":"161.638068ms","start":"2026-07-25T07:43:10.37286Z","end":"2026-07-25T07:43:10.534498Z","steps":["trace[1937492582] 'agreement among raft nodes before linearized reading' (duration: 161.343204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:10.534348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.999173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-6nt7q\" ","response":"range_response_count:1 size:5814"} {"level":"info","ts":"2026-07-25T07:43:10.534634Z","caller":"traceutil/trace.go:171","msg":"trace[60889574] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-6nt7q; range_end:; response_count:1; response_revision:2151; }","duration":"181.295867ms","start":"2026-07-25T07:43:10.353325Z","end":"2026-07-25T07:43:10.534621Z","steps":["trace[60889574] 'agreement among raft nodes before linearized reading' (duration: 180.861041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:10.533813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.326222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:43:10.534742Z","caller":"traceutil/trace.go:171","msg":"trace[9255447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2151; }","duration":"115.307807ms","start":"2026-07-25T07:43:10.419426Z","end":"2026-07-25T07:43:10.534734Z","steps":["trace[9255447] 'agreement among raft nodes before linearized reading' (duration: 114.326703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.594233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.304391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:11.594478Z","caller":"traceutil/trace.go:171","msg":"trace[1441641682] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"1.047969899s","start":"2026-07-25T07:43:10.546489Z","end":"2026-07-25T07:43:11.594459Z","steps":["trace[1441641682] 'process raft request' (duration: 1.047889548s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.594558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.546478Z","time spent":"1.04803879s","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2384,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:11.594772Z","caller":"traceutil/trace.go:171","msg":"trace[118436558] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"1.049787397s","start":"2026-07-25T07:43:10.544972Z","end":"2026-07-25T07:43:11.59476Z","steps":["trace[118436558] 'process raft request' (duration: 351.834286ms)","trace[118436558] 'compare' (duration: 697.157789ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:11.594827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.544944Z","time spent":"1.049853857s","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6128,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:11.624454Z","caller":"traceutil/trace.go:171","msg":"trace[488147286] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2243; }","duration":"964.239747ms","start":"2026-07-25T07:43:10.66019Z","end":"2026-07-25T07:43:11.62443Z","steps":["trace[488147286] 'read index received' (duration: 236.609441ms)","trace[488147286] 'applied index is now lower than readState.Index' (duration: 727.629086ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:11.624777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.578142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-25T07:43:11.624848Z","caller":"traceutil/trace.go:171","msg":"trace[1984884218] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:2154; }","duration":"964.699413ms","start":"2026-07-25T07:43:10.660136Z","end":"2026-07-25T07:43:11.624836Z","steps":["trace[1984884218] 'agreement among raft nodes before linearized reading' (duration: 964.387869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.624964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.660115Z","time spent":"964.836386ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"info","ts":"2026-07-25T07:43:11.625106Z","caller":"traceutil/trace.go:171","msg":"trace[326647274] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2154; }","duration":"1.052890161s","start":"2026-07-25T07:43:10.571581Z","end":"2026-07-25T07:43:11.624472Z","steps":["trace[326647274] 'process raft request' (duration: 1.052764149s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.625313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.571568Z","time spent":"1.053650502s","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":66,"response count":0,"response size":2432,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:11.664353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.652188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-25T07:43:11.664415Z","caller":"traceutil/trace.go:171","msg":"trace[447136251] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2156; }","duration":"717.765999ms","start":"2026-07-25T07:43:10.946633Z","end":"2026-07-25T07:43:11.664399Z","steps":["trace[447136251] 'agreement among raft nodes before linearized reading' (duration: 717.587887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.664452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:10.946609Z","time spent":"717.834111ms","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T07:43:11.664696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.795227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0623da54-63cd-4cb0-a5dc-ad7074a10573\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-25T07:43:11.664743Z","caller":"traceutil/trace.go:171","msg":"trace[817353209] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0623da54-63cd-4cb0-a5dc-ad7074a10573; range_end:; response_count:1; response_revision:2156; }","duration":"123.854019ms","start":"2026-07-25T07:43:11.540871Z","end":"2026-07-25T07:43:11.664725Z","steps":["trace[817353209] 'agreement among raft nodes before linearized reading' (duration: 123.753888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.664885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.094047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2\" ","response":"range_response_count:1 size:3616"} {"level":"info","ts":"2026-07-25T07:43:11.664919Z","caller":"traceutil/trace.go:171","msg":"trace[1518427562] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2; range_end:; response_count:1; response_revision:2156; }","duration":"125.168778ms","start":"2026-07-25T07:43:11.539741Z","end":"2026-07-25T07:43:11.66491Z","steps":["trace[1518427562] 'agreement among raft nodes before linearized reading' (duration: 125.082306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.665019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.251503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T07:43:11.66505Z","caller":"traceutil/trace.go:171","msg":"trace[442175583] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2156; }","duration":"128.325725ms","start":"2026-07-25T07:43:11.536716Z","end":"2026-07-25T07:43:11.665042Z","steps":["trace[442175583] 'agreement among raft nodes before linearized reading' (duration: 128.274674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.665498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.340652ms","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-07-25T07:43:11.665609Z","caller":"traceutil/trace.go:171","msg":"trace[1119172452] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2156; }","duration":"595.502624ms","start":"2026-07-25T07:43:11.070077Z","end":"2026-07-25T07:43:11.665579Z","steps":["trace[1119172452] 'agreement among raft nodes before linearized reading' (duration: 595.27263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.665664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:11.070056Z","time spent":"595.596786ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-25T07:43:11.665666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.950422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:43:11.665716Z","caller":"traceutil/trace.go:171","msg":"trace[1243375956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2156; }","duration":"245.996892ms","start":"2026-07-25T07:43:11.419706Z","end":"2026-07-25T07:43:11.665703Z","steps":["trace[1243375956] 'agreement among raft nodes before linearized reading' (duration: 245.930131ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:11.87025Z","caller":"traceutil/trace.go:171","msg":"trace[631910814] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2248; }","duration":"201.389176ms","start":"2026-07-25T07:43:11.668839Z","end":"2026-07-25T07:43:11.870228Z","steps":["trace[631910814] 'read index received' (duration: 165.291146ms)","trace[631910814] 'applied index is now lower than readState.Index' (duration: 36.09697ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:11.870351Z","caller":"traceutil/trace.go:171","msg":"trace[288226822] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"235.349716ms","start":"2026-07-25T07:43:11.634979Z","end":"2026-07-25T07:43:11.870329Z","steps":["trace[288226822] 'process raft request' (duration: 235.116752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.87046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.60285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:43:11.870631Z","caller":"traceutil/trace.go:171","msg":"trace[1297068174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2157; }","duration":"201.801973ms","start":"2026-07-25T07:43:11.668804Z","end":"2026-07-25T07:43:11.870606Z","steps":["trace[1297068174] 'agreement among raft nodes before linearized reading' (duration: 201.579049ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:11.967784Z","caller":"traceutil/trace.go:171","msg":"trace[80662436] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"292.20558ms","start":"2026-07-25T07:43:11.675562Z","end":"2026-07-25T07:43:11.967767Z","steps":["trace[80662436] 'process raft request' (duration: 291.997977ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:11.967935Z","caller":"traceutil/trace.go:171","msg":"trace[439920425] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"263.014202ms","start":"2026-07-25T07:43:11.70491Z","end":"2026-07-25T07:43:11.967924Z","steps":["trace[439920425] 'process raft request' (duration: 262.815629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.968226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.067901ms","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-07-25T07:43:11.968252Z","caller":"traceutil/trace.go:171","msg":"trace[374118234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2160; }","duration":"158.167062ms","start":"2026-07-25T07:43:11.810079Z","end":"2026-07-25T07:43:11.968246Z","steps":["trace[374118234] 'agreement among raft nodes before linearized reading' (duration: 158.087221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:11.968299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.498332ms","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-07-25T07:43:11.968349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.329548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-25T07:43:11.968365Z","caller":"traceutil/trace.go:171","msg":"trace[1397480056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2160; }","duration":"163.375559ms","start":"2026-07-25T07:43:11.804984Z","end":"2026-07-25T07:43:11.968359Z","steps":["trace[1397480056] 'agreement among raft nodes before linearized reading' (duration: 163.331018ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:11.968374Z","caller":"traceutil/trace.go:171","msg":"trace[1373418917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2160; }","duration":"157.624374ms","start":"2026-07-25T07:43:11.810732Z","end":"2026-07-25T07:43:11.968357Z","steps":["trace[1373418917] 'agreement among raft nodes before linearized reading' (duration: 157.412161ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:11.968323Z","caller":"traceutil/trace.go:171","msg":"trace[1875305657] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"192.872822ms","start":"2026-07-25T07:43:11.775438Z","end":"2026-07-25T07:43:11.968311Z","steps":["trace[1875305657] 'process raft request' (duration: 192.450696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:12.881896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.727044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:12.882164Z","caller":"traceutil/trace.go:171","msg":"trace[1598164713] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2253; }","duration":"914.017034ms","start":"2026-07-25T07:43:11.968126Z","end":"2026-07-25T07:43:12.882143Z","steps":["trace[1598164713] 'read index received' (duration: 44.068496ms)","trace[1598164713] 'applied index is now lower than readState.Index' (duration: 869.947468ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:12.88225Z","caller":"traceutil/trace.go:171","msg":"trace[22510175] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"1.010942377s","start":"2026-07-25T07:43:11.871289Z","end":"2026-07-25T07:43:12.882231Z","steps":["trace[22510175] 'process raft request' (duration: 140.803767ms)","trace[22510175] 'compare' (duration: 869.505061ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:12.88226Z","caller":"traceutil/trace.go:171","msg":"trace[1105440039] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"921.824589ms","start":"2026-07-25T07:43:11.960402Z","end":"2026-07-25T07:43:12.882226Z","steps":["trace[1105440039] 'process raft request' (duration: 921.578886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:12.882371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:11.871259Z","time spent":"1.01105035s","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:12.882419Z","caller":"traceutil/trace.go:171","msg":"trace[891248953] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"909.979225ms","start":"2026-07-25T07:43:11.97242Z","end":"2026-07-25T07:43:12.8824Z","steps":["trace[891248953] 'process raft request' (duration: 909.66856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:12.88248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:11.960372Z","time spent":"922.023912ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:12.882502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:11.972401Z","time spent":"910.058697ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:12.882536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.403526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-25T07:43:12.882536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.938859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T07:43:12.882601Z","caller":"traceutil/trace.go:171","msg":"trace[1742447899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2163; }","duration":"924.503139ms","start":"2026-07-25T07:43:11.95808Z","end":"2026-07-25T07:43:12.882584Z","steps":["trace[1742447899] 'agreement among raft nodes before linearized reading' (duration: 924.147593ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:12.882609Z","caller":"traceutil/trace.go:171","msg":"trace[273129085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2163; }","duration":"926.100322ms","start":"2026-07-25T07:43:11.956496Z","end":"2026-07-25T07:43:12.882596Z","steps":["trace[273129085] 'agreement among raft nodes before linearized reading' (duration: 925.743637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:12.882644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:11.956451Z","time spent":"926.184493ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T07:43:12.882644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:11.958071Z","time spent":"924.563099ms","remote":"127.0.0.1:54724","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":"info","ts":"2026-07-25T07:43:12.882635Z","caller":"traceutil/trace.go:171","msg":"trace[771791155] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"909.082622ms","start":"2026-07-25T07:43:11.973539Z","end":"2026-07-25T07:43:12.882621Z","steps":["trace[771791155] 'process raft request' (duration: 908.804228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:12.882719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:11.97353Z","time spent":"909.151443ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:12.882805Z","caller":"traceutil/trace.go:171","msg":"trace[1968548316] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"340.686613ms","start":"2026-07-25T07:43:12.542111Z","end":"2026-07-25T07:43:12.882797Z","steps":["trace[1968548316] 'process raft request' (duration: 340.633752ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:12.882842Z","caller":"traceutil/trace.go:171","msg":"trace[28685576] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"908.688796ms","start":"2026-07-25T07:43:11.974138Z","end":"2026-07-25T07:43:12.882827Z","steps":["trace[28685576] 'process raft request' (duration: 908.427542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:12.882855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.542084Z","time spent":"340.745953ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:12.882932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:11.974131Z","time spent":"908.737787ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:13.189329Z","caller":"traceutil/trace.go:171","msg":"trace[1519478772] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2259; }","duration":"307.093305ms","start":"2026-07-25T07:43:12.882207Z","end":"2026-07-25T07:43:13.1893Z","steps":["trace[1519478772] 'read index received' (duration: 306.863992ms)","trace[1519478772] 'applied index is now lower than readState.Index' (duration: 227.883µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:13.189538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.950765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0623da54-63cd-4cb0-a5dc-ad7074a10573\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-07-25T07:43:13.189532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.019982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-25T07:43:13.189582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.346131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2\" ","response":"range_response_count:1 size:3736"} {"level":"info","ts":"2026-07-25T07:43:13.189624Z","caller":"traceutil/trace.go:171","msg":"trace[209797574] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2168; }","duration":"515.148743ms","start":"2026-07-25T07:43:12.674459Z","end":"2026-07-25T07:43:13.189607Z","steps":["trace[209797574] 'agreement among raft nodes before linearized reading' (duration: 514.877069ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:13.189629Z","caller":"traceutil/trace.go:171","msg":"trace[174210078] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2; range_end:; response_count:1; response_revision:2168; }","duration":"516.455402ms","start":"2026-07-25T07:43:12.673158Z","end":"2026-07-25T07:43:13.189613Z","steps":["trace[174210078] 'agreement among raft nodes before linearized reading' (duration: 516.26779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:13.189667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.674453Z","time spent":"515.204004ms","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T07:43:13.189674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.673134Z","time spent":"516.530863ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3758,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2\" "} {"level":"info","ts":"2026-07-25T07:43:13.189617Z","caller":"traceutil/trace.go:171","msg":"trace[486470360] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0623da54-63cd-4cb0-a5dc-ad7074a10573; range_end:; response_count:1; response_revision:2168; }","duration":"516.056777ms","start":"2026-07-25T07:43:12.673541Z","end":"2026-07-25T07:43:13.189598Z","steps":["trace[486470360] 'agreement among raft nodes before linearized reading' (duration: 515.831034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:13.189751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.673535Z","time spent":"516.204169ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0623da54-63cd-4cb0-a5dc-ad7074a10573\" "} {"level":"info","ts":"2026-07-25T07:43:13.189882Z","caller":"traceutil/trace.go:171","msg":"trace[1945323333] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"338.068508ms","start":"2026-07-25T07:43:12.851802Z","end":"2026-07-25T07:43:13.18987Z","steps":["trace[1945323333] 'process raft request' (duration: 337.230526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:13.189973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.851772Z","time spent":"338.15187ms","remote":"127.0.0.1:54534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":942,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T07:43:13.189386Z","caller":"traceutil/trace.go:171","msg":"trace[308506613] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"330.331415ms","start":"2026-07-25T07:43:12.858992Z","end":"2026-07-25T07:43:13.189323Z","steps":["trace[308506613] 'process raft request' (duration: 330.233474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:13.191825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.858983Z","time spent":"331.266629ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:13.193034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.527696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-25T07:43:13.193103Z","caller":"traceutil/trace.go:171","msg":"trace[1957968392] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2170; }","duration":"308.663297ms","start":"2026-07-25T07:43:12.884426Z","end":"2026-07-25T07:43:13.193089Z","steps":["trace[1957968392] 'agreement among raft nodes before linearized reading' (duration: 308.505385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:13.193222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.884415Z","time spent":"308.795859ms","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-25T07:43:13.193279Z","caller":"traceutil/trace.go:171","msg":"trace[1546695604] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"307.378159ms","start":"2026-07-25T07:43:12.885864Z","end":"2026-07-25T07:43:13.193242Z","steps":["trace[1546695604] 'process raft request' (duration: 306.940112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:13.193369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.885853Z","time spent":"307.46582ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:13.193668Z","caller":"traceutil/trace.go:171","msg":"trace[265812648] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"306.370845ms","start":"2026-07-25T07:43:12.887275Z","end":"2026-07-25T07:43:13.193646Z","steps":["trace[265812648] 'process raft request' (duration: 305.626044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:13.19378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:12.887252Z","time spent":"306.477006ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:24.481896Z","caller":"traceutil/trace.go:171","msg":"trace[1331011081] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"403.832581ms","start":"2026-07-25T07:43:24.078045Z","end":"2026-07-25T07:43:24.481878Z","steps":["trace[1331011081] 'process raft request' (duration: 403.72514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:24.482025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:24.078028Z","time spent":"403.919662ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:24.762689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.221439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:24.762975Z","caller":"traceutil/trace.go:171","msg":"trace[685434521] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"683.018695ms","start":"2026-07-25T07:43:24.079924Z","end":"2026-07-25T07:43:24.762943Z","steps":["trace[685434521] 'process raft request' (duration: 464.42225ms)","trace[685434521] 'compare' (duration: 218.094428ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:24.763092Z","caller":"traceutil/trace.go:171","msg":"trace[1271042343] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2380; }","duration":"502.06018ms","start":"2026-07-25T07:43:24.26098Z","end":"2026-07-25T07:43:24.763041Z","steps":["trace[1271042343] 'read index received' (duration: 220.808617ms)","trace[1271042343] 'applied index is now lower than readState.Index' (duration: 281.248883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:24.763125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:24.079899Z","time spent":"683.140106ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:24.763202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.210812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-25T07:43:24.763232Z","caller":"traceutil/trace.go:171","msg":"trace[1211627571] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2286; }","duration":"502.324694ms","start":"2026-07-25T07:43:24.260897Z","end":"2026-07-25T07:43:24.763222Z","steps":["trace[1211627571] 'agreement among raft nodes before linearized reading' (duration: 502.212912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:24.763251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:24.260881Z","time spent":"502.365804ms","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T07:43:32.093584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.141266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:32.093703Z","caller":"traceutil/trace.go:171","msg":"trace[1400135336] linearizableReadLoop","detail":"{readStateIndex:2434; appliedIndex:2433; }","duration":"829.93534ms","start":"2026-07-25T07:43:31.263746Z","end":"2026-07-25T07:43:32.093682Z","steps":["trace[1400135336] 'read index received' (duration: 261.619711ms)","trace[1400135336] 'applied index is now lower than readState.Index' (duration: 568.314259ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:32.093862Z","caller":"traceutil/trace.go:171","msg":"trace[1048799437] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"1.07398867s","start":"2026-07-25T07:43:31.019812Z","end":"2026-07-25T07:43:32.0938Z","steps":["trace[1048799437] 'process raft request' (duration: 505.53931ms)","trace[1048799437] 'compare' (duration: 567.72179ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:32.094025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:31.019795Z","time spent":"1.074149593s","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:32.094653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.898223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-25T07:43:32.094711Z","caller":"traceutil/trace.go:171","msg":"trace[1178863108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2335; }","duration":"830.988804ms","start":"2026-07-25T07:43:31.263708Z","end":"2026-07-25T07:43:32.094696Z","steps":["trace[1178863108] 'agreement among raft nodes before linearized reading' (duration: 830.239233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:32.094743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:31.263689Z","time spent":"831.047166ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T07:43:32.16429Z","caller":"traceutil/trace.go:171","msg":"trace[805921371] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"192.776401ms","start":"2026-07-25T07:43:31.971484Z","end":"2026-07-25T07:43:32.16426Z","steps":["trace[805921371] 'process raft request' (duration: 192.620149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:32.164635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.80432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-25T07:43:32.164708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.506442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-wd29j\" ","response":"range_response_count:1 size:5851"} {"level":"info","ts":"2026-07-25T07:43:32.164761Z","caller":"traceutil/trace.go:171","msg":"trace[1456886200] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-wd29j; range_end:; response_count:1; response_revision:2336; }","duration":"174.599533ms","start":"2026-07-25T07:43:31.990146Z","end":"2026-07-25T07:43:32.164746Z","steps":["trace[1456886200] 'agreement among raft nodes before linearized reading' (duration: 174.188407ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:32.164706Z","caller":"traceutil/trace.go:171","msg":"trace[1279397891] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2336; }","duration":"328.914492ms","start":"2026-07-25T07:43:31.835773Z","end":"2026-07-25T07:43:32.164687Z","steps":["trace[1279397891] 'agreement among raft nodes before linearized reading' (duration: 328.574877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:32.164862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:31.835756Z","time spent":"329.092654ms","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T07:43:32.165328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.667868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-25T07:43:32.165375Z","caller":"traceutil/trace.go:171","msg":"trace[328351760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2336; }","duration":"897.73055ms","start":"2026-07-25T07:43:31.267633Z","end":"2026-07-25T07:43:32.165363Z","steps":["trace[328351760] 'agreement among raft nodes before linearized reading' (duration: 896.589413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:32.165405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:31.267625Z","time spent":"897.77265ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T07:43:43.13026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.918262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:43.130416Z","caller":"traceutil/trace.go:171","msg":"trace[740624839] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"160.635632ms","start":"2026-07-25T07:43:42.969761Z","end":"2026-07-25T07:43:43.130396Z","steps":["trace[740624839] 'process raft request' (duration: 15.459206ms)","trace[740624839] 'compare' (duration: 144.79043ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:43.132858Z","caller":"traceutil/trace.go:171","msg":"trace[1560962367] linearizableReadLoop","detail":"{readStateIndex:2509; appliedIndex:2508; }","duration":"119.226583ms","start":"2026-07-25T07:43:43.013611Z","end":"2026-07-25T07:43:43.132838Z","steps":["trace[1560962367] 'read index received' (duration: 119.085211ms)","trace[1560962367] 'applied index is now lower than readState.Index' (duration: 140.352µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:43.132892Z","caller":"traceutil/trace.go:171","msg":"trace[2136653369] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"147.380936ms","start":"2026-07-25T07:43:42.985492Z","end":"2026-07-25T07:43:43.132873Z","steps":["trace[2136653369] 'process raft request' (duration: 147.226204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:43.133102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.468716ms","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-07-25T07:43:43.133113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.422184ms","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-07-25T07:43:43.133163Z","caller":"traceutil/trace.go:171","msg":"trace[1569564570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2407; }","duration":"119.574868ms","start":"2026-07-25T07:43:43.013572Z","end":"2026-07-25T07:43:43.133147Z","steps":["trace[1569564570] 'agreement among raft nodes before linearized reading' (duration: 119.396296ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:43.133172Z","caller":"traceutil/trace.go:171","msg":"trace[543068138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2407; }","duration":"111.524315ms","start":"2026-07-25T07:43:43.021633Z","end":"2026-07-25T07:43:43.133157Z","steps":["trace[543068138] 'agreement among raft nodes before linearized reading' (duration: 111.367583ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:44.160947Z","caller":"traceutil/trace.go:171","msg":"trace[962604975] transaction","detail":"{read_only:false; response_revision:2412; number_of_response:1; }","duration":"140.787132ms","start":"2026-07-25T07:43:44.020136Z","end":"2026-07-25T07:43:44.160923Z","steps":["trace[962604975] 'process raft request' (duration: 140.63598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:46.788725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.433778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:43:46.788823Z","caller":"traceutil/trace.go:171","msg":"trace[368562277] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2434; }","duration":"239.57495ms","start":"2026-07-25T07:43:46.549231Z","end":"2026-07-25T07:43:46.788806Z","steps":["trace[368562277] 'count revisions from in-memory index tree' (duration: 239.322856ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:46.855726Z","caller":"traceutil/trace.go:171","msg":"trace[2045410810] linearizableReadLoop","detail":"{readStateIndex:2538; appliedIndex:2537; }","duration":"274.548705ms","start":"2026-07-25T07:43:46.581159Z","end":"2026-07-25T07:43:46.855708Z","steps":["trace[2045410810] 'read index received' (duration: 274.499405ms)","trace[2045410810] 'applied index is now lower than readState.Index' (duration: 48.35µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:46.855831Z","caller":"traceutil/trace.go:171","msg":"trace[1530413867] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"285.328794ms","start":"2026-07-25T07:43:46.570472Z","end":"2026-07-25T07:43:46.855801Z","steps":["trace[1530413867] 'process raft request' (duration: 285.126793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:46.856002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.757898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-07-25T07:43:46.856132Z","caller":"traceutil/trace.go:171","msg":"trace[1821947343] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2435; }","duration":"274.92291ms","start":"2026-07-25T07:43:46.58113Z","end":"2026-07-25T07:43:46.856053Z","steps":["trace[1821947343] 'agreement among raft nodes before linearized reading' (duration: 274.644076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:47.423925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.572238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:47.424221Z","caller":"traceutil/trace.go:171","msg":"trace[1617077088] linearizableReadLoop","detail":"{readStateIndex:2544; appliedIndex:2541; }","duration":"267.357002ms","start":"2026-07-25T07:43:47.156847Z","end":"2026-07-25T07:43:47.424204Z","steps":["trace[1617077088] 'read index received' (duration: 60.398299ms)","trace[1617077088] 'applied index is now lower than readState.Index' (duration: 206.957363ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:47.424306Z","caller":"traceutil/trace.go:171","msg":"trace[2092633894] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"254.035557ms","start":"2026-07-25T07:43:47.170258Z","end":"2026-07-25T07:43:47.424294Z","steps":["trace[2092633894] 'process raft request' (duration: 253.903475ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:47.424308Z","caller":"traceutil/trace.go:171","msg":"trace[101483455] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"280.866619ms","start":"2026-07-25T07:43:47.14337Z","end":"2026-07-25T07:43:47.424236Z","steps":["trace[101483455] 'process raft request' (duration: 280.670237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:47.424573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.633446ms","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-07-25T07:43:47.424603Z","caller":"traceutil/trace.go:171","msg":"trace[375527355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2442; }","duration":"267.804928ms","start":"2026-07-25T07:43:47.15679Z","end":"2026-07-25T07:43:47.424595Z","steps":["trace[375527355] 'agreement among raft nodes before linearized reading' (duration: 267.584685ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:47.424585Z","caller":"traceutil/trace.go:171","msg":"trace[46209995] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"325.465929ms","start":"2026-07-25T07:43:47.099085Z","end":"2026-07-25T07:43:47.424551Z","steps":["trace[46209995] 'process raft request' (duration: 118.149541ms)","trace[46209995] 'compare' (duration: 205.238229ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:47.424463Z","caller":"traceutil/trace.go:171","msg":"trace[1363528974] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"277.33154ms","start":"2026-07-25T07:43:47.147094Z","end":"2026-07-25T07:43:47.424425Z","steps":["trace[1363528974] 'process raft request' (duration: 277.024626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:47.424901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:47.09906Z","time spent":"325.669751ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:47.455983Z","caller":"traceutil/trace.go:171","msg":"trace[196908594] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"231.983761ms","start":"2026-07-25T07:43:47.223974Z","end":"2026-07-25T07:43:47.455958Z","steps":["trace[196908594] 'process raft request' (duration: 231.846169ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:47.590538Z","caller":"traceutil/trace.go:171","msg":"trace[1188829843] linearizableReadLoop","detail":"{readStateIndex:2547; appliedIndex:2545; }","duration":"166.267048ms","start":"2026-07-25T07:43:47.424254Z","end":"2026-07-25T07:43:47.590521Z","steps":["trace[1188829843] 'read index received' (duration: 31.579308ms)","trace[1188829843] 'applied index is now lower than readState.Index' (duration: 134.6868ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:47.590552Z","caller":"traceutil/trace.go:171","msg":"trace[1822940359] transaction","detail":"{read_only:false; response_revision:2445; number_of_response:1; }","duration":"162.684718ms","start":"2026-07-25T07:43:47.427848Z","end":"2026-07-25T07:43:47.590533Z","steps":["trace[1822940359] 'process raft request' (duration: 162.641387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:47.590706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.905407ms","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-07-25T07:43:47.590732Z","caller":"traceutil/trace.go:171","msg":"trace[2039983867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2445; }","duration":"421.973628ms","start":"2026-07-25T07:43:47.168751Z","end":"2026-07-25T07:43:47.590725Z","steps":["trace[2039983867] 'agreement among raft nodes before linearized reading' (duration: 421.834476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:47.590753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:47.168738Z","time spent":"422.008899ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T07:43:47.590728Z","caller":"traceutil/trace.go:171","msg":"trace[312318511] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"299.344245ms","start":"2026-07-25T07:43:47.291367Z","end":"2026-07-25T07:43:47.590711Z","steps":["trace[312318511] 'process raft request' (duration: 259.30321ms)","trace[312318511] 'compare' (duration: 39.727181ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:47.590824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.786236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-25T07:43:47.590804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.033524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T07:43:47.590858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.402742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T07:43:47.590867Z","caller":"traceutil/trace.go:171","msg":"trace[2055306871] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2445; }","duration":"313.873577ms","start":"2026-07-25T07:43:47.276981Z","end":"2026-07-25T07:43:47.590854Z","steps":["trace[2055306871] 'agreement among raft nodes before linearized reading' (duration: 313.720015ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:47.590877Z","caller":"traceutil/trace.go:171","msg":"trace[898976964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2445; }","duration":"419.444022ms","start":"2026-07-25T07:43:47.171427Z","end":"2026-07-25T07:43:47.590871Z","steps":["trace[898976964] 'agreement among raft nodes before linearized reading' (duration: 419.391092ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:47.590879Z","caller":"traceutil/trace.go:171","msg":"trace[1261795847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2445; }","duration":"171.144465ms","start":"2026-07-25T07:43:47.419717Z","end":"2026-07-25T07:43:47.590862Z","steps":["trace[1261795847] 'agreement among raft nodes before linearized reading' (duration: 171.031884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:47.590898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:47.276961Z","time spent":"313.930378ms","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T07:43:47.590896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:47.171414Z","time spent":"419.477994ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T07:43:48.249249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021990270929332,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T07:43:48.644545Z","caller":"traceutil/trace.go:171","msg":"trace[440209190] linearizableReadLoop","detail":"{readStateIndex:2552; appliedIndex:2551; }","duration":"895.39119ms","start":"2026-07-25T07:43:47.749128Z","end":"2026-07-25T07:43:48.64452Z","steps":["trace[440209190] 'read index received' (duration: 865.412775ms)","trace[440209190] 'applied index is now lower than readState.Index' (duration: 29.977295ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:48.644809Z","caller":"traceutil/trace.go:171","msg":"trace[1115755461] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"898.207239ms","start":"2026-07-25T07:43:47.746578Z","end":"2026-07-25T07:43:48.644786Z","steps":["trace[1115755461] 'process raft request' (duration: 867.774707ms)","trace[1115755461] 'compare' (duration: 29.393818ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:48.644948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:47.746551Z","time spent":"898.30638ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:48.645041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.794803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-25T07:43:48.645062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.908627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T07:43:48.645062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.432217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:43:48.645108Z","caller":"traceutil/trace.go:171","msg":"trace[2065418247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2449; }","duration":"391.908754ms","start":"2026-07-25T07:43:48.253182Z","end":"2026-07-25T07:43:48.645091Z","steps":["trace[2065418247] 'agreement among raft nodes before linearized reading' (duration: 391.701161ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:48.645117Z","caller":"traceutil/trace.go:171","msg":"trace[422006182] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2449; }","duration":"896.013368ms","start":"2026-07-25T07:43:47.749088Z","end":"2026-07-25T07:43:48.645102Z","steps":["trace[422006182] 'agreement among raft nodes before linearized reading' (duration: 895.898517ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:48.645121Z","caller":"traceutil/trace.go:171","msg":"trace[1251144956] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2449; }","duration":"378.533629ms","start":"2026-07-25T07:43:48.266574Z","end":"2026-07-25T07:43:48.645107Z","steps":["trace[1251144956] 'agreement among raft nodes before linearized reading' (duration: 378.428487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:48.645147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:48.253163Z","time spent":"391.975475ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T07:43:48.64515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:48.26656Z","time spent":"378.58116ms","remote":"127.0.0.1:40494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-25T07:43:48.645146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.511792ms","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-07-25T07:43:48.645173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:47.749073Z","time spent":"896.065879ms","remote":"127.0.0.1:57376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-07-25T07:43:48.645217Z","caller":"traceutil/trace.go:171","msg":"trace[1872618397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2449; }","duration":"371.627993ms","start":"2026-07-25T07:43:48.273565Z","end":"2026-07-25T07:43:48.645193Z","steps":["trace[1872618397] 'agreement among raft nodes before linearized reading' (duration: 371.41201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:48.64526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:48.273545Z","time spent":"371.706785ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T07:43:48.871755Z","caller":"traceutil/trace.go:171","msg":"trace[2010902020] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"223.093424ms","start":"2026-07-25T07:43:48.648635Z","end":"2026-07-25T07:43:48.871728Z","steps":["trace[2010902020] 'process raft request' (duration: 222.861471ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:48.871892Z","caller":"traceutil/trace.go:171","msg":"trace[266970663] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"214.94702ms","start":"2026-07-25T07:43:48.656862Z","end":"2026-07-25T07:43:48.871809Z","steps":["trace[266970663] 'process raft request' (duration: 214.789798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:48.872064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.756016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-25T07:43:48.87215Z","caller":"traceutil/trace.go:171","msg":"trace[1168811130] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2452; }","duration":"185.887178ms","start":"2026-07-25T07:43:48.686242Z","end":"2026-07-25T07:43:48.872129Z","steps":["trace[1168811130] 'agreement among raft nodes before linearized reading' (duration: 185.656834ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:48.871741Z","caller":"traceutil/trace.go:171","msg":"trace[184240164] linearizableReadLoop","detail":"{readStateIndex:2555; appliedIndex:2553; }","duration":"185.394581ms","start":"2026-07-25T07:43:48.68632Z","end":"2026-07-25T07:43:48.871715Z","steps":["trace[184240164] 'read index received' (duration: 55.51615ms)","trace[184240164] 'applied index is now lower than readState.Index' (duration: 129.877421ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:49.572948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.029612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:49.573091Z","caller":"traceutil/trace.go:171","msg":"trace[835577396] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"588.613152ms","start":"2026-07-25T07:43:48.984465Z","end":"2026-07-25T07:43:49.573078Z","steps":["trace[835577396] 'process raft request' (duration: 588.557342ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:49.573095Z","caller":"traceutil/trace.go:171","msg":"trace[1823074977] linearizableReadLoop","detail":"{readStateIndex:2557; appliedIndex:2556; }","duration":"613.733921ms","start":"2026-07-25T07:43:48.95935Z","end":"2026-07-25T07:43:49.573084Z","steps":["trace[1823074977] 'read index received' (duration: 27.21µs)","trace[1823074977] 'applied index is now lower than readState.Index' (duration: 613.705961ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:49.573138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:48.984443Z","time spent":"588.674713ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:49.573247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.891183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12400"} {"level":"info","ts":"2026-07-25T07:43:49.573273Z","caller":"traceutil/trace.go:171","msg":"trace[1485433465] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2455; }","duration":"613.937973ms","start":"2026-07-25T07:43:48.959329Z","end":"2026-07-25T07:43:49.573267Z","steps":["trace[1485433465] 'agreement among raft nodes before linearized reading' (duration: 613.830022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:49.573294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:48.959318Z","time spent":"613.969743ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12422,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-25T07:43:49.573148Z","caller":"traceutil/trace.go:171","msg":"trace[1782769935] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"688.432046ms","start":"2026-07-25T07:43:48.884708Z","end":"2026-07-25T07:43:49.57314Z","steps":["trace[1782769935] 'process raft request' (duration: 23.143211ms)","trace[1782769935] 'compare' (duration: 664.787598ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:49.573336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:48.884689Z","time spent":"688.62778ms","remote":"127.0.0.1:54534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:49.573611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.996795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-07-25T07:43:49.573641Z","caller":"traceutil/trace.go:171","msg":"trace[1696928855] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2453; }","duration":"689.044925ms","start":"2026-07-25T07:43:48.884588Z","end":"2026-07-25T07:43:49.573633Z","steps":["trace[1696928855] 'range keys from in-memory index tree' (duration: 687.9716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:49.57366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:48.884575Z","time spent":"689.080895ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-07-25T07:43:50.073781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021990270929348,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T07:43:50.444254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.617817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:50.444923Z","caller":"traceutil/trace.go:171","msg":"trace[1289122841] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"1.016041654s","start":"2026-07-25T07:43:49.428853Z","end":"2026-07-25T07:43:50.444894Z","steps":["trace[1289122841] 'process raft request' (duration: 735.696787ms)","trace[1289122841] 'compare' (duration: 279.462445ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:43:50.444984Z","caller":"traceutil/trace.go:171","msg":"trace[1279829137] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"1.014387011s","start":"2026-07-25T07:43:49.430579Z","end":"2026-07-25T07:43:50.444966Z","steps":["trace[1279829137] 'process raft request' (duration: 1.014213169s)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:50.444961Z","caller":"traceutil/trace.go:171","msg":"trace[879032336] linearizableReadLoop","detail":"{readStateIndex:2560; appliedIndex:2558; }","duration":"871.824067ms","start":"2026-07-25T07:43:49.573119Z","end":"2026-07-25T07:43:50.444943Z","steps":["trace[879032336] 'read index received' (duration: 591.444829ms)","trace[879032336] 'applied index is now lower than readState.Index' (duration: 280.378418ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:50.445056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.428834Z","time spent":"1.016152786s","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:50.445072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.430562Z","time spent":"1.014467092s","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:50.445204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427607774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T07:43:50.445211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02585068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:43:50.445298Z","caller":"traceutil/trace.go:171","msg":"trace[2052953686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2457; }","duration":"1.025989523s","start":"2026-07-25T07:43:49.419281Z","end":"2026-07-25T07:43:50.445271Z","steps":["trace[2052953686] 'agreement among raft nodes before linearized reading' (duration: 1.025691808s)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:50.44528Z","caller":"traceutil/trace.go:171","msg":"trace[337500999] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2457; }","duration":"1.427699305s","start":"2026-07-25T07:43:49.017563Z","end":"2026-07-25T07:43:50.445263Z","steps":["trace[337500999] 'agreement among raft nodes before linearized reading' (duration: 1.427565554s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:50.44541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.419256Z","time spent":"1.026142194s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T07:43:50.445417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.017554Z","time spent":"1.427815477s","remote":"127.0.0.1:40402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-25T07:43:50.445453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.213701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2\" ","response":"range_response_count:1 size:1293"} {"level":"warn","ts":"2026-07-25T07:43:50.445537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.752484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T07:43:50.445551Z","caller":"traceutil/trace.go:171","msg":"trace[210434815] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2; range_end:; response_count:1; response_revision:2457; }","duration":"869.353213ms","start":"2026-07-25T07:43:49.576168Z","end":"2026-07-25T07:43:50.445521Z","steps":["trace[210434815] 'agreement among raft nodes before linearized reading' (duration: 869.10664ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:50.445575Z","caller":"traceutil/trace.go:171","msg":"trace[1203100231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2457; }","duration":"829.812875ms","start":"2026-07-25T07:43:49.615751Z","end":"2026-07-25T07:43:50.445564Z","steps":["trace[1203100231] 'agreement among raft nodes before linearized reading' (duration: 829.680883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:50.445598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.615741Z","time spent":"829.851726ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T07:43:50.445605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.57615Z","time spent":"869.442274ms","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1315,"request content":"key:\"/registry/persistentvolumes/pvc-b520d48a-a2ce-46ca-a518-726bdfb7a3e2\" "} {"level":"warn","ts":"2026-07-25T07:43:50.445616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.621987ms","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-07-25T07:43:50.445711Z","caller":"traceutil/trace.go:171","msg":"trace[1901938442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2457; }","duration":"851.714088ms","start":"2026-07-25T07:43:49.593952Z","end":"2026-07-25T07:43:50.445666Z","steps":["trace[1901938442] 'agreement among raft nodes before linearized reading' (duration: 851.561576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:50.445759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.593929Z","time spent":"851.81748ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T07:43:50.445811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.926007ms","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-07-25T07:43:50.445838Z","caller":"traceutil/trace.go:171","msg":"trace[1051145985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2457; }","duration":"830.037678ms","start":"2026-07-25T07:43:49.615794Z","end":"2026-07-25T07:43:50.445832Z","steps":["trace[1051145985] 'agreement among raft nodes before linearized reading' (duration: 829.982198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:50.445859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.615789Z","time spent":"830.065259ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T07:43:50.445855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.879929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T07:43:50.445761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.994879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-25T07:43:50.445898Z","caller":"traceutil/trace.go:171","msg":"trace[919765336] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2457; }","duration":"861.93428ms","start":"2026-07-25T07:43:49.583952Z","end":"2026-07-25T07:43:50.445886Z","steps":["trace[919765336] 'agreement among raft nodes before linearized reading' (duration: 861.869129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:50.446017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.583946Z","time spent":"862.060992ms","remote":"127.0.0.1:54914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-25T07:43:50.445928Z","caller":"traceutil/trace.go:171","msg":"trace[2076538114] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2457; }","duration":"794.173441ms","start":"2026-07-25T07:43:49.651739Z","end":"2026-07-25T07:43:50.445912Z","steps":["trace[2076538114] 'agreement among raft nodes before linearized reading' (duration: 793.951268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:50.446106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.414982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12400"} {"level":"info","ts":"2026-07-25T07:43:50.448133Z","caller":"traceutil/trace.go:171","msg":"trace[1304426935] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2457; }","duration":"855.477471ms","start":"2026-07-25T07:43:49.592632Z","end":"2026-07-25T07:43:50.448109Z","steps":["trace[1304426935] 'agreement among raft nodes before linearized reading' (duration: 852.855265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:50.448204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.592615Z","time spent":"855.565312ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12422,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-25T07:43:50.446174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:49.651729Z","time spent":"794.429915ms","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T07:43:51.221576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.813145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:43:51.222016Z","caller":"traceutil/trace.go:171","msg":"trace[244908357] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"771.52915ms","start":"2026-07-25T07:43:50.450467Z","end":"2026-07-25T07:43:51.221996Z","steps":["trace[244908357] 'process raft request' (duration: 450.223499ms)","trace[244908357] 'compare' (duration: 320.309477ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:51.222146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.450455Z","time spent":"771.650611ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:51.339164Z","caller":"traceutil/trace.go:171","msg":"trace[510419265] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"887.2904ms","start":"2026-07-25T07:43:50.451843Z","end":"2026-07-25T07:43:51.339134Z","steps":["trace[510419265] 'process raft request' (duration: 887.045446ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:51.3392Z","caller":"traceutil/trace.go:171","msg":"trace[1457672952] linearizableReadLoop","detail":"{readStateIndex:2563; appliedIndex:2560; }","duration":"885.619366ms","start":"2026-07-25T07:43:50.453557Z","end":"2026-07-25T07:43:51.339176Z","steps":["trace[1457672952] 'read index received' (duration: 447.221857ms)","trace[1457672952] 'applied index is now lower than readState.Index' (duration: 438.396499ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:43:51.3393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.451821Z","time spent":"887.407821ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:51.339314Z","caller":"traceutil/trace.go:171","msg":"trace[1385523846] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"887.37949ms","start":"2026-07-25T07:43:50.451924Z","end":"2026-07-25T07:43:51.339304Z","steps":["trace[1385523846] 'process raft request' (duration: 887.115486ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:51.3394Z","caller":"traceutil/trace.go:171","msg":"trace[116353981] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"452.951861ms","start":"2026-07-25T07:43:50.886429Z","end":"2026-07-25T07:43:51.339381Z","steps":["trace[116353981] 'process raft request' (duration: 452.711488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:51.339496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.886408Z","time spent":"453.033462ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:43:51.339597Z","caller":"traceutil/trace.go:171","msg":"trace[2031870344] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"881.440578ms","start":"2026-07-25T07:43:50.458147Z","end":"2026-07-25T07:43:51.339587Z","steps":["trace[2031870344] 'process raft request' (duration: 880.935421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:51.339672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.458135Z","time spent":"881.50877ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12367,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:51.339619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.451918Z","time spent":"887.425681ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:43:51.339649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.906598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-25T07:43:51.339741Z","caller":"traceutil/trace.go:171","msg":"trace[435311402] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2462; }","duration":"883.027751ms","start":"2026-07-25T07:43:50.456691Z","end":"2026-07-25T07:43:51.339719Z","steps":["trace[435311402] 'agreement among raft nodes before linearized reading' (duration: 882.765127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:51.339754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.196354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:43:51.339781Z","caller":"traceutil/trace.go:171","msg":"trace[63836449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2462; }","duration":"886.249114ms","start":"2026-07-25T07:43:50.453524Z","end":"2026-07-25T07:43:51.339774Z","steps":["trace[63836449] 'agreement among raft nodes before linearized reading' (duration: 886.205014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:51.339789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.456682Z","time spent":"883.095721ms","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-25T07:43:51.339857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.259883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T07:43:51.339879Z","caller":"traceutil/trace.go:171","msg":"trace[2120473582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2462; }","duration":"463.315954ms","start":"2026-07-25T07:43:50.876556Z","end":"2026-07-25T07:43:51.339872Z","steps":["trace[2120473582] 'agreement among raft nodes before linearized reading' (duration: 463.269693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:51.339901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.876535Z","time spent":"463.360924ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T07:43:51.339811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.861674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-25T07:43:51.340036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.52525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T07:43:51.34006Z","caller":"traceutil/trace.go:171","msg":"trace[1568975872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2462; }","duration":"595.137927ms","start":"2026-07-25T07:43:50.744907Z","end":"2026-07-25T07:43:51.340045Z","steps":["trace[1568975872] 'agreement among raft nodes before linearized reading' (duration: 594.819193ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:43:51.340087Z","caller":"traceutil/trace.go:171","msg":"trace[2120845032] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2462; }","duration":"829.60623ms","start":"2026-07-25T07:43:50.510467Z","end":"2026-07-25T07:43:51.340074Z","steps":["trace[2120845032] 'agreement among raft nodes before linearized reading' (duration: 829.516549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:51.340108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.744888Z","time spent":"595.202669ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T07:43:51.340124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.510456Z","time spent":"829.659042ms","remote":"127.0.0.1:54918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":3,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-07-25T07:43:51.340183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.412186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-25T07:43:51.340233Z","caller":"traceutil/trace.go:171","msg":"trace[2135106038] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:2462; }","duration":"677.505598ms","start":"2026-07-25T07:43:50.66272Z","end":"2026-07-25T07:43:51.340225Z","steps":["trace[2135106038] 'agreement among raft nodes before linearized reading' (duration: 677.344845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:51.340254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.662696Z","time spent":"677.552878ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-07-25T07:43:51.34037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.017357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-25T07:43:51.340411Z","caller":"traceutil/trace.go:171","msg":"trace[990104786] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2462; }","duration":"791.094258ms","start":"2026-07-25T07:43:50.549305Z","end":"2026-07-25T07:43:51.3404Z","steps":["trace[990104786] 'agreement among raft nodes before linearized reading' (duration: 790.981486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:43:51.340446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.549288Z","time spent":"791.14857ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-25T07:43:51.339799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:43:50.453515Z","time spent":"886.281215ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T07:44:17.392813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.240256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T07:44:17.392905Z","caller":"traceutil/trace.go:171","msg":"trace[752365367] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2602; }","duration":"149.401774ms","start":"2026-07-25T07:44:17.243487Z","end":"2026-07-25T07:44:17.392889Z","steps":["trace[752365367] 'count revisions from in-memory index tree' (duration: 149.139652ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:44:17.571939Z","caller":"traceutil/trace.go:171","msg":"trace[68083571] linearizableReadLoop","detail":"{readStateIndex:2715; appliedIndex:2713; }","duration":"104.151451ms","start":"2026-07-25T07:44:17.467767Z","end":"2026-07-25T07:44:17.571919Z","steps":["trace[68083571] 'read index received' (duration: 90.948176ms)","trace[68083571] 'applied index is now lower than readState.Index' (duration: 13.202585ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:44:17.572Z","caller":"traceutil/trace.go:171","msg":"trace[964536790] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"136.403898ms","start":"2026-07-25T07:44:17.435551Z","end":"2026-07-25T07:44:17.571955Z","steps":["trace[964536790] 'process raft request' (duration: 123.33381ms)","trace[964536790] 'compare' (duration: 12.8734ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:44:17.572101Z","caller":"traceutil/trace.go:171","msg":"trace[962749780] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"133.201307ms","start":"2026-07-25T07:44:17.438783Z","end":"2026-07-25T07:44:17.571984Z","steps":["trace[962749780] 'process raft request' (duration: 133.085261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:44:17.572218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.424932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T07:44:17.572316Z","caller":"traceutil/trace.go:171","msg":"trace[1701835451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2606; }","duration":"104.578089ms","start":"2026-07-25T07:44:17.467716Z","end":"2026-07-25T07:44:17.572294Z","steps":["trace[1701835451] 'agreement among raft nodes before linearized reading' (duration: 104.306967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:44:17.57235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.624507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-25T07:44:17.572401Z","caller":"traceutil/trace.go:171","msg":"trace[1371080474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2606; }","duration":"103.686329ms","start":"2026-07-25T07:44:17.468701Z","end":"2026-07-25T07:44:17.572388Z","steps":["trace[1371080474] 'agreement among raft nodes before linearized reading' (duration: 103.516052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:44:17.572143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.828117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T07:44:17.57247Z","caller":"traceutil/trace.go:171","msg":"trace[1124902301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2606; }","duration":"104.213143ms","start":"2026-07-25T07:44:17.468246Z","end":"2026-07-25T07:44:17.572459Z","steps":["trace[1124902301] 'agreement among raft nodes before linearized reading' (duration: 103.795214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:20.278032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.668714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:45:20.278169Z","caller":"traceutil/trace.go:171","msg":"trace[1714990688] transaction","detail":"{read_only:false; response_revision:3050; number_of_response:1; }","duration":"315.085627ms","start":"2026-07-25T07:45:19.963057Z","end":"2026-07-25T07:45:20.278143Z","steps":["trace[1714990688] 'process raft request' (duration: 163.176844ms)","trace[1714990688] 'compare' (duration: 151.54319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:45:20.278268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:19.963035Z","time spent":"315.18849ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:20.310224Z","caller":"traceutil/trace.go:171","msg":"trace[1811635580] transaction","detail":"{read_only:false; response_revision:3051; number_of_response:1; }","duration":"345.516373ms","start":"2026-07-25T07:45:19.964674Z","end":"2026-07-25T07:45:20.310191Z","steps":["trace[1811635580] 'process raft request' (duration: 345.302156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:20.310442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:19.964662Z","time spent":"345.693138ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:20.310594Z","caller":"traceutil/trace.go:171","msg":"trace[1332655252] transaction","detail":"{read_only:false; response_revision:3052; number_of_response:1; }","duration":"345.791552ms","start":"2026-07-25T07:45:19.96478Z","end":"2026-07-25T07:45:20.310572Z","steps":["trace[1332655252] 'process raft request' (duration: 345.366077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:20.310701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:19.964753Z","time spent":"345.895065ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:20.3107Z","caller":"traceutil/trace.go:171","msg":"trace[1619783099] transaction","detail":"{read_only:false; response_revision:3053; number_of_response:1; }","duration":"345.910036ms","start":"2026-07-25T07:45:19.964772Z","end":"2026-07-25T07:45:20.310682Z","steps":["trace[1619783099] 'process raft request' (duration: 345.768801ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:20.31071Z","caller":"traceutil/trace.go:171","msg":"trace[1478376020] linearizableReadLoop","detail":"{readStateIndex:3175; appliedIndex:3171; }","duration":"327.708085ms","start":"2026-07-25T07:45:19.982976Z","end":"2026-07-25T07:45:20.310684Z","steps":["trace[1478376020] 'read index received' (duration: 143.256186ms)","trace[1478376020] 'applied index is now lower than readState.Index' (duration: 184.450529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:45:20.310784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:19.964753Z","time spent":"345.988968ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:45:20.310969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.854458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-07-25T07:45:20.310968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.971755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-25T07:45:20.311052Z","caller":"traceutil/trace.go:171","msg":"trace[1917638587] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c; range_end:; response_count:1; response_revision:3053; }","duration":"271.95088ms","start":"2026-07-25T07:45:20.03907Z","end":"2026-07-25T07:45:20.31102Z","steps":["trace[1917638587] 'agreement among raft nodes before linearized reading' (duration: 271.793285ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:20.311798Z","caller":"traceutil/trace.go:171","msg":"trace[1548420572] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c; range_end:; response_count:1; response_revision:3053; }","duration":"328.093848ms","start":"2026-07-25T07:45:19.982944Z","end":"2026-07-25T07:45:20.311037Z","steps":["trace[1548420572] 'agreement among raft nodes before linearized reading' (duration: 327.86266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:20.311868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:19.982933Z","time spent":"328.919896ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c\" "} {"level":"info","ts":"2026-07-25T07:45:20.449597Z","caller":"traceutil/trace.go:171","msg":"trace[1206868371] transaction","detail":"{read_only:false; response_revision:3054; number_of_response:1; }","duration":"129.838396ms","start":"2026-07-25T07:45:20.319721Z","end":"2026-07-25T07:45:20.449559Z","steps":["trace[1206868371] 'process raft request' (duration: 125.425818ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:21.196145Z","caller":"traceutil/trace.go:171","msg":"trace[217999529] transaction","detail":"{read_only:false; response_revision:3057; number_of_response:1; }","duration":"458.764683ms","start":"2026-07-25T07:45:20.737353Z","end":"2026-07-25T07:45:21.196118Z","steps":["trace[217999529] 'process raft request' (duration: 452.997671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:21.196279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:20.737336Z","time spent":"458.873867ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:21.198333Z","caller":"traceutil/trace.go:171","msg":"trace[799134956] linearizableReadLoop","detail":"{readStateIndex:3181; appliedIndex:3179; }","duration":"206.547181ms","start":"2026-07-25T07:45:20.991732Z","end":"2026-07-25T07:45:21.198279Z","steps":["trace[799134956] 'read index received' (duration: 198.534235ms)","trace[799134956] 'applied index is now lower than readState.Index' (duration: 8.011936ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:45:21.198579Z","caller":"traceutil/trace.go:171","msg":"trace[280215963] transaction","detail":"{read_only:false; response_revision:3058; number_of_response:1; }","duration":"459.594221ms","start":"2026-07-25T07:45:20.738974Z","end":"2026-07-25T07:45:21.198568Z","steps":["trace[280215963] 'process raft request' (duration: 459.220128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:21.198659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:20.738949Z","time spent":"459.671043ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:45:21.198816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.081709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-07-25T07:45:21.198853Z","caller":"traceutil/trace.go:171","msg":"trace[1165187929] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c; range_end:; response_count:1; response_revision:3058; }","duration":"207.144331ms","start":"2026-07-25T07:45:20.991698Z","end":"2026-07-25T07:45:21.198842Z","steps":["trace[1165187929] 'agreement among raft nodes before linearized reading' (duration: 207.038117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:21.199146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.959728ms","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-07-25T07:45:21.199234Z","caller":"traceutil/trace.go:171","msg":"trace[793402343] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:3058; }","duration":"184.067412ms","start":"2026-07-25T07:45:21.015148Z","end":"2026-07-25T07:45:21.199215Z","steps":["trace[793402343] 'agreement among raft nodes before linearized reading' (duration: 183.863115ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:22.48815Z","caller":"traceutil/trace.go:171","msg":"trace[322732211] linearizableReadLoop","detail":"{readStateIndex:3189; appliedIndex:3188; }","duration":"240.882939ms","start":"2026-07-25T07:45:22.247242Z","end":"2026-07-25T07:45:22.488125Z","steps":["trace[322732211] 'read index received' (duration: 240.5885ms)","trace[322732211] 'applied index is now lower than readState.Index' (duration: 293.199µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:45:22.488289Z","caller":"traceutil/trace.go:171","msg":"trace[1791774699] transaction","detail":"{read_only:false; response_revision:3066; number_of_response:1; }","duration":"379.642697ms","start":"2026-07-25T07:45:22.108636Z","end":"2026-07-25T07:45:22.488279Z","steps":["trace[1791774699] 'process raft request' (duration: 379.292445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.488422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:22.108625Z","time spent":"379.7144ms","remote":"127.0.0.1:54534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:45:22.488545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.379437ms","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-07-25T07:45:22.488581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.687948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-07-25T07:45:22.488627Z","caller":"traceutil/trace.go:171","msg":"trace[76915936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3066; }","duration":"206.507061ms","start":"2026-07-25T07:45:22.282101Z","end":"2026-07-25T07:45:22.488608Z","steps":["trace[76915936] 'agreement among raft nodes before linearized reading' (duration: 206.244663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.488633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.369756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-25T07:45:22.488656Z","caller":"traceutil/trace.go:171","msg":"trace[281833003] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c; range_end:; response_count:1; response_revision:3066; }","duration":"168.816422ms","start":"2026-07-25T07:45:22.31982Z","end":"2026-07-25T07:45:22.488637Z","steps":["trace[281833003] 'agreement among raft nodes before linearized reading' (duration: 168.541513ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:22.488716Z","caller":"traceutil/trace.go:171","msg":"trace[1142790289] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3066; }","duration":"241.49128ms","start":"2026-07-25T07:45:22.247201Z","end":"2026-07-25T07:45:22.488692Z","steps":["trace[1142790289] 'agreement among raft nodes before linearized reading' (duration: 241.19013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.488949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.389427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T07:45:22.488986Z","caller":"traceutil/trace.go:171","msg":"trace[1316116688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3066; }","duration":"174.45968ms","start":"2026-07-25T07:45:22.314514Z","end":"2026-07-25T07:45:22.488973Z","steps":["trace[1316116688] 'agreement among raft nodes before linearized reading' (duration: 174.356616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.48917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.608165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T07:45:22.489213Z","caller":"traceutil/trace.go:171","msg":"trace[1618946490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3066; }","duration":"174.669416ms","start":"2026-07-25T07:45:22.314532Z","end":"2026-07-25T07:45:22.489202Z","steps":["trace[1618946490] 'agreement among raft nodes before linearized reading' (duration: 174.513301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.489222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.735102ms","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-07-25T07:45:22.489289Z","caller":"traceutil/trace.go:171","msg":"trace[1095260716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3066; }","duration":"175.837756ms","start":"2026-07-25T07:45:22.313435Z","end":"2026-07-25T07:45:22.489273Z","steps":["trace[1095260716] 'agreement among raft nodes before linearized reading' (duration: 175.618028ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:22.892337Z","caller":"traceutil/trace.go:171","msg":"trace[1073618876] transaction","detail":"{read_only:false; response_revision:3067; number_of_response:1; }","duration":"398.386197ms","start":"2026-07-25T07:45:22.493925Z","end":"2026-07-25T07:45:22.892311Z","steps":["trace[1073618876] 'process raft request' (duration: 386.40038ms)","trace[1073618876] 'compare' (duration: 11.562843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:45:22.892469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:22.493903Z","time spent":"398.498041ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:22.92904Z","caller":"traceutil/trace.go:171","msg":"trace[1640902829] transaction","detail":"{read_only:false; response_revision:3068; number_of_response:1; }","duration":"433.977817ms","start":"2026-07-25T07:45:22.495036Z","end":"2026-07-25T07:45:22.929014Z","steps":["trace[1640902829] 'process raft request' (duration: 433.848213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.929158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:22.495022Z","time spent":"434.07396ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:22.92926Z","caller":"traceutil/trace.go:171","msg":"trace[468292000] transaction","detail":"{read_only:false; response_revision:3069; number_of_response:1; }","duration":"433.925705ms","start":"2026-07-25T07:45:22.495319Z","end":"2026-07-25T07:45:22.929244Z","steps":["trace[468292000] 'process raft request' (duration: 433.663246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.929342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:22.495312Z","time spent":"433.988306ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:22.929495Z","caller":"traceutil/trace.go:171","msg":"trace[2104369074] linearizableReadLoop","detail":"{readStateIndex:3191; appliedIndex:3189; }","duration":"434.291647ms","start":"2026-07-25T07:45:22.495194Z","end":"2026-07-25T07:45:22.929486Z","steps":["trace[2104369074] 'read index received' (duration: 385.143988ms)","trace[2104369074] 'applied index is now lower than readState.Index' (duration: 49.146999ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:45:22.929609Z","caller":"traceutil/trace.go:171","msg":"trace[948267604] transaction","detail":"{read_only:false; response_revision:3070; number_of_response:1; }","duration":"433.295313ms","start":"2026-07-25T07:45:22.496308Z","end":"2026-07-25T07:45:22.929603Z","steps":["trace[948267604] 'process raft request' (duration: 432.87845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.929639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:22.4963Z","time spent":"433.320215ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:45:22.929749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.553385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-07-25T07:45:22.929765Z","caller":"traceutil/trace.go:171","msg":"trace[1700837688] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3070; }","duration":"434.586867ms","start":"2026-07-25T07:45:22.495172Z","end":"2026-07-25T07:45:22.929759Z","steps":["trace[1700837688] 'agreement among raft nodes before linearized reading' (duration: 434.521224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.929778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:22.495151Z","time spent":"434.624628ms","remote":"127.0.0.1:54658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1339,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-07-25T07:45:22.929816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.532316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:45:22.929865Z","caller":"traceutil/trace.go:171","msg":"trace[419576939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:3070; }","duration":"361.598769ms","start":"2026-07-25T07:45:22.568253Z","end":"2026-07-25T07:45:22.929852Z","steps":["trace[419576939] 'agreement among raft nodes before linearized reading' (duration: 361.501325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.929898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:22.568236Z","time spent":"361.655481ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-07-25T07:45:22.930008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.900742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-07-25T07:45:22.930028Z","caller":"traceutil/trace.go:171","msg":"trace[1736096126] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c; range_end:; response_count:1; response_revision:3070; }","duration":"314.949443ms","start":"2026-07-25T07:45:22.615072Z","end":"2026-07-25T07:45:22.930021Z","steps":["trace[1736096126] 'agreement among raft nodes before linearized reading' (duration: 314.876801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:22.930041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:22.615054Z","time spent":"314.984785ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3973,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c\" "} {"level":"warn","ts":"2026-07-25T07:45:33.263674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.119941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:45:33.264077Z","caller":"traceutil/trace.go:171","msg":"trace[1043876512] transaction","detail":"{read_only:false; response_revision:3150; number_of_response:1; }","duration":"634.031511ms","start":"2026-07-25T07:45:32.630029Z","end":"2026-07-25T07:45:33.26406Z","steps":["trace[1043876512] 'process raft request' (duration: 145.443834ms)","trace[1043876512] 'compare' (duration: 487.555414ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:45:33.264141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:32.63Z","time spent":"634.106053ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:33.264193Z","caller":"traceutil/trace.go:171","msg":"trace[325662463] linearizableReadLoop","detail":"{readStateIndex:3276; appliedIndex:3274; }","duration":"333.192845ms","start":"2026-07-25T07:45:32.930973Z","end":"2026-07-25T07:45:33.264166Z","steps":["trace[325662463] 'read index received' (duration: 38.401µs)","trace[325662463] 'applied index is now lower than readState.Index' (duration: 333.152134ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:45:33.264266Z","caller":"traceutil/trace.go:171","msg":"trace[765997304] transaction","detail":"{read_only:false; response_revision:3151; number_of_response:1; }","duration":"506.435976ms","start":"2026-07-25T07:45:32.757822Z","end":"2026-07-25T07:45:33.264258Z","steps":["trace[765997304] 'process raft request' (duration: 505.9268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.264341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.377241ms","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-07-25T07:45:33.264341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:32.757798Z","time spent":"506.484617ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:45:33.264364Z","caller":"traceutil/trace.go:171","msg":"trace[783396101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3152; }","duration":"333.440603ms","start":"2026-07-25T07:45:32.930916Z","end":"2026-07-25T07:45:33.264357Z","steps":["trace[783396101] 'agreement among raft nodes before linearized reading' (duration: 333.36202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.264386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:32.930894Z","time spent":"333.486265ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T07:45:33.352806Z","caller":"traceutil/trace.go:171","msg":"trace[385992037] transaction","detail":"{read_only:false; response_revision:3153; number_of_response:1; }","duration":"118.465151ms","start":"2026-07-25T07:45:33.234317Z","end":"2026-07-25T07:45:33.352782Z","steps":["trace[385992037] 'process raft request' (duration: 118.10827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.352823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.991753ms","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-07-25T07:45:33.352904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.971494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-25T07:45:33.352928Z","caller":"traceutil/trace.go:171","msg":"trace[898456162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3153; }","duration":"363.028945ms","start":"2026-07-25T07:45:32.989887Z","end":"2026-07-25T07:45:33.352916Z","steps":["trace[898456162] 'agreement among raft nodes before linearized reading' (duration: 362.709575ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:33.35296Z","caller":"traceutil/trace.go:171","msg":"trace[190150855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3153; }","duration":"368.171999ms","start":"2026-07-25T07:45:32.984783Z","end":"2026-07-25T07:45:33.352955Z","steps":["trace[190150855] 'agreement among raft nodes before linearized reading' (duration: 367.967473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.352983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:32.984773Z","time spent":"368.20567ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T07:45:33.352979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.052015ms","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-07-25T07:45:33.353042Z","caller":"traceutil/trace.go:171","msg":"trace[1853173115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3153; }","duration":"363.16208ms","start":"2026-07-25T07:45:32.98986Z","end":"2026-07-25T07:45:33.353022Z","steps":["trace[1853173115] 'agreement among raft nodes before linearized reading' (duration: 362.994864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.353026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.719849ms","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":"warn","ts":"2026-07-25T07:45:33.353071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:32.989819Z","time spent":"363.248792ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T07:45:33.353103Z","caller":"traceutil/trace.go:171","msg":"trace[1105207687] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:3153; }","duration":"233.853233ms","start":"2026-07-25T07:45:33.119231Z","end":"2026-07-25T07:45:33.353084Z","steps":["trace[1105207687] 'agreement among raft nodes before linearized reading' (duration: 233.592775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.353089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:45:32.989839Z","time spent":"363.236981ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T07:45:33.479312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.566818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T07:45:33.479617Z","caller":"traceutil/trace.go:171","msg":"trace[12360298] transaction","detail":"{read_only:false; response_revision:3154; number_of_response:1; }","duration":"211.206904ms","start":"2026-07-25T07:45:33.268377Z","end":"2026-07-25T07:45:33.479584Z","steps":["trace[12360298] 'process raft request' (duration: 101.271804ms)","trace[12360298] 'compare' (duration: 109.346341ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:45:33.479803Z","caller":"traceutil/trace.go:171","msg":"trace[249882254] linearizableReadLoop","detail":"{readStateIndex:3279; appliedIndex:3278; }","duration":"127.25175ms","start":"2026-07-25T07:45:33.352541Z","end":"2026-07-25T07:45:33.479792Z","steps":["trace[249882254] 'read index received' (duration: 17.098073ms)","trace[249882254] 'applied index is now lower than readState.Index' (duration: 110.152066ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T07:45:33.480042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.174381ms","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-07-25T07:45:33.480081Z","caller":"traceutil/trace.go:171","msg":"trace[804123761] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3155; }","duration":"158.254553ms","start":"2026-07-25T07:45:33.321817Z","end":"2026-07-25T07:45:33.480072Z","steps":["trace[804123761] 'agreement among raft nodes before linearized reading' (duration: 158.057907ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:33.480526Z","caller":"traceutil/trace.go:171","msg":"trace[1876964289] transaction","detail":"{read_only:false; response_revision:3156; number_of_response:1; }","duration":"122.421496ms","start":"2026-07-25T07:45:33.358088Z","end":"2026-07-25T07:45:33.480509Z","steps":["trace[1876964289] 'process raft request' (duration: 121.708304ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:33.480683Z","caller":"traceutil/trace.go:171","msg":"trace[1220976251] transaction","detail":"{read_only:false; response_revision:3157; number_of_response:1; }","duration":"122.029564ms","start":"2026-07-25T07:45:33.358629Z","end":"2026-07-25T07:45:33.480658Z","steps":["trace[1220976251] 'process raft request' (duration: 121.704794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.480546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.11452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-25T07:45:33.484259Z","caller":"traceutil/trace.go:171","msg":"trace[1129180037] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a4c04f2e-f9f1-4017-a3c3-b13d223abf2c; range_end:; response_count:1; response_revision:3157; }","duration":"127.778576ms","start":"2026-07-25T07:45:33.356403Z","end":"2026-07-25T07:45:33.484182Z","steps":["trace[1129180037] 'agreement among raft nodes before linearized reading' (duration: 124.023547ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:33.481252Z","caller":"traceutil/trace.go:171","msg":"trace[81400812] transaction","detail":"{read_only:false; response_revision:3155; number_of_response:1; }","duration":"123.154259ms","start":"2026-07-25T07:45:33.357991Z","end":"2026-07-25T07:45:33.481145Z","steps":["trace[81400812] 'process raft request' (duration: 121.626051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.91882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.210826ms","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-07-25T07:45:33.918911Z","caller":"traceutil/trace.go:171","msg":"trace[1885077780] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:3158; }","duration":"252.354601ms","start":"2026-07-25T07:45:33.666533Z","end":"2026-07-25T07:45:33.918887Z","steps":["trace[1885077780] 'range keys from in-memory index tree' (duration: 251.969858ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:45:33.940303Z","caller":"traceutil/trace.go:171","msg":"trace[1643064869] linearizableReadLoop","detail":"{readStateIndex:3284; appliedIndex:3283; }","duration":"182.098761ms","start":"2026-07-25T07:45:33.75818Z","end":"2026-07-25T07:45:33.940279Z","steps":["trace[1643064869] 'read index received' (duration: 181.867083ms)","trace[1643064869] 'applied index is now lower than readState.Index' (duration: 230.408µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:45:33.94042Z","caller":"traceutil/trace.go:171","msg":"trace[982477185] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"220.735217ms","start":"2026-07-25T07:45:33.719665Z","end":"2026-07-25T07:45:33.9404Z","steps":["trace[982477185] 'process raft request' (duration: 220.476588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:45:33.940657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.451422ms","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-07-25T07:45:33.940706Z","caller":"traceutil/trace.go:171","msg":"trace[588329450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3159; }","duration":"182.542005ms","start":"2026-07-25T07:45:33.758151Z","end":"2026-07-25T07:45:33.940693Z","steps":["trace[588329450] 'agreement among raft nodes before linearized reading' (duration: 182.221155ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:47:57.85458Z","caller":"traceutil/trace.go:171","msg":"trace[904736424] transaction","detail":"{read_only:false; response_revision:4086; number_of_response:1; }","duration":"118.771105ms","start":"2026-07-25T07:47:57.735781Z","end":"2026-07-25T07:47:57.854552Z","steps":["trace[904736424] 'process raft request' (duration: 118.354476ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:47:58.575948Z","caller":"traceutil/trace.go:171","msg":"trace[704158890] linearizableReadLoop","detail":"{readStateIndex:4251; appliedIndex:4248; }","duration":"321.297274ms","start":"2026-07-25T07:47:58.254629Z","end":"2026-07-25T07:47:58.575927Z","steps":["trace[704158890] 'read index received' (duration: 79.465912ms)","trace[704158890] 'applied index is now lower than readState.Index' (duration: 241.830462ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:47:58.576009Z","caller":"traceutil/trace.go:171","msg":"trace[1140276768] transaction","detail":"{read_only:false; response_revision:4090; number_of_response:1; }","duration":"321.452838ms","start":"2026-07-25T07:47:58.254531Z","end":"2026-07-25T07:47:58.575983Z","steps":["trace[1140276768] 'process raft request' (duration: 321.354376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:47:58.57618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:47:58.254516Z","time spent":"321.566121ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T07:47:58.575997Z","caller":"traceutil/trace.go:171","msg":"trace[1333365845] transaction","detail":"{read_only:false; response_revision:4089; number_of_response:1; }","duration":"321.469308ms","start":"2026-07-25T07:47:58.25449Z","end":"2026-07-25T07:47:58.57596Z","steps":["trace[1333365845] 'process raft request' (duration: 321.233103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:47:58.576314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:47:58.254464Z","time spent":"321.815466ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T07:47:58.576199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.569691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T07:47:58.576355Z","caller":"traceutil/trace.go:171","msg":"trace[1830536344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4090; }","duration":"321.760474ms","start":"2026-07-25T07:47:58.254582Z","end":"2026-07-25T07:47:58.576342Z","steps":["trace[1830536344] 'agreement among raft nodes before linearized reading' (duration: 321.505159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:47:58.576416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T07:47:58.254527Z","time spent":"321.879017ms","remote":"127.0.0.1:54724","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-07-25T07:47:58.595774Z","caller":"traceutil/trace.go:171","msg":"trace[2000053702] transaction","detail":"{read_only:false; response_revision:4091; number_of_response:1; }","duration":"163.468752ms","start":"2026-07-25T07:47:58.432271Z","end":"2026-07-25T07:47:58.595739Z","steps":["trace[2000053702] 'process raft request' (duration: 163.302669ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:49:57.632463Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2830} {"level":"info","ts":"2026-07-25T07:49:57.698536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2830,"took":"64.796777ms","hash":1275451843,"current-db-size-bytes":38916096,"current-db-size":"39 MB","current-db-size-in-use-bytes":13987840,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-25T07:49:57.698708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1275451843,"revision":2830,"compact-revision":-1} {"level":"info","ts":"2026-07-25T07:51:07.67684Z","caller":"traceutil/trace.go:171","msg":"trace[774638255] linearizableReadLoop","detail":"{readStateIndex:5680; appliedIndex:5679; }","duration":"169.269431ms","start":"2026-07-25T07:51:07.507546Z","end":"2026-07-25T07:51:07.676816Z","steps":["trace[774638255] 'read index received' (duration: 169.230931ms)","trace[774638255] 'applied index is now lower than readState.Index' (duration: 37.52µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:51:07.676958Z","caller":"traceutil/trace.go:171","msg":"trace[1012120011] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"169.833721ms","start":"2026-07-25T07:51:07.507111Z","end":"2026-07-25T07:51:07.676945Z","steps":["trace[1012120011] 'process raft request' (duration: 169.562146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:51:07.677201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.630028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T07:51:07.677685Z","caller":"traceutil/trace.go:171","msg":"trace[1474915023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5474; }","duration":"169.8074ms","start":"2026-07-25T07:51:07.507524Z","end":"2026-07-25T07:51:07.677331Z","steps":["trace[1474915023] 'agreement among raft nodes before linearized reading' (duration: 169.558286ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:51:07.686765Z","caller":"traceutil/trace.go:171","msg":"trace[1236752602] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"178.36795ms","start":"2026-07-25T07:51:07.508379Z","end":"2026-07-25T07:51:07.686747Z","steps":["trace[1236752602] 'process raft request' (duration: 178.190077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:51:07.686918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.987582ms","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-07-25T07:51:07.686958Z","caller":"traceutil/trace.go:171","msg":"trace[353218471] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:5475; }","duration":"115.063573ms","start":"2026-07-25T07:51:07.571885Z","end":"2026-07-25T07:51:07.686949Z","steps":["trace[353218471] 'agreement among raft nodes before linearized reading' (duration: 114.89363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:51:07.687129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.046268ms","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-07-25T07:51:07.687188Z","caller":"traceutil/trace.go:171","msg":"trace[927697463] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:5475; }","duration":"161.126319ms","start":"2026-07-25T07:51:07.526051Z","end":"2026-07-25T07:51:07.687178Z","steps":["trace[927697463] 'agreement among raft nodes before linearized reading' (duration: 160.657181ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:51:36.885213Z","caller":"traceutil/trace.go:171","msg":"trace[1889047025] linearizableReadLoop","detail":"{readStateIndex:5903; appliedIndex:5902; }","duration":"104.000756ms","start":"2026-07-25T07:51:36.781189Z","end":"2026-07-25T07:51:36.88519Z","steps":["trace[1889047025] 'read index received' (duration: 96.037788ms)","trace[1889047025] 'applied index is now lower than readState.Index' (duration: 7.961968ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:51:36.885467Z","caller":"traceutil/trace.go:171","msg":"trace[416940335] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"145.279218ms","start":"2026-07-25T07:51:36.740177Z","end":"2026-07-25T07:51:36.885456Z","steps":["trace[416940335] 'process raft request' (duration: 137.116017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:51:36.885678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.462214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T07:51:36.885721Z","caller":"traceutil/trace.go:171","msg":"trace[1833434919] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5688; }","duration":"104.548886ms","start":"2026-07-25T07:51:36.781161Z","end":"2026-07-25T07:51:36.88571Z","steps":["trace[1833434919] 'agreement among raft nodes before linearized reading' (duration: 104.461844ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:52:31.669106Z","caller":"traceutil/trace.go:171","msg":"trace[1979565967] transaction","detail":"{read_only:false; response_revision:6578; number_of_response:1; }","duration":"159.325981ms","start":"2026-07-25T07:52:31.509757Z","end":"2026-07-25T07:52:31.669083Z","steps":["trace[1979565967] 'process raft request' (duration: 159.170719ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:52:31.698515Z","caller":"traceutil/trace.go:171","msg":"trace[1785286095] linearizableReadLoop","detail":"{readStateIndex:6828; appliedIndex:6826; }","duration":"120.718667ms","start":"2026-07-25T07:52:31.577778Z","end":"2026-07-25T07:52:31.698497Z","steps":["trace[1785286095] 'read index received' (duration: 91.199396ms)","trace[1785286095] 'applied index is now lower than readState.Index' (duration: 29.518431ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:52:31.698587Z","caller":"traceutil/trace.go:171","msg":"trace[1135438817] transaction","detail":"{read_only:false; response_revision:6579; number_of_response:1; }","duration":"176.284839ms","start":"2026-07-25T07:52:31.522269Z","end":"2026-07-25T07:52:31.698553Z","steps":["trace[1135438817] 'process raft request' (duration: 176.120856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:52:31.698845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.050622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T07:52:31.698867Z","caller":"traceutil/trace.go:171","msg":"trace[612028353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6580; }","duration":"121.131223ms","start":"2026-07-25T07:52:31.57773Z","end":"2026-07-25T07:52:31.698861Z","steps":["trace[612028353] 'agreement among raft nodes before linearized reading' (duration: 120.971591ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:52:32.671529Z","caller":"traceutil/trace.go:171","msg":"trace[1091740423] transaction","detail":"{read_only:false; response_revision:6602; number_of_response:1; }","duration":"128.227604ms","start":"2026-07-25T07:52:32.543279Z","end":"2026-07-25T07:52:32.671506Z","steps":["trace[1091740423] 'process raft request' (duration: 128.115932ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:52:32.707112Z","caller":"traceutil/trace.go:171","msg":"trace[1840403016] transaction","detail":"{read_only:false; response_revision:6603; number_of_response:1; }","duration":"160.533371ms","start":"2026-07-25T07:52:32.546556Z","end":"2026-07-25T07:52:32.707089Z","steps":["trace[1840403016] 'process raft request' (duration: 160.364778ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:52:37.251233Z","caller":"traceutil/trace.go:171","msg":"trace[1536988493] linearizableReadLoop","detail":"{readStateIndex:6915; appliedIndex:6914; }","duration":"128.624768ms","start":"2026-07-25T07:52:37.12259Z","end":"2026-07-25T07:52:37.251215Z","steps":["trace[1536988493] 'read index received' (duration: 128.428235ms)","trace[1536988493] 'applied index is now lower than readState.Index' (duration: 195.673µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T07:52:37.251371Z","caller":"traceutil/trace.go:171","msg":"trace[24920770] transaction","detail":"{read_only:false; response_revision:6664; number_of_response:1; }","duration":"144.73344ms","start":"2026-07-25T07:52:37.106593Z","end":"2026-07-25T07:52:37.251327Z","steps":["trace[24920770] 'process raft request' (duration: 144.511296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T07:52:37.251438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.837532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-data-valkey-node-1.18c578c45ddeee81\" ","response":"range_response_count:1 size:764"} {"level":"info","ts":"2026-07-25T07:52:37.25147Z","caller":"traceutil/trace.go:171","msg":"trace[570739495] range","detail":"{range_begin:/registry/events/openstack/valkey-data-valkey-node-1.18c578c45ddeee81; range_end:; response_count:1; response_revision:6664; }","duration":"128.927603ms","start":"2026-07-25T07:52:37.122534Z","end":"2026-07-25T07:52:37.251461Z","steps":["trace[570739495] 'agreement among raft nodes before linearized reading' (duration: 128.775501ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T07:54:57.63848Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4857} {"level":"info","ts":"2026-07-25T07:54:57.693707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4857,"took":"53.77149ms","hash":1198121489,"current-db-size-bytes":38916096,"current-db-size":"39 MB","current-db-size-in-use-bytes":30605312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-25T07:54:57.693776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1198121489,"revision":4857,"compact-revision":2830}