{"level":"warn","ts":"2026-07-27T19:56:22.219803Z","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-27T19:56:22.219903Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-27T19:56:22.220005Z","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-27T19:56:22.220022Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-27T19:56:22.220052Z","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-27T19:56:22.22101Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-07-27T19:56:22.221101Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-27T19:56:22.225978Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.603905ms"} {"level":"info","ts":"2026-07-27T19:56:22.232144Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-07-27T19:56:22.232247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T19:56:22.232275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-07-27T19:56:22.232289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T19:56:22.232301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-07-27T19:56:22.232346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-07-27T19:56:22.240717Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T19:56:22.242055Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T19:56:22.243361Z","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-27T19:56:22.245519Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T19:56:22.245704Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T19:56:22.245909Z","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-27T19:56:22.246113Z","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-27T19:56:22.246229Z","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-27T19:56:22.246467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-07-27T19:56:22.24718Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-27T19:56:22.251459Z","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-27T19:56:22.251701Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-27T19:56:22.251777Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T19:56:22.251923Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T19:56:22.252084Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-27T19:56:22.83294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T19:56:22.833005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T19:56:22.833039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-07-27T19:56:22.833057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-07-27T19:56:22.833081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-27T19:56:22.833101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-07-27T19:56:22.833118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-27T19:56:22.834968Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T19:56:22.835224Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T19:56:22.835343Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T19:56:22.835434Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T19:56:22.835467Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T19:56:22.835765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T19:56:22.83611Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T19:56:22.836223Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T19:56:22.836257Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T19:56:22.837108Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T19:56:22.838919Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-07-27T19:56:58.270271Z","caller":"traceutil/trace.go:171","msg":"trace[791973758] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"163.545582ms","start":"2026-07-27T19:56:58.106658Z","end":"2026-07-27T19:56:58.270203Z","steps":["trace[791973758] 'process raft request' (duration: 163.231388ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.270351Z","caller":"traceutil/trace.go:171","msg":"trace[73424880] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"139.86096ms","start":"2026-07-27T19:56:58.130469Z","end":"2026-07-27T19:56:58.27033Z","steps":["trace[73424880] 'process raft request' (duration: 139.822968ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.270382Z","caller":"traceutil/trace.go:171","msg":"trace[926879677] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"151.886384ms","start":"2026-07-27T19:56:58.11847Z","end":"2026-07-27T19:56:58.270356Z","steps":["trace[926879677] 'process raft request' (duration: 151.689585ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.270408Z","caller":"traceutil/trace.go:171","msg":"trace[1002218059] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"140.100751ms","start":"2026-07-27T19:56:58.130299Z","end":"2026-07-27T19:56:58.270399Z","steps":["trace[1002218059] 'process raft request' (duration: 139.952994ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.270552Z","caller":"traceutil/trace.go:171","msg":"trace[591102829] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"152.100795ms","start":"2026-07-27T19:56:58.118438Z","end":"2026-07-27T19:56:58.270539Z","steps":["trace[591102829] 'process raft request' (duration: 151.638263ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.270595Z","caller":"traceutil/trace.go:171","msg":"trace[1456774179] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"140.260959ms","start":"2026-07-27T19:56:58.130178Z","end":"2026-07-27T19:56:58.270439Z","steps":["trace[1456774179] 'process raft request' (duration: 140.021448ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.270432Z","caller":"traceutil/trace.go:171","msg":"trace[1832298735] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"155.629857ms","start":"2026-07-27T19:56:58.114792Z","end":"2026-07-27T19:56:58.270422Z","steps":["trace[1832298735] 'process raft request' (duration: 155.200697ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.276831Z","caller":"traceutil/trace.go:171","msg":"trace[981405112] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"121.475752ms","start":"2026-07-27T19:56:58.155332Z","end":"2026-07-27T19:56:58.276808Z","steps":["trace[981405112] 'process raft request' (duration: 120.851523ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.277004Z","caller":"traceutil/trace.go:171","msg":"trace[1844857349] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"121.581007ms","start":"2026-07-27T19:56:58.155413Z","end":"2026-07-27T19:56:58.276994Z","steps":["trace[1844857349] 'process raft request' (duration: 120.895055ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.277509Z","caller":"traceutil/trace.go:171","msg":"trace[950206284] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"121.965175ms","start":"2026-07-27T19:56:58.15553Z","end":"2026-07-27T19:56:58.277495Z","steps":["trace[950206284] 'process raft request' (duration: 120.829113ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.277661Z","caller":"traceutil/trace.go:171","msg":"trace[1413438691] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"119.631457ms","start":"2026-07-27T19:56:58.158023Z","end":"2026-07-27T19:56:58.277655Z","steps":["trace[1413438691] 'process raft request' (duration: 118.374179ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.277798Z","caller":"traceutil/trace.go:171","msg":"trace[872530711] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"119.720841ms","start":"2026-07-27T19:56:58.158065Z","end":"2026-07-27T19:56:58.277786Z","steps":["trace[872530711] 'process raft request' (duration: 118.38865ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.277833Z","caller":"traceutil/trace.go:171","msg":"trace[888762877] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"117.064888ms","start":"2026-07-27T19:56:58.160761Z","end":"2026-07-27T19:56:58.277826Z","steps":["trace[888762877] 'process raft request' (duration: 115.733727ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:56:58.27796Z","caller":"traceutil/trace.go:171","msg":"trace[1431738791] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"103.772755ms","start":"2026-07-27T19:56:58.174179Z","end":"2026-07-27T19:56:58.277952Z","steps":["trace[1431738791] 'process raft request' (duration: 102.37348ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:57:04.59706Z","caller":"traceutil/trace.go:171","msg":"trace[176074490] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"261.382041ms","start":"2026-07-27T19:57:04.33565Z","end":"2026-07-27T19:57:04.597032Z","steps":["trace[176074490] 'read index received' (duration: 260.599317ms)","trace[176074490] 'applied index is now lower than readState.Index' (duration: 781.864µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:57:04.597169Z","caller":"traceutil/trace.go:171","msg":"trace[1992824742] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"283.910511ms","start":"2026-07-27T19:57:04.313175Z","end":"2026-07-27T19:57:04.597085Z","steps":["trace[1992824742] 'process raft request' (duration: 283.322196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:57:04.597329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.657173ms","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-27T19:57:04.597397Z","caller":"traceutil/trace.go:171","msg":"trace[1990951700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"261.81414ms","start":"2026-07-27T19:57:04.33557Z","end":"2026-07-27T19:57:04.597384Z","steps":["trace[1990951700] 'agreement among raft nodes before linearized reading' (duration: 261.563409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:57:05.260675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.304107ms","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-27T19:57:05.260749Z","caller":"traceutil/trace.go:171","msg":"trace[117725806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"344.417071ms","start":"2026-07-27T19:57:04.916313Z","end":"2026-07-27T19:57:05.260731Z","steps":["trace[117725806] 'range keys from in-memory index tree' (duration: 344.088088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:57:05.260795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:57:04.916297Z","time spent":"344.486475ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-27T19:57:05.260847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.974243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5pjj2\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-27T19:57:05.260938Z","caller":"traceutil/trace.go:171","msg":"trace[258735604] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5pjj2; range_end:; response_count:1; response_revision:633; }","duration":"433.108779ms","start":"2026-07-27T19:57:04.827807Z","end":"2026-07-27T19:57:05.260916Z","steps":["trace[258735604] 'range keys from in-memory index tree' (duration: 432.795566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:57:05.261001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:57:04.827789Z","time spent":"433.201324ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-5pjj2\" "} {"level":"info","ts":"2026-07-27T19:57:05.281642Z","caller":"traceutil/trace.go:171","msg":"trace[689095011] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"173.270134ms","start":"2026-07-27T19:57:05.108343Z","end":"2026-07-27T19:57:05.281613Z","steps":["trace[689095011] 'process raft request' (duration: 172.645398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:57:19.68522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.167564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-27T19:57:19.685279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.7373ms","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-27T19:57:19.685309Z","caller":"traceutil/trace.go:171","msg":"trace[1760894323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"182.306669ms","start":"2026-07-27T19:57:19.502985Z","end":"2026-07-27T19:57:19.685292Z","steps":["trace[1760894323] 'range keys from in-memory index tree' (duration: 181.985558ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:57:19.685363Z","caller":"traceutil/trace.go:171","msg":"trace[988866022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"330.844274ms","start":"2026-07-27T19:57:19.354499Z","end":"2026-07-27T19:57:19.685344Z","steps":["trace[988866022] 'range keys from in-memory index tree' (duration: 330.518042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:57:19.685421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:57:19.354477Z","time spent":"330.934298ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-27T19:57:50.947833Z","caller":"traceutil/trace.go:171","msg":"trace[793861158] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"217.759517ms","start":"2026-07-27T19:57:50.730046Z","end":"2026-07-27T19:57:50.947806Z","steps":["trace[793861158] 'process raft request' (duration: 217.352766ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:06.134549Z","caller":"traceutil/trace.go:171","msg":"trace[530346744] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"145.116117ms","start":"2026-07-27T19:58:05.989407Z","end":"2026-07-27T19:58:06.134523Z","steps":["trace[530346744] 'read index received' (duration: 140.142089ms)","trace[530346744] 'applied index is now lower than readState.Index' (duration: 4.972918ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:06.134709Z","caller":"traceutil/trace.go:171","msg":"trace[1947657955] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"199.794505ms","start":"2026-07-27T19:58:05.934885Z","end":"2026-07-27T19:58:06.13468Z","steps":["trace[1947657955] 'process raft request' (duration: 194.729065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:06.135103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.5105ms","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-27T19:58:06.135185Z","caller":"traceutil/trace.go:171","msg":"trace[972823825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1339; }","duration":"141.632913ms","start":"2026-07-27T19:58:05.99353Z","end":"2026-07-27T19:58:06.135163Z","steps":["trace[972823825] 'agreement among raft nodes before linearized reading' (duration: 141.404408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:06.135331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.772053ms","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-27T19:58:06.13606Z","caller":"traceutil/trace.go:171","msg":"trace[1326231066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"146.041259ms","start":"2026-07-27T19:58:05.989359Z","end":"2026-07-27T19:58:06.135401Z","steps":["trace[1326231066] 'agreement among raft nodes before linearized reading' (duration: 145.468366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:10.266263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.836123ms","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-27T19:58:10.26639Z","caller":"traceutil/trace.go:171","msg":"trace[1988255808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1447; }","duration":"107.965565ms","start":"2026-07-27T19:58:10.158373Z","end":"2026-07-27T19:58:10.266338Z","steps":["trace[1988255808] 'range keys from in-memory index tree' (duration: 107.676399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:10.266375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.527931ms","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-27T19:58:10.266511Z","caller":"traceutil/trace.go:171","msg":"trace[115094457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1447; }","duration":"109.659844ms","start":"2026-07-27T19:58:10.156791Z","end":"2026-07-27T19:58:10.266451Z","steps":["trace[115094457] 'range keys from in-memory index tree' (duration: 109.359868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:10.266532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.575315ms","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-27T19:58:10.266621Z","caller":"traceutil/trace.go:171","msg":"trace[358485913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1447; }","duration":"110.685898ms","start":"2026-07-27T19:58:10.155906Z","end":"2026-07-27T19:58:10.266592Z","steps":["trace[358485913] 'range keys from in-memory index tree' (duration: 110.456372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:12.601131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.189455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T19:58:12.601334Z","caller":"traceutil/trace.go:171","msg":"trace[531216883] linearizableReadLoop","detail":"{readStateIndex:1504; appliedIndex:1502; }","duration":"245.440376ms","start":"2026-07-27T19:58:12.355869Z","end":"2026-07-27T19:58:12.601309Z","steps":["trace[531216883] 'read index received' (duration: 98.559025ms)","trace[531216883] 'applied index is now lower than readState.Index' (duration: 146.880391ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:12.601344Z","caller":"traceutil/trace.go:171","msg":"trace[1245461217] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"317.375049ms","start":"2026-07-27T19:58:12.283936Z","end":"2026-07-27T19:58:12.601311Z","steps":["trace[1245461217] 'process raft request' (duration: 170.393205ms)","trace[1245461217] 'compare' (duration: 146.042902ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:12.60142Z","caller":"traceutil/trace.go:171","msg":"trace[1390214456] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"247.268309ms","start":"2026-07-27T19:58:12.354142Z","end":"2026-07-27T19:58:12.60141Z","steps":["trace[1390214456] 'process raft request' (duration: 247.110115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:12.6015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:12.283909Z","time spent":"317.513251ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T19:58:12.601618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.739203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T19:58:12.60167Z","caller":"traceutil/trace.go:171","msg":"trace[1541264901] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1461; }","duration":"245.859436ms","start":"2026-07-27T19:58:12.355797Z","end":"2026-07-27T19:58:12.601656Z","steps":["trace[1541264901] 'agreement among raft nodes before linearized reading' (duration: 245.768714ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:14.401406Z","caller":"traceutil/trace.go:171","msg":"trace[1488676357] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"192.536594ms","start":"2026-07-27T19:58:14.208842Z","end":"2026-07-27T19:58:14.401379Z","steps":["trace[1488676357] 'process raft request' (duration: 192.35702ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:14.567364Z","caller":"traceutil/trace.go:171","msg":"trace[1201150992] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"158.845081ms","start":"2026-07-27T19:58:14.408484Z","end":"2026-07-27T19:58:14.567329Z","steps":["trace[1201150992] 'process raft request' (duration: 132.751498ms)","trace[1201150992] 'compare' (duration: 25.430068ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:14.711025Z","caller":"traceutil/trace.go:171","msg":"trace[531317388] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1514; }","duration":"106.168603ms","start":"2026-07-27T19:58:14.604833Z","end":"2026-07-27T19:58:14.711002Z","steps":["trace[531317388] 'read index received' (duration: 105.873346ms)","trace[531317388] 'applied index is now lower than readState.Index' (duration: 294.237µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:14.711194Z","caller":"traceutil/trace.go:171","msg":"trace[1866647860] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"106.49372ms","start":"2026-07-27T19:58:14.604637Z","end":"2026-07-27T19:58:14.711131Z","steps":["trace[1866647860] 'process raft request' (duration: 106.149052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:14.711364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.58804ms","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-27T19:58:14.711404Z","caller":"traceutil/trace.go:171","msg":"trace[214044804] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1472; }","duration":"100.008936ms","start":"2026-07-27T19:58:14.611375Z","end":"2026-07-27T19:58:14.711384Z","steps":["trace[214044804] 'agreement among raft nodes before linearized reading' (duration: 99.909833ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:14.711443Z","caller":"traceutil/trace.go:171","msg":"trace[757569945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1472; }","duration":"105.685512ms","start":"2026-07-27T19:58:14.605735Z","end":"2026-07-27T19:58:14.711421Z","steps":["trace[757569945] 'agreement among raft nodes before linearized reading' (duration: 105.455967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:14.711434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.567802ms","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-27T19:58:14.711555Z","caller":"traceutil/trace.go:171","msg":"trace[1983448875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1472; }","duration":"106.693425ms","start":"2026-07-27T19:58:14.604801Z","end":"2026-07-27T19:58:14.711494Z","steps":["trace[1983448875] 'agreement among raft nodes before linearized reading' (duration: 106.403669ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:27.811839Z","caller":"traceutil/trace.go:171","msg":"trace[1433418252] linearizableReadLoop","detail":"{readStateIndex:1797; appliedIndex:1796; }","duration":"123.595657ms","start":"2026-07-27T19:58:27.688222Z","end":"2026-07-27T19:58:27.811817Z","steps":["trace[1433418252] 'read index received' (duration: 123.25914ms)","trace[1433418252] 'applied index is now lower than readState.Index' (duration: 335.477µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:27.811949Z","caller":"traceutil/trace.go:171","msg":"trace[355145731] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"129.777124ms","start":"2026-07-27T19:58:27.682148Z","end":"2026-07-27T19:58:27.811925Z","steps":["trace[355145731] 'process raft request' (duration: 129.398376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:27.812047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.76476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T19:58:27.812163Z","caller":"traceutil/trace.go:171","msg":"trace[212767499] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:1730; }","duration":"123.891923ms","start":"2026-07-27T19:58:27.688191Z","end":"2026-07-27T19:58:27.812083Z","steps":["trace[212767499] 'agreement among raft nodes before linearized reading' (duration: 123.74041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:27.816551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.15497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T19:58:27.816653Z","caller":"traceutil/trace.go:171","msg":"trace[1934650907] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:1731; }","duration":"128.340884ms","start":"2026-07-27T19:58:27.688292Z","end":"2026-07-27T19:58:27.816633Z","steps":["trace[1934650907] 'agreement among raft nodes before linearized reading' (duration: 128.16902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:27.81737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.115119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T19:58:27.81744Z","caller":"traceutil/trace.go:171","msg":"trace[172827019] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1731; }","duration":"105.1654ms","start":"2026-07-27T19:58:27.712236Z","end":"2026-07-27T19:58:27.817402Z","steps":["trace[172827019] 'agreement among raft nodes before linearized reading' (duration: 105.068798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:27.817591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.931286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T19:58:27.817773Z","caller":"traceutil/trace.go:171","msg":"trace[907724317] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:1731; }","duration":"129.13494ms","start":"2026-07-27T19:58:27.688624Z","end":"2026-07-27T19:58:27.817759Z","steps":["trace[907724317] 'agreement among raft nodes before linearized reading' (duration: 128.924166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:27.818238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.724982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T19:58:27.818333Z","caller":"traceutil/trace.go:171","msg":"trace[1574474577] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1731; }","duration":"106.834424ms","start":"2026-07-27T19:58:27.711476Z","end":"2026-07-27T19:58:27.818311Z","steps":["trace[1574474577] 'agreement among raft nodes before linearized reading' (duration: 106.704252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:27.81866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.264003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T19:58:27.818693Z","caller":"traceutil/trace.go:171","msg":"trace[2106021617] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1731; }","duration":"107.320264ms","start":"2026-07-27T19:58:27.711364Z","end":"2026-07-27T19:58:27.818684Z","steps":["trace[2106021617] 'agreement among raft nodes before linearized reading' (duration: 107.263073ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:37.64427Z","caller":"traceutil/trace.go:171","msg":"trace[1624658072] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"445.996454ms","start":"2026-07-27T19:58:37.198246Z","end":"2026-07-27T19:58:37.644243Z","steps":["trace[1624658072] 'read index received' (duration: 445.972854ms)","trace[1624658072] 'applied index is now lower than readState.Index' (duration: 22.46µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:37.644316Z","caller":"traceutil/trace.go:171","msg":"trace[1839080493] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"452.755454ms","start":"2026-07-27T19:58:37.19153Z","end":"2026-07-27T19:58:37.644285Z","steps":["trace[1839080493] 'process raft request' (duration: 452.53303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:37.644491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:37.191509Z","time spent":"452.883957ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T19:58:37.698829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.557113ms","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-27T19:58:37.698926Z","caller":"traceutil/trace.go:171","msg":"trace[207626055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1880; }","duration":"500.675795ms","start":"2026-07-27T19:58:37.198228Z","end":"2026-07-27T19:58:37.698904Z","steps":["trace[207626055] 'agreement among raft nodes before linearized reading' (duration: 446.139787ms)","trace[207626055] 'range keys from in-memory index tree' (duration: 54.291814ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T19:58:37.698987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:37.198188Z","time spent":"500.783197ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-27T19:58:37.706432Z","caller":"traceutil/trace.go:171","msg":"trace[903616042] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"426.129133ms","start":"2026-07-27T19:58:37.280285Z","end":"2026-07-27T19:58:37.706414Z","steps":["trace[903616042] 'process raft request' (duration: 426.01708ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:37.706492Z","caller":"traceutil/trace.go:171","msg":"trace[348086067] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"474.471022ms","start":"2026-07-27T19:58:37.231998Z","end":"2026-07-27T19:58:37.706469Z","steps":["trace[348086067] 'process raft request' (duration: 474.158966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:37.706553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:37.280265Z","time spent":"426.215954ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T19:58:37.706637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:37.231978Z","time spent":"474.575334ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T19:58:37.706822Z","caller":"traceutil/trace.go:171","msg":"trace[2060036253] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"115.47844ms","start":"2026-07-27T19:58:37.59129Z","end":"2026-07-27T19:58:37.706768Z","steps":["trace[2060036253] 'process raft request' (duration: 115.087733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:37.707354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.140032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e69ede24-a06f-4b7b-946c-fda13eb6b12e\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-27T19:58:37.707476Z","caller":"traceutil/trace.go:171","msg":"trace[2103519421] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e69ede24-a06f-4b7b-946c-fda13eb6b12e; range_end:; response_count:1; response_revision:1883; }","duration":"388.280145ms","start":"2026-07-27T19:58:37.319178Z","end":"2026-07-27T19:58:37.707458Z","steps":["trace[2103519421] 'agreement among raft nodes before linearized reading' (duration: 387.488139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:37.708173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:37.319148Z","time spent":"388.957958ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e69ede24-a06f-4b7b-946c-fda13eb6b12e\" "} {"level":"info","ts":"2026-07-27T19:58:38.258759Z","caller":"traceutil/trace.go:171","msg":"trace[1553462892] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"542.228256ms","start":"2026-07-27T19:58:37.716507Z","end":"2026-07-27T19:58:38.258735Z","steps":["trace[1553462892] 'process raft request' (duration: 464.793336ms)","trace[1553462892] 'compare' (duration: 76.975122ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T19:58:38.258926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:37.716481Z","time spent":"542.370308ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T19:58:38.259779Z","caller":"traceutil/trace.go:171","msg":"trace[353146582] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1953; }","duration":"397.070225ms","start":"2026-07-27T19:58:37.862681Z","end":"2026-07-27T19:58:38.259752Z","steps":["trace[353146582] 'read index received' (duration: 318.805219ms)","trace[353146582] 'applied index is now lower than readState.Index' (duration: 78.263216ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:38.259975Z","caller":"traceutil/trace.go:171","msg":"trace[802948716] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"409.822771ms","start":"2026-07-27T19:58:37.85014Z","end":"2026-07-27T19:58:38.259963Z","steps":["trace[802948716] 'process raft request' (duration: 408.553616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:38.260254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:37.850108Z","time spent":"409.910272ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T19:58:38.260411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.718498ms","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-27T19:58:38.260488Z","caller":"traceutil/trace.go:171","msg":"trace[215637394] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1886; }","duration":"397.844219ms","start":"2026-07-27T19:58:37.862625Z","end":"2026-07-27T19:58:38.26047Z","steps":["trace[215637394] 'agreement among raft nodes before linearized reading' (duration: 397.639495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:38.260531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T19:58:37.862602Z","time spent":"397.920671ms","remote":"127.0.0.1:50714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-27T19:58:49.822184Z","caller":"traceutil/trace.go:171","msg":"trace[1947104852] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"120.525779ms","start":"2026-07-27T19:58:49.701589Z","end":"2026-07-27T19:58:49.822115Z","steps":["trace[1947104852] 'process raft request' (duration: 120.343045ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:49.970663Z","caller":"traceutil/trace.go:171","msg":"trace[1712016541] linearizableReadLoop","detail":"{readStateIndex:2057; appliedIndex:2055; }","duration":"205.588449ms","start":"2026-07-27T19:58:49.765052Z","end":"2026-07-27T19:58:49.97064Z","steps":["trace[1712016541] 'read index received' (duration: 57.113628ms)","trace[1712016541] 'applied index is now lower than readState.Index' (duration: 148.473771ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:49.970706Z","caller":"traceutil/trace.go:171","msg":"trace[457090682] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"228.283957ms","start":"2026-07-27T19:58:49.742385Z","end":"2026-07-27T19:58:49.970669Z","steps":["trace[457090682] 'process raft request' (duration: 193.044243ms)","trace[457090682] 'compare' (duration: 35.067771ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:58:49.970734Z","caller":"traceutil/trace.go:171","msg":"trace[784632318] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"220.441126ms","start":"2026-07-27T19:58:49.75025Z","end":"2026-07-27T19:58:49.970692Z","steps":["trace[784632318] 'process raft request' (duration: 220.340954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:58:49.971495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.425474ms","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-27T19:58:49.971592Z","caller":"traceutil/trace.go:171","msg":"trace[1584461110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1985; }","duration":"206.568787ms","start":"2026-07-27T19:58:49.765006Z","end":"2026-07-27T19:58:49.971575Z","steps":["trace[1584461110] 'agreement among raft nodes before linearized reading' (duration: 205.730592ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:54.096009Z","caller":"traceutil/trace.go:171","msg":"trace[1468820364] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"258.393108ms","start":"2026-07-27T19:58:53.83753Z","end":"2026-07-27T19:58:54.095923Z","steps":["trace[1468820364] 'process raft request' (duration: 258.157894ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:54.129692Z","caller":"traceutil/trace.go:171","msg":"trace[1861330440] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"146.557093ms","start":"2026-07-27T19:58:53.983113Z","end":"2026-07-27T19:58:54.12967Z","steps":["trace[1861330440] 'process raft request' (duration: 146.479662ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:54.129875Z","caller":"traceutil/trace.go:171","msg":"trace[1703250808] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"147.077642ms","start":"2026-07-27T19:58:53.982785Z","end":"2026-07-27T19:58:54.129863Z","steps":["trace[1703250808] 'process raft request' (duration: 145.950582ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:58:54.130043Z","caller":"traceutil/trace.go:171","msg":"trace[1663535536] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2086; }","duration":"137.655006ms","start":"2026-07-27T19:58:53.992352Z","end":"2026-07-27T19:58:54.130007Z","steps":["trace[1663535536] 'read index received' (duration: 103.238672ms)","trace[1663535536] 'applied index is now lower than readState.Index' (duration: 34.413074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T19:58:54.130289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.906ms","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-27T19:58:54.130313Z","caller":"traceutil/trace.go:171","msg":"trace[1804691733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2017; }","duration":"137.993122ms","start":"2026-07-27T19:58:53.992312Z","end":"2026-07-27T19:58:54.130305Z","steps":["trace[1804691733] 'agreement among raft nodes before linearized reading' (duration: 137.801909ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:59:16.439567Z","caller":"traceutil/trace.go:171","msg":"trace[700715837] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"242.723237ms","start":"2026-07-27T19:59:16.196816Z","end":"2026-07-27T19:59:16.439539Z","steps":["trace[700715837] 'process raft request' (duration: 242.503504ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:59:16.441429Z","caller":"traceutil/trace.go:171","msg":"trace[1570917594] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2217; }","duration":"238.372398ms","start":"2026-07-27T19:59:16.203036Z","end":"2026-07-27T19:59:16.441408Z","steps":["trace[1570917594] 'read index received' (duration: 236.286925ms)","trace[1570917594] 'applied index is now lower than readState.Index' (duration: 2.084863ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:59:16.441471Z","caller":"traceutil/trace.go:171","msg":"trace[1745241081] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"242.579695ms","start":"2026-07-27T19:59:16.198875Z","end":"2026-07-27T19:59:16.441455Z","steps":["trace[1745241081] 'process raft request' (duration: 242.400672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:59:16.44164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.593421ms","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-27T19:59:16.441685Z","caller":"traceutil/trace.go:171","msg":"trace[1688284605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2142; }","duration":"238.703903ms","start":"2026-07-27T19:59:16.20297Z","end":"2026-07-27T19:59:16.441674Z","steps":["trace[1688284605] 'agreement among raft nodes before linearized reading' (duration: 238.553351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:59:16.441757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.430073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-27T19:59:16.441778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.668976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T19:59:16.441877Z","caller":"traceutil/trace.go:171","msg":"trace[1552818488] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2142; }","duration":"166.832429ms","start":"2026-07-27T19:59:16.275022Z","end":"2026-07-27T19:59:16.441854Z","steps":["trace[1552818488] 'agreement among raft nodes before linearized reading' (duration: 166.614446ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:59:16.441874Z","caller":"traceutil/trace.go:171","msg":"trace[475581620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2142; }","duration":"205.581105ms","start":"2026-07-27T19:59:16.23625Z","end":"2026-07-27T19:59:16.441831Z","steps":["trace[475581620] 'agreement among raft nodes before linearized reading' (duration: 205.329411ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:59:16.569754Z","caller":"traceutil/trace.go:171","msg":"trace[787336017] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"121.532687ms","start":"2026-07-27T19:59:16.448196Z","end":"2026-07-27T19:59:16.569729Z","steps":["trace[787336017] 'process raft request' (duration: 121.414435ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:59:16.569775Z","caller":"traceutil/trace.go:171","msg":"trace[1404557574] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"122.369161ms","start":"2026-07-27T19:59:16.447313Z","end":"2026-07-27T19:59:16.569682Z","steps":["trace[1404557574] 'process raft request' (duration: 74.212133ms)","trace[1404557574] 'compare' (duration: 47.923404ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:59:16.741138Z","caller":"traceutil/trace.go:171","msg":"trace[653617180] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"109.472584ms","start":"2026-07-27T19:59:16.631607Z","end":"2026-07-27T19:59:16.74108Z","steps":["trace[653617180] 'process raft request' (duration: 102.089037ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T19:59:17.299489Z","caller":"traceutil/trace.go:171","msg":"trace[1145694863] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"152.805896ms","start":"2026-07-27T19:59:17.146642Z","end":"2026-07-27T19:59:17.299448Z","steps":["trace[1145694863] 'process raft request' (duration: 137.883039ms)","trace[1145694863] 'compare' (duration: 14.549581ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T19:59:17.299474Z","caller":"traceutil/trace.go:171","msg":"trace[1641624605] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2226; }","duration":"117.068809ms","start":"2026-07-27T19:59:17.18231Z","end":"2026-07-27T19:59:17.299378Z","steps":["trace[1641624605] 'read index received' (duration: 102.159852ms)","trace[1641624605] 'applied index is now lower than readState.Index' (duration: 14.906967ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T19:59:17.299673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.332833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T19:59:17.299778Z","caller":"traceutil/trace.go:171","msg":"trace[862353474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2149; }","duration":"117.511586ms","start":"2026-07-27T19:59:17.182242Z","end":"2026-07-27T19:59:17.299753Z","steps":["trace[862353474] 'agreement among raft nodes before linearized reading' (duration: 117.305863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T19:59:17.809172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.345675ms","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-27T19:59:17.809294Z","caller":"traceutil/trace.go:171","msg":"trace[2073253663] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2149; }","duration":"228.535688ms","start":"2026-07-27T19:59:17.580728Z","end":"2026-07-27T19:59:17.809263Z","steps":["trace[2073253663] 'range keys from in-memory index tree' (duration: 228.083561ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:00:14.738409Z","caller":"traceutil/trace.go:171","msg":"trace[1319730119] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"102.544556ms","start":"2026-07-27T20:00:14.635841Z","end":"2026-07-27T20:00:14.738386Z","steps":["trace[1319730119] 'process raft request' (duration: 102.212372ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:00:45.160924Z","caller":"traceutil/trace.go:171","msg":"trace[1717789302] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"143.930561ms","start":"2026-07-27T20:00:45.01696Z","end":"2026-07-27T20:00:45.16089Z","steps":["trace[1717789302] 'process raft request' (duration: 143.730983ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:00:45.167827Z","caller":"traceutil/trace.go:171","msg":"trace[1036571934] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"149.338489ms","start":"2026-07-27T20:00:45.018463Z","end":"2026-07-27T20:00:45.167801Z","steps":["trace[1036571934] 'process raft request' (duration: 149.073748ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:00:45.16798Z","caller":"traceutil/trace.go:171","msg":"trace[1561017543] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"133.156087ms","start":"2026-07-27T20:00:45.034799Z","end":"2026-07-27T20:00:45.167955Z","steps":["trace[1561017543] 'process raft request' (duration: 132.957998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:00:45.447483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.245618ms","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-27T20:00:45.447687Z","caller":"traceutil/trace.go:171","msg":"trace[1346368997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2732; }","duration":"228.483068ms","start":"2026-07-27T20:00:45.219176Z","end":"2026-07-27T20:00:45.447659Z","steps":["trace[1346368997] 'range keys from in-memory index tree' (duration: 228.113152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:00:45.715863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.447212ms","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-27T20:00:45.716047Z","caller":"traceutil/trace.go:171","msg":"trace[612425644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2733; }","duration":"107.677362ms","start":"2026-07-27T20:00:45.608351Z","end":"2026-07-27T20:00:45.716028Z","steps":["trace[612425644] 'range keys from in-memory index tree' (duration: 107.315507ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:00:51.132294Z","caller":"traceutil/trace.go:171","msg":"trace[147299185] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"242.442732ms","start":"2026-07-27T20:00:50.889815Z","end":"2026-07-27T20:00:51.132258Z","steps":["trace[147299185] 'process raft request' (duration: 241.044985ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:04:34.516295Z","caller":"traceutil/trace.go:171","msg":"trace[2116797932] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"123.319403ms","start":"2026-07-27T20:04:34.392944Z","end":"2026-07-27T20:04:34.516263Z","steps":["trace[2116797932] 'process raft request' (duration: 122.897645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:04:34.516293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.491346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:04:34.51665Z","caller":"traceutil/trace.go:171","msg":"trace[1965144217] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:4625; }","duration":"122.866463ms","start":"2026-07-27T20:04:34.393745Z","end":"2026-07-27T20:04:34.516612Z","steps":["trace[1965144217] 'agreement among raft nodes before linearized reading' (duration: 122.436035ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:04:34.517101Z","caller":"traceutil/trace.go:171","msg":"trace[483670240] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"121.559308ms","start":"2026-07-27T20:04:34.395526Z","end":"2026-07-27T20:04:34.517085Z","steps":["trace[483670240] 'process raft request' (duration: 120.471686ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:04:34.786079Z","caller":"traceutil/trace.go:171","msg":"trace[863388073] linearizableReadLoop","detail":"{readStateIndex:4805; appliedIndex:4803; }","duration":"113.806283ms","start":"2026-07-27T20:04:34.67224Z","end":"2026-07-27T20:04:34.786047Z","steps":["trace[863388073] 'read index received' (duration: 7.440649ms)","trace[863388073] 'applied index is now lower than readState.Index' (duration: 106.364574ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:04:34.786107Z","caller":"traceutil/trace.go:171","msg":"trace[2044700571] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"113.899574ms","start":"2026-07-27T20:04:34.672171Z","end":"2026-07-27T20:04:34.786071Z","steps":["trace[2044700571] 'process raft request' (duration: 105.818203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:04:34.786511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.224231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-4jh5h-tls-wbx58\" ","response":"range_response_count:1 size:2629"} {"level":"info","ts":"2026-07-27T20:04:34.78663Z","caller":"traceutil/trace.go:171","msg":"trace[340239319] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-4jh5h-tls-wbx58; range_end:; response_count:1; response_revision:4633; }","duration":"114.401035ms","start":"2026-07-27T20:04:34.672203Z","end":"2026-07-27T20:04:34.786604Z","steps":["trace[340239319] 'agreement among raft nodes before linearized reading' (duration: 113.978737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:04:34.788475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.854464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-27T20:04:34.78854Z","caller":"traceutil/trace.go:171","msg":"trace[1345259654] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4634; }","duration":"113.935965ms","start":"2026-07-27T20:04:34.674587Z","end":"2026-07-27T20:04:34.788523Z","steps":["trace[1345259654] 'agreement among raft nodes before linearized reading' (duration: 113.715071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:04:34.788743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.035807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-27T20:04:34.788867Z","caller":"traceutil/trace.go:171","msg":"trace[1195177516] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4634; }","duration":"113.16877ms","start":"2026-07-27T20:04:34.675669Z","end":"2026-07-27T20:04:34.788838Z","steps":["trace[1195177516] 'agreement among raft nodes before linearized reading' (duration: 112.833583ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:04:40.391314Z","caller":"traceutil/trace.go:171","msg":"trace[76697322] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"168.587833ms","start":"2026-07-27T20:04:40.222677Z","end":"2026-07-27T20:04:40.391265Z","steps":["trace[76697322] 'process raft request' (duration: 167.641544ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:04:40.391326Z","caller":"traceutil/trace.go:171","msg":"trace[141583680] linearizableReadLoop","detail":"{readStateIndex:4903; appliedIndex:4902; }","duration":"120.886809ms","start":"2026-07-27T20:04:40.270255Z","end":"2026-07-27T20:04:40.391142Z","steps":["trace[141583680] 'read index received' (duration: 120.093914ms)","trace[141583680] 'applied index is now lower than readState.Index' (duration: 791.275µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:04:40.391893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.595213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:04:40.391948Z","caller":"traceutil/trace.go:171","msg":"trace[58200324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4730; }","duration":"121.713176ms","start":"2026-07-27T20:04:40.27022Z","end":"2026-07-27T20:04:40.391933Z","steps":["trace[58200324] 'agreement among raft nodes before linearized reading' (duration: 121.180555ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:04:40.532781Z","caller":"traceutil/trace.go:171","msg":"trace[1503184237] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"255.529081ms","start":"2026-07-27T20:04:40.27721Z","end":"2026-07-27T20:04:40.53274Z","steps":["trace[1503184237] 'process raft request' (duration: 255.339088ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:04:41.155007Z","caller":"traceutil/trace.go:171","msg":"trace[2068354794] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"153.474939ms","start":"2026-07-27T20:04:41.001497Z","end":"2026-07-27T20:04:41.154972Z","steps":["trace[2068354794] 'process raft request' (duration: 105.599575ms)","trace[2068354794] 'compare' (duration: 47.733602ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:04:54.754646Z","caller":"traceutil/trace.go:171","msg":"trace[593894450] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"133.558981ms","start":"2026-07-27T20:04:54.621044Z","end":"2026-07-27T20:04:54.754603Z","steps":["trace[593894450] 'process raft request' (duration: 133.265316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:04:56.477159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.346603ms","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-27T20:04:56.477262Z","caller":"traceutil/trace.go:171","msg":"trace[2110031051] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4868; }","duration":"114.517496ms","start":"2026-07-27T20:04:56.362722Z","end":"2026-07-27T20:04:56.47724Z","steps":["trace[2110031051] 'range keys from in-memory index tree' (duration: 114.1368ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:21.52126Z","caller":"traceutil/trace.go:171","msg":"trace[1419789] linearizableReadLoop","detail":"{readStateIndex:5281; appliedIndex:5280; }","duration":"164.036633ms","start":"2026-07-27T20:05:21.357195Z","end":"2026-07-27T20:05:21.521232Z","steps":["trace[1419789] 'read index received' (duration: 163.848119ms)","trace[1419789] 'applied index is now lower than readState.Index' (duration: 187.704µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:05:21.521349Z","caller":"traceutil/trace.go:171","msg":"trace[783619333] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"198.820649ms","start":"2026-07-27T20:05:21.322513Z","end":"2026-07-27T20:05:21.521333Z","steps":["trace[783619333] 'process raft request' (duration: 198.561224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:05:21.521577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.336798ms","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-27T20:05:21.521648Z","caller":"traceutil/trace.go:171","msg":"trace[1848877875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5096; }","duration":"164.513681ms","start":"2026-07-27T20:05:21.357123Z","end":"2026-07-27T20:05:21.521637Z","steps":["trace[1848877875] 'agreement among raft nodes before linearized reading' (duration: 164.240456ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:43.814895Z","caller":"traceutil/trace.go:171","msg":"trace[144938901] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"118.055199ms","start":"2026-07-27T20:05:43.696809Z","end":"2026-07-27T20:05:43.814864Z","steps":["trace[144938901] 'process raft request' (duration: 118.006509ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:43.815051Z","caller":"traceutil/trace.go:171","msg":"trace[83142667] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"119.480825ms","start":"2026-07-27T20:05:43.695473Z","end":"2026-07-27T20:05:43.814954Z","steps":["trace[83142667] 'process raft request' (duration: 75.952406ms)","trace[83142667] 'compare' (duration: 43.222654ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:06:22.869692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2926} {"level":"info","ts":"2026-07-27T20:06:22.954974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2926,"took":"83.046329ms","hash":2861304108,"current-db-size-bytes":54288384,"current-db-size":"54 MB","current-db-size-in-use-bytes":29265920,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-27T20:06:22.955085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2861304108,"revision":2926,"compact-revision":-1} {"level":"info","ts":"2026-07-27T20:11:22.87861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5598} {"level":"info","ts":"2026-07-27T20:11:22.989756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5598,"took":"108.304175ms","hash":266500409,"current-db-size-bytes":54288384,"current-db-size":"54 MB","current-db-size-in-use-bytes":18452480,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-27T20:11:22.989818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":266500409,"revision":5598,"compact-revision":2926}