{"level":"warn","ts":"2026-10-15T14:13:41.0534Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:13:41.05355Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.95: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://162.253.55.95:2380","--initial-cluster=instance=https://162.253.55.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.95: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-10-15T14:13:41.053662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:13:41.053693Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.95:2380"]} {"level":"info","ts":"2026-10-15T14:13:41.05373Z","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-10-15T14:13:41.055052Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"]} {"level":"info","ts":"2026-10-15T14:13:41.05522Z","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":16,"max-cpu-available":16,"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://162.253.55.95:2380"],"listen-peer-urls":["https://162.253.55.95:2380"],"advertise-client-urls":["https://162.253.55.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.95: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-10-15T14:13:41.06016Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.512651ms"} {"level":"info","ts":"2026-10-15T14:13:41.067417Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"282167b6747c9cda","cluster-id":"dc65cfc6e389e361"} {"level":"info","ts":"2026-10-15T14:13:41.06753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:13:41.067559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became follower at term 0"} {"level":"info","ts":"2026-10-15T14:13:41.067576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 282167b6747c9cda [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:13:41.067602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became follower at term 1"} {"level":"info","ts":"2026-10-15T14:13:41.067655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=(2891706469084601562)"} {"level":"warn","ts":"2026-10-15T14:13:41.075033Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:13:41.076919Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:13:41.078917Z","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-10-15T14:13:41.082153Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"282167b6747c9cda","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:13:41.082483Z","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-10-15T14:13:41.082451Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"282167b6747c9cda","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:13:41.082649Z","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-10-15T14:13:41.082712Z","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-10-15T14:13:41.085216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=(2891706469084601562)"} {"level":"info","ts":"2026-10-15T14:13:41.085907Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dc65cfc6e389e361","local-member-id":"282167b6747c9cda","added-peer-id":"282167b6747c9cda","added-peer-peer-urls":["https://162.253.55.95:2380"]} {"level":"info","ts":"2026-10-15T14:13:41.088523Z","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-10-15T14:13:41.088578Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.95:2380"} {"level":"info","ts":"2026-10-15T14:13:41.088616Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.95:2380"} {"level":"info","ts":"2026-10-15T14:13:41.089069Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"282167b6747c9cda","initial-advertise-peer-urls":["https://162.253.55.95:2380"],"listen-peer-urls":["https://162.253.55.95:2380"],"advertise-client-urls":["https://162.253.55.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:13:41.089148Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:13:41.869022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:13:41.869099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:13:41.869124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda received MsgPreVoteResp from 282167b6747c9cda at term 1"} {"level":"info","ts":"2026-10-15T14:13:41.869147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:13:41.869159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda received MsgVoteResp from 282167b6747c9cda at term 2"} {"level":"info","ts":"2026-10-15T14:13:41.8692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became leader at term 2"} {"level":"info","ts":"2026-10-15T14:13:41.869214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 282167b6747c9cda elected leader 282167b6747c9cda at term 2"} {"level":"info","ts":"2026-10-15T14:13:41.871393Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"282167b6747c9cda","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.95:2379]}","request-path":"/0/members/282167b6747c9cda/attributes","cluster-id":"dc65cfc6e389e361","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:13:41.871406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:13:41.871456Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:13:41.871495Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:13:41.871654Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:13:41.871892Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:13:41.872924Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dc65cfc6e389e361","local-member-id":"282167b6747c9cda","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:13:41.873102Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:13:41.873161Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:13:41.874535Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:13:41.875931Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.95:2379"} {"level":"info","ts":"2026-10-15T14:13:57.203069Z","caller":"traceutil/trace.go:171","msg":"trace[1229756127] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"177.816129ms","start":"2026-10-15T14:13:57.025227Z","end":"2026-10-15T14:13:57.203043Z","steps":["trace[1229756127] 'process raft request' (duration: 177.665335ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:02.586972Z","caller":"traceutil/trace.go:171","msg":"trace[402247108] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"123.974024ms","start":"2026-10-15T14:14:02.462972Z","end":"2026-10-15T14:14:02.586946Z","steps":["trace[402247108] 'process raft request' (duration: 123.81934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:08.077163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.975281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:14:08.077446Z","caller":"traceutil/trace.go:171","msg":"trace[360255697] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"214.52401ms","start":"2026-10-15T14:14:07.862843Z","end":"2026-10-15T14:14:08.077367Z","steps":["trace[360255697] 'read index received' (duration: 60.729759ms)","trace[360255697] 'applied index is now lower than readState.Index' (duration: 153.791041ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:14:08.077598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.734424ms","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-10-15T14:14:08.077677Z","caller":"traceutil/trace.go:171","msg":"trace[1359143669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:438; }","duration":"214.848777ms","start":"2026-10-15T14:14:07.862801Z","end":"2026-10-15T14:14:08.07765Z","steps":["trace[1359143669] 'agreement among raft nodes before linearized reading' (duration: 214.706394ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:08.077884Z","caller":"traceutil/trace.go:171","msg":"trace[393396491] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"321.708148ms","start":"2026-10-15T14:14:07.75609Z","end":"2026-10-15T14:14:08.077798Z","steps":["trace[393396491] 'process raft request' (duration: 167.6074ms)","trace[393396491] 'compare' (duration: 151.751546ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:14:08.078187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:07.756065Z","time spent":"321.967643ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:14:14.270497Z","caller":"traceutil/trace.go:171","msg":"trace[844819025] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"109.65806ms","start":"2026-10-15T14:14:14.160813Z","end":"2026-10-15T14:14:14.270471Z","steps":["trace[844819025] 'process raft request' (duration: 101.762913ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:27.958108Z","caller":"traceutil/trace.go:171","msg":"trace[43203527] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"101.523598ms","start":"2026-10-15T14:14:27.856544Z","end":"2026-10-15T14:14:27.958068Z","steps":["trace[43203527] 'process raft request' (duration: 101.301743ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:34.366674Z","caller":"traceutil/trace.go:171","msg":"trace[22266481] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"192.834157ms","start":"2026-10-15T14:14:34.17381Z","end":"2026-10-15T14:14:34.366645Z","steps":["trace[22266481] 'process raft request' (duration: 192.226953ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:34.877908Z","caller":"traceutil/trace.go:171","msg":"trace[98191477] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"134.437167ms","start":"2026-10-15T14:14:34.743444Z","end":"2026-10-15T14:14:34.877881Z","steps":["trace[98191477] 'read index received' (duration: 133.769622ms)","trace[98191477] 'applied index is now lower than readState.Index' (duration: 666.525µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:14:34.878075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.61558ms","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-10-15T14:14:34.87811Z","caller":"traceutil/trace.go:171","msg":"trace[1352855404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"134.694863ms","start":"2026-10-15T14:14:34.743403Z","end":"2026-10-15T14:14:34.878098Z","steps":["trace[1352855404] 'agreement among raft nodes before linearized reading' (duration: 134.56701ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:34.878081Z","caller":"traceutil/trace.go:171","msg":"trace[1755949389] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"404.816313ms","start":"2026-10-15T14:14:34.473249Z","end":"2026-10-15T14:14:34.878065Z","steps":["trace[1755949389] 'process raft request' (duration: 404.126127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:34.878625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:34.473216Z","time spent":"405.062959ms","remote":"127.0.0.1:40962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:14:35.453627Z","caller":"traceutil/trace.go:171","msg":"trace[1935023440] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"478.989077ms","start":"2026-10-15T14:14:34.974616Z","end":"2026-10-15T14:14:35.453605Z","steps":["trace[1935023440] 'read index received' (duration: 459.51111ms)","trace[1935023440] 'applied index is now lower than readState.Index' (duration: 19.477277ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:35.453644Z","caller":"traceutil/trace.go:171","msg":"trace[454462378] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"495.153339ms","start":"2026-10-15T14:14:34.958466Z","end":"2026-10-15T14:14:35.453619Z","steps":["trace[454462378] 'process raft request' (duration: 475.717193ms)","trace[454462378] 'compare' (duration: 19.17143ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:14:35.453795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.1561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:14:35.453754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:34.95844Z","time spent":"495.253392ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:14:35.453819Z","caller":"traceutil/trace.go:171","msg":"trace[103360659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"479.247923ms","start":"2026-10-15T14:14:34.974563Z","end":"2026-10-15T14:14:35.453811Z","steps":["trace[103360659] 'agreement among raft nodes before linearized reading' (duration: 479.15279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:35.453839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:34.97454Z","time spent":"479.294664ms","remote":"127.0.0.1:40856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T14:14:35.453836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.695788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-15T14:14:35.453883Z","caller":"traceutil/trace.go:171","msg":"trace[1152934054] range","detail":"{range_begin:/registry/masterleases/162.253.55.95; range_end:; response_count:1; response_revision:659; }","duration":"313.77548ms","start":"2026-10-15T14:14:35.140093Z","end":"2026-10-15T14:14:35.453868Z","steps":["trace[1152934054] 'agreement among raft nodes before linearized reading' (duration: 313.640727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:35.453936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:35.140072Z","time spent":"313.849412ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.95\" "} {"level":"info","ts":"2026-10-15T14:14:51.408639Z","caller":"traceutil/trace.go:171","msg":"trace[386359608] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"370.517053ms","start":"2026-10-15T14:14:51.038099Z","end":"2026-10-15T14:14:51.408616Z","steps":["trace[386359608] 'read index received' (duration: 370.328339ms)","trace[386359608] 'applied index is now lower than readState.Index' (duration: 187.884µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:14:51.408714Z","caller":"traceutil/trace.go:171","msg":"trace[1515578916] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"395.004393ms","start":"2026-10-15T14:14:51.013686Z","end":"2026-10-15T14:14:51.408691Z","steps":["trace[1515578916] 'process raft request' (duration: 394.792098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.408828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.657826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:14:51.408864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.013666Z","time spent":"395.125645ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:14:51.408866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.740948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.40895Z","caller":"traceutil/trace.go:171","msg":"trace[77054565] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:825; }","duration":"370.877851ms","start":"2026-10-15T14:14:51.038054Z","end":"2026-10-15T14:14:51.408932Z","steps":["trace[77054565] 'agreement among raft nodes before linearized reading' (duration: 370.691697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.408932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.427162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:14:51.408966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.618211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.409004Z","caller":"traceutil/trace.go:171","msg":"trace[986706756] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:825; }","duration":"321.513504ms","start":"2026-10-15T14:14:51.087479Z","end":"2026-10-15T14:14:51.408992Z","steps":["trace[986706756] 'agreement among raft nodes before linearized reading' (duration: 321.423372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.038035Z","time spent":"370.959543ms","remote":"127.0.0.1:34396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-10-15T14:14:51.409051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.087473Z","time spent":"321.564295ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"info","ts":"2026-10-15T14:14:51.408874Z","caller":"traceutil/trace.go:171","msg":"trace[1399064677] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:825; }","duration":"370.717008ms","start":"2026-10-15T14:14:51.038141Z","end":"2026-10-15T14:14:51.408858Z","steps":["trace[1399064677] 'agreement among raft nodes before linearized reading' (duration: 370.613045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.870321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:14:51.409088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.348649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.409109Z","caller":"traceutil/trace.go:171","msg":"trace[822131494] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:825; }","duration":"140.947943ms","start":"2026-10-15T14:14:51.268146Z","end":"2026-10-15T14:14:51.409094Z","steps":["trace[822131494] 'agreement among raft nodes before linearized reading' (duration: 140.8346ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.409124Z","caller":"traceutil/trace.go:171","msg":"trace[168657440] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:825; }","duration":"226.419ms","start":"2026-10-15T14:14:51.182694Z","end":"2026-10-15T14:14:51.409113Z","steps":["trace[168657440] 'agreement among raft nodes before linearized reading' (duration: 226.372419ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.409043Z","caller":"traceutil/trace.go:171","msg":"trace[1750073640] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:825; }","duration":"282.730343ms","start":"2026-10-15T14:14:51.126293Z","end":"2026-10-15T14:14:51.409023Z","steps":["trace[1750073640] 'agreement among raft nodes before linearized reading' (duration: 282.581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.038072Z","time spent":"371.032365ms","remote":"127.0.0.1:34396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:14:51.409191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.618468ms","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":"info","ts":"2026-10-15T14:14:51.40925Z","caller":"traceutil/trace.go:171","msg":"trace[1178905908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:825; }","duration":"141.689439ms","start":"2026-10-15T14:14:51.267535Z","end":"2026-10-15T14:14:51.409225Z","steps":["trace[1178905908] 'agreement among raft nodes before linearized reading' (duration: 141.631668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.951665ms","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-10-15T14:14:51.409346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.649119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:14:51.409388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.089636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.409391Z","caller":"traceutil/trace.go:171","msg":"trace[1542947371] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:825; }","duration":"179.042877ms","start":"2026-10-15T14:14:51.230323Z","end":"2026-10-15T14:14:51.409366Z","steps":["trace[1542947371] 'agreement among raft nodes before linearized reading' (duration: 178.958925ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.409419Z","caller":"traceutil/trace.go:171","msg":"trace[1659876770] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:825; }","duration":"244.148228ms","start":"2026-10-15T14:14:51.165262Z","end":"2026-10-15T14:14:51.409411Z","steps":["trace[1659876770] 'agreement among raft nodes before linearized reading' (duration: 244.099777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.058675ms","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":"warn","ts":"2026-10-15T14:14:51.40943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.766602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:14:51.409453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.777853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.409466Z","caller":"traceutil/trace.go:171","msg":"trace[1215837137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:825; }","duration":"322.829063ms","start":"2026-10-15T14:14:51.086627Z","end":"2026-10-15T14:14:51.409456Z","steps":["trace[1215837137] 'agreement among raft nodes before linearized reading' (duration: 322.778712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.577827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.40949Z","caller":"traceutil/trace.go:171","msg":"trace[1607806791] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:825; }","duration":"244.139688ms","start":"2026-10-15T14:14:51.165315Z","end":"2026-10-15T14:14:51.409455Z","steps":["trace[1607806791] 'agreement among raft nodes before linearized reading' (duration: 244.003925ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.409493Z","caller":"traceutil/trace.go:171","msg":"trace[916653629] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:825; }","duration":"351.850555ms","start":"2026-10-15T14:14:51.057631Z","end":"2026-10-15T14:14:51.409482Z","steps":["trace[916653629] 'agreement among raft nodes before linearized reading' (duration: 351.786773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.08661Z","time spent":"322.879755ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-10-15T14:14:51.409501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.610866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:14:51.409547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.057614Z","time spent":"351.918216ms","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-10-15T14:14:51.409558Z","caller":"traceutil/trace.go:171","msg":"trace[39575318] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:825; }","duration":"370.696617ms","start":"2026-10-15T14:14:51.038846Z","end":"2026-10-15T14:14:51.409543Z","steps":["trace[39575318] 'agreement among raft nodes before linearized reading' (duration: 370.612225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.216515ms","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":"warn","ts":"2026-10-15T14:14:51.409562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.313342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:14:51.409607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.038833Z","time spent":"370.758778ms","remote":"127.0.0.1:34396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-10-15T14:14:51.409569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.785953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.409633Z","caller":"traceutil/trace.go:171","msg":"trace[1786788072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:825; }","duration":"351.405845ms","start":"2026-10-15T14:14:51.058212Z","end":"2026-10-15T14:14:51.409618Z","steps":["trace[1786788072] 'agreement among raft nodes before linearized reading' (duration: 351.316413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.0582Z","time spent":"351.455416ms","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-10-15T14:14:51.4096Z","caller":"traceutil/trace.go:171","msg":"trace[115094927] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:825; }","duration":"283.283986ms","start":"2026-10-15T14:14:51.126308Z","end":"2026-10-15T14:14:51.409592Z","steps":["trace[115094927] 'agreement among raft nodes before linearized reading' (duration: 283.232355ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.409393Z","caller":"traceutil/trace.go:171","msg":"trace[1912457086] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:825; }","duration":"322.720521ms","start":"2026-10-15T14:14:51.08666Z","end":"2026-10-15T14:14:51.40938Z","steps":["trace[1912457086] 'agreement among raft nodes before linearized reading' (duration: 322.64794ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.409689Z","caller":"traceutil/trace.go:171","msg":"trace[1093167117] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:825; }","duration":"351.979017ms","start":"2026-10-15T14:14:51.057674Z","end":"2026-10-15T14:14:51.409653Z","steps":["trace[1093167117] 'agreement among raft nodes before linearized reading' (duration: 351.695821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.086642Z","time spent":"323.097739ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:14:51.409752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.146386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.409538Z","caller":"traceutil/trace.go:171","msg":"trace[2027440719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:825; }","duration":"142.024657ms","start":"2026-10-15T14:14:51.267481Z","end":"2026-10-15T14:14:51.409506Z","steps":["trace[2027440719] 'agreement among raft nodes before linearized reading' (duration: 141.474455ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.730479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.40981Z","caller":"traceutil/trace.go:171","msg":"trace[904491587] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:825; }","duration":"227.216658ms","start":"2026-10-15T14:14:51.182585Z","end":"2026-10-15T14:14:51.409801Z","steps":["trace[904491587] 'agreement among raft nodes before linearized reading' (duration: 227.144276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.409802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.473478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dv6vj\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-10-15T14:14:51.409823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:14:51.057648Z","time spent":"352.10742ms","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-10-15T14:14:51.409844Z","caller":"traceutil/trace.go:171","msg":"trace[1605176657] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:825; }","duration":"243.842051ms","start":"2026-10-15T14:14:51.165979Z","end":"2026-10-15T14:14:51.409821Z","steps":["trace[1605176657] 'agreement among raft nodes before linearized reading' (duration: 243.699398ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:14:51.409857Z","caller":"traceutil/trace.go:171","msg":"trace[1178930350] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dv6vj; range_end:; response_count:1; response_revision:825; }","duration":"159.587211ms","start":"2026-10-15T14:14:51.250251Z","end":"2026-10-15T14:14:51.409838Z","steps":["trace[1178930350] 'agreement among raft nodes before linearized reading' (duration: 159.394116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.410038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.766624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.410096Z","caller":"traceutil/trace.go:171","msg":"trace[1662601171] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:825; }","duration":"179.856465ms","start":"2026-10-15T14:14:51.230222Z","end":"2026-10-15T14:14:51.410079Z","steps":["trace[1662601171] 'agreement among raft nodes before linearized reading' (duration: 179.772223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:14:51.410097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.669926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:14:51.410174Z","caller":"traceutil/trace.go:171","msg":"trace[1451572372] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:825; }","duration":"226.762148ms","start":"2026-10-15T14:14:51.183399Z","end":"2026-10-15T14:14:51.410161Z","steps":["trace[1451572372] 'agreement among raft nodes before linearized reading' (duration: 225.419938ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:20.761359Z","caller":"traceutil/trace.go:171","msg":"trace[225558088] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"104.391274ms","start":"2026-10-15T14:15:20.656948Z","end":"2026-10-15T14:15:20.76134Z","steps":["trace[225558088] 'process raft request' (duration: 104.112868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:21.160523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.140292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:15:21.160597Z","caller":"traceutil/trace.go:171","msg":"trace[264065551] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1223; }","duration":"122.261684ms","start":"2026-10-15T14:15:21.03832Z","end":"2026-10-15T14:15:21.160582Z","steps":["trace[264065551] 'count revisions from in-memory index tree' (duration: 122.052849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:21.160631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.752508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:15:21.160706Z","caller":"traceutil/trace.go:171","msg":"trace[115538209] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1223; }","duration":"259.892281ms","start":"2026-10-15T14:15:20.900795Z","end":"2026-10-15T14:15:21.160688Z","steps":["trace[115538209] 'count revisions from in-memory index tree' (duration: 259.633755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:15:21.160746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.85585ms","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-10-15T14:15:21.160844Z","caller":"traceutil/trace.go:171","msg":"trace[1292329925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1223; }","duration":"123.958412ms","start":"2026-10-15T14:15:21.036869Z","end":"2026-10-15T14:15:21.160827Z","steps":["trace[1292329925] 'range keys from in-memory index tree' (duration: 123.625274ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:22.542257Z","caller":"traceutil/trace.go:171","msg":"trace[593506770] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"109.852287ms","start":"2026-10-15T14:15:22.432379Z","end":"2026-10-15T14:15:22.542232Z","steps":["trace[593506770] 'process raft request' (duration: 109.708633ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:15:40.771397Z","caller":"traceutil/trace.go:171","msg":"trace[2108714126] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"152.644233ms","start":"2026-10-15T14:15:40.618727Z","end":"2026-10-15T14:15:40.771371Z","steps":["trace[2108714126] 'process raft request' (duration: 152.48709ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:05.98057Z","caller":"traceutil/trace.go:171","msg":"trace[733359803] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"101.237664ms","start":"2026-10-15T14:16:05.879295Z","end":"2026-10-15T14:16:05.980533Z","steps":["trace[733359803] 'process raft request' (duration: 100.971878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:06.593368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.779361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T14:16:06.59353Z","caller":"traceutil/trace.go:171","msg":"trace[439896284] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"328.978646ms","start":"2026-10-15T14:16:06.264496Z","end":"2026-10-15T14:16:06.593475Z","steps":["trace[439896284] 'range keys from in-memory index tree' (duration: 328.570447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:06.593602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:06.264459Z","time spent":"329.124399ms","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-15T14:16:06.593618Z","caller":"traceutil/trace.go:171","msg":"trace[1368052921] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"169.790086ms","start":"2026-10-15T14:16:06.423798Z","end":"2026-10-15T14:16:06.593588Z","steps":["trace[1368052921] 'process raft request' (duration: 145.498753ms)","trace[1368052921] 'compare' (duration: 23.900755ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:06.627089Z","caller":"traceutil/trace.go:171","msg":"trace[186551964] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"112.620948ms","start":"2026-10-15T14:16:06.514406Z","end":"2026-10-15T14:16:06.627027Z","steps":["trace[186551964] 'process raft request' (duration: 112.29621ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:06.834486Z","caller":"traceutil/trace.go:171","msg":"trace[1365644249] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"127.357587ms","start":"2026-10-15T14:16:06.707091Z","end":"2026-10-15T14:16:06.834448Z","steps":["trace[1365644249] 'read index received' (duration: 127.110282ms)","trace[1365644249] 'applied index is now lower than readState.Index' (duration: 245.605µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:16:06.83474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.619104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:16:06.83481Z","caller":"traceutil/trace.go:171","msg":"trace[918580486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1883; }","duration":"127.757286ms","start":"2026-10-15T14:16:06.707037Z","end":"2026-10-15T14:16:06.834794Z","steps":["trace[918580486] 'agreement among raft nodes before linearized reading' (duration: 127.603633ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:06.834992Z","caller":"traceutil/trace.go:171","msg":"trace[977001523] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"141.269978ms","start":"2026-10-15T14:16:06.693676Z","end":"2026-10-15T14:16:06.834946Z","steps":["trace[977001523] 'process raft request' (duration: 140.503341ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:07.222258Z","caller":"traceutil/trace.go:171","msg":"trace[126453648] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"248.401735ms","start":"2026-10-15T14:16:06.973815Z","end":"2026-10-15T14:16:07.222217Z","steps":["trace[126453648] 'read index received' (duration: 188.155817ms)","trace[126453648] 'applied index is now lower than readState.Index' (duration: 60.243487ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:07.222399Z","caller":"traceutil/trace.go:171","msg":"trace[1371990903] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"320.21485ms","start":"2026-10-15T14:16:06.902111Z","end":"2026-10-15T14:16:07.222326Z","steps":["trace[1371990903] 'process raft request' (duration: 259.883851ms)","trace[1371990903] 'compare' (duration: 59.999132ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:16:07.222588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.745322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:16:07.222689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.325635ms","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-10-15T14:16:07.222701Z","caller":"traceutil/trace.go:171","msg":"trace[958936844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1885; }","duration":"248.940516ms","start":"2026-10-15T14:16:06.973735Z","end":"2026-10-15T14:16:07.222675Z","steps":["trace[958936844] 'agreement among raft nodes before linearized reading' (duration: 248.6965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:07.222721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:06.90208Z","time spent":"320.495276ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:16:07.222756Z","caller":"traceutil/trace.go:171","msg":"trace[1534852799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1885; }","duration":"177.441667ms","start":"2026-10-15T14:16:07.045296Z","end":"2026-10-15T14:16:07.222738Z","steps":["trace[1534852799] 'agreement among raft nodes before linearized reading' (duration: 177.205332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:07.222791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.627281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9253f738-b2b2-4722-9969-fda98d4fed10\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-10-15T14:16:07.222871Z","caller":"traceutil/trace.go:171","msg":"trace[732938122] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9253f738-b2b2-4722-9969-fda98d4fed10; range_end:; response_count:1; response_revision:1885; }","duration":"236.798025ms","start":"2026-10-15T14:16:06.986051Z","end":"2026-10-15T14:16:07.222849Z","steps":["trace[732938122] 'agreement among raft nodes before linearized reading' (duration: 236.425986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:07.223195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.243107ms","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-10-15T14:16:07.22334Z","caller":"traceutil/trace.go:171","msg":"trace[1700175775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1885; }","duration":"204.457582ms","start":"2026-10-15T14:16:07.018852Z","end":"2026-10-15T14:16:07.223309Z","steps":["trace[1700175775] 'agreement among raft nodes before linearized reading' (duration: 203.740946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:51.837654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.949503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:16:51.837975Z","caller":"traceutil/trace.go:171","msg":"trace[1532128183] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2262; }","duration":"288.44063ms","start":"2026-10-15T14:16:51.549492Z","end":"2026-10-15T14:16:51.837932Z","steps":["trace[1532128183] 'read index received' (duration: 87.115338ms)","trace[1532128183] 'applied index is now lower than readState.Index' (duration: 201.324202ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:51.837988Z","caller":"traceutil/trace.go:171","msg":"trace[1300915483] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"354.08747ms","start":"2026-10-15T14:16:51.483869Z","end":"2026-10-15T14:16:51.837957Z","steps":["trace[1300915483] 'process raft request' (duration: 353.956887ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:51.837957Z","caller":"traceutil/trace.go:171","msg":"trace[890211505] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"355.744066ms","start":"2026-10-15T14:16:51.482165Z","end":"2026-10-15T14:16:51.83791Z","steps":["trace[890211505] 'process raft request' (duration: 154.424885ms)","trace[890211505] 'compare' (duration: 200.070133ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:51.838023Z","caller":"traceutil/trace.go:171","msg":"trace[1873868741] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"267.756628ms","start":"2026-10-15T14:16:51.570229Z","end":"2026-10-15T14:16:51.837986Z","steps":["trace[1873868741] 'process raft request' (duration: 267.662406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:51.838139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.664095ms","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-10-15T14:16:51.838179Z","caller":"traceutil/trace.go:171","msg":"trace[395643984] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2184; }","duration":"288.756067ms","start":"2026-10-15T14:16:51.549413Z","end":"2026-10-15T14:16:51.838169Z","steps":["trace[395643984] 'agreement among raft nodes before linearized reading' (duration: 288.660805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:51.838167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:51.48383Z","time spent":"354.238653ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:16:51.838205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:51.482144Z","time spent":"355.948741ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:16:51.838639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.656771ms","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-10-15T14:16:51.838828Z","caller":"traceutil/trace.go:171","msg":"trace[2065268315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2184; }","duration":"113.936967ms","start":"2026-10-15T14:16:51.724859Z","end":"2026-10-15T14:16:51.838796Z","steps":["trace[2065268315] 'agreement among raft nodes before linearized reading' (duration: 113.434856ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:52.106448Z","caller":"traceutil/trace.go:171","msg":"trace[224785221] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"101.762417ms","start":"2026-10-15T14:16:52.004658Z","end":"2026-10-15T14:16:52.10642Z","steps":["trace[224785221] 'process raft request' (duration: 101.506251ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:57.854104Z","caller":"traceutil/trace.go:171","msg":"trace[1820752850] linearizableReadLoop","detail":"{readStateIndex:2294; appliedIndex:2293; }","duration":"337.198074ms","start":"2026-10-15T14:16:57.516875Z","end":"2026-10-15T14:16:57.854073Z","steps":["trace[1820752850] 'read index received' (duration: 336.961188ms)","trace[1820752850] 'applied index is now lower than readState.Index' (duration: 235.246µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:57.854154Z","caller":"traceutil/trace.go:171","msg":"trace[1881627127] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"354.715566ms","start":"2026-10-15T14:16:57.499402Z","end":"2026-10-15T14:16:57.854117Z","steps":["trace[1881627127] 'process raft request' (duration: 354.488971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:57.854276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.360367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:16:57.854316Z","caller":"traceutil/trace.go:171","msg":"trace[1922011252] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2211; }","duration":"337.47082ms","start":"2026-10-15T14:16:57.516832Z","end":"2026-10-15T14:16:57.854303Z","steps":["trace[1922011252] 'agreement among raft nodes before linearized reading' (duration: 337.367118ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:57.854384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:57.516813Z","time spent":"337.557972ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:16:57.854306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:57.49938Z","time spent":"354.844289ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:16:58.15407Z","caller":"traceutil/trace.go:171","msg":"trace[1518590292] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2294; }","duration":"287.763697ms","start":"2026-10-15T14:16:57.866273Z","end":"2026-10-15T14:16:58.154037Z","steps":["trace[1518590292] 'read index received' (duration: 276.334062ms)","trace[1518590292] 'applied index is now lower than readState.Index' (duration: 11.428205ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:16:58.154238Z","caller":"traceutil/trace.go:171","msg":"trace[363113701] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"294.695653ms","start":"2026-10-15T14:16:57.859363Z","end":"2026-10-15T14:16:58.154059Z","steps":["trace[363113701] 'process raft request' (duration: 283.233646ms)","trace[363113701] 'compare' (duration: 11.314524ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:16:58.154316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.033484ms","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-10-15T14:16:58.154489Z","caller":"traceutil/trace.go:171","msg":"trace[1743740569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2212; }","duration":"288.211078ms","start":"2026-10-15T14:16:57.866256Z","end":"2026-10-15T14:16:58.154467Z","steps":["trace[1743740569] 'agreement among raft nodes before linearized reading' (duration: 287.921401ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:16:58.253367Z","caller":"traceutil/trace.go:171","msg":"trace[1867051969] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"160.148233ms","start":"2026-10-15T14:16:58.093181Z","end":"2026-10-15T14:16:58.25333Z","steps":["trace[1867051969] 'process raft request' (duration: 159.670502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:58.253555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.876132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-10-15T14:16:58.253435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.52907ms","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-10-15T14:16:58.253716Z","caller":"traceutil/trace.go:171","msg":"trace[464761388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2213; }","duration":"113.864387ms","start":"2026-10-15T14:16:58.139823Z","end":"2026-10-15T14:16:58.253687Z","steps":["trace[464761388] 'agreement among raft nodes before linearized reading' (duration: 113.364955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:58.253806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.819332ms","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-10-15T14:16:58.253848Z","caller":"traceutil/trace.go:171","msg":"trace[148512656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2213; }","duration":"360.897514ms","start":"2026-10-15T14:16:57.892942Z","end":"2026-10-15T14:16:58.253839Z","steps":["trace[148512656] 'agreement among raft nodes before linearized reading' (duration: 360.738721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:58.253877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:57.892921Z","time spent":"360.948265ms","remote":"127.0.0.1:49126","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-10-15T14:16:58.25363Z","caller":"traceutil/trace.go:171","msg":"trace[1826306714] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2213; }","duration":"292.976655ms","start":"2026-10-15T14:16:57.96063Z","end":"2026-10-15T14:16:58.253606Z","steps":["trace[1826306714] 'agreement among raft nodes before linearized reading' (duration: 292.791481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:58.253449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.699051ms","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-10-15T14:16:58.254232Z","caller":"traceutil/trace.go:171","msg":"trace[1931421715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2213; }","duration":"387.661872ms","start":"2026-10-15T14:16:57.866549Z","end":"2026-10-15T14:16:58.254211Z","steps":["trace[1931421715] 'agreement among raft nodes before linearized reading' (duration: 386.545857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:16:58.254299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:16:57.866501Z","time spent":"387.784215ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T14:18:20.299024Z","caller":"traceutil/trace.go:171","msg":"trace[2116387654] transaction","detail":"{read_only:false; response_revision:2852; number_of_response:1; }","duration":"116.528348ms","start":"2026-10-15T14:18:20.182466Z","end":"2026-10-15T14:18:20.298994Z","steps":["trace[2116387654] 'process raft request' (duration: 116.368764ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:25.680901Z","caller":"traceutil/trace.go:171","msg":"trace[1750759626] transaction","detail":"{read_only:false; response_revision:2885; number_of_response:1; }","duration":"157.035818ms","start":"2026-10-15T14:18:25.523836Z","end":"2026-10-15T14:18:25.680872Z","steps":["trace[1750759626] 'process raft request' (duration: 156.841154ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:00.70189Z","caller":"traceutil/trace.go:171","msg":"trace[1894134726] transaction","detail":"{read_only:false; response_revision:3825; number_of_response:1; }","duration":"130.87503ms","start":"2026-10-15T14:21:00.570993Z","end":"2026-10-15T14:21:00.701868Z","steps":["trace[1894134726] 'process raft request' (duration: 96.425972ms)","trace[1894134726] 'compare' (duration: 34.303125ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:21:00.702138Z","caller":"traceutil/trace.go:171","msg":"trace[430187865] transaction","detail":"{read_only:false; response_revision:3826; number_of_response:1; }","duration":"128.342233ms","start":"2026-10-15T14:21:00.57376Z","end":"2026-10-15T14:21:00.702102Z","steps":["trace[430187865] 'process raft request' (duration: 128.078578ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.426165Z","caller":"traceutil/trace.go:171","msg":"trace[157240017] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"248.923915ms","start":"2026-10-15T14:22:57.177209Z","end":"2026-10-15T14:22:57.426133Z","steps":["trace[157240017] 'process raft request' (duration: 248.649779ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.426233Z","caller":"traceutil/trace.go:171","msg":"trace[1427280844] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"248.923564ms","start":"2026-10-15T14:22:57.177262Z","end":"2026-10-15T14:22:57.426185Z","steps":["trace[1427280844] 'process raft request' (duration: 248.759451ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.426315Z","caller":"traceutil/trace.go:171","msg":"trace[2102463173] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"248.930485ms","start":"2026-10-15T14:22:57.17733Z","end":"2026-10-15T14:22:57.42626Z","steps":["trace[2102463173] 'process raft request' (duration: 248.734691ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.426402Z","caller":"traceutil/trace.go:171","msg":"trace[1304468700] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"249.014647ms","start":"2026-10-15T14:22:57.177332Z","end":"2026-10-15T14:22:57.426346Z","steps":["trace[1304468700] 'process raft request' (duration: 248.763131ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.426561Z","caller":"traceutil/trace.go:171","msg":"trace[864499536] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"249.163991ms","start":"2026-10-15T14:22:57.177384Z","end":"2026-10-15T14:22:57.426548Z","steps":["trace[864499536] 'process raft request' (duration: 248.741471ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.426542Z","caller":"traceutil/trace.go:171","msg":"trace[1877192169] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"231.56302ms","start":"2026-10-15T14:22:57.194881Z","end":"2026-10-15T14:22:57.426444Z","steps":["trace[1877192169] 'process raft request' (duration: 231.306755ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:57.426553Z","caller":"traceutil/trace.go:171","msg":"trace[1249953497] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5313; }","duration":"246.867529ms","start":"2026-10-15T14:22:57.179517Z","end":"2026-10-15T14:22:57.426385Z","steps":["trace[1249953497] 'read index received' (duration: 48.983415ms)","trace[1249953497] 'applied index is now lower than readState.Index' (duration: 197.882484ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:22:57.426868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.32406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-15T14:22:57.426984Z","caller":"traceutil/trace.go:171","msg":"trace[564013035] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5123; }","duration":"247.481643ms","start":"2026-10-15T14:22:57.179484Z","end":"2026-10-15T14:22:57.426965Z","steps":["trace[564013035] 'agreement among raft nodes before linearized reading' (duration: 247.172726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:22:57.453823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.894621ms","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-10-15T14:22:57.453899Z","caller":"traceutil/trace.go:171","msg":"trace[2098276289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5124; }","duration":"243.012764ms","start":"2026-10-15T14:22:57.210869Z","end":"2026-10-15T14:22:57.453882Z","steps":["trace[2098276289] 'agreement among raft nodes before linearized reading' (duration: 242.82747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:22:57.454025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.596862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:529459"} {"level":"info","ts":"2026-10-15T14:22:57.454092Z","caller":"traceutil/trace.go:171","msg":"trace[68861007] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5124; }","duration":"231.727744ms","start":"2026-10-15T14:22:57.22235Z","end":"2026-10-15T14:22:57.454078Z","steps":["trace[68861007] 'agreement among raft nodes before linearized reading' (duration: 231.241584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:22:57.454535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.963381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210289"} {"level":"info","ts":"2026-10-15T14:22:57.454598Z","caller":"traceutil/trace.go:171","msg":"trace[1451137928] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5124; }","duration":"126.110085ms","start":"2026-10-15T14:22:57.328474Z","end":"2026-10-15T14:22:57.454584Z","steps":["trace[1451137928] 'agreement among raft nodes before linearized reading' (duration: 125.171214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:02.228059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.072331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:23:02.228286Z","caller":"traceutil/trace.go:171","msg":"trace[1574454108] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"216.720551ms","start":"2026-10-15T14:23:02.011537Z","end":"2026-10-15T14:23:02.228257Z","steps":["trace[1574454108] 'process raft request' (duration: 26.375314ms)","trace[1574454108] 'compare' (duration: 189.930108ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:02.228447Z","caller":"traceutil/trace.go:171","msg":"trace[1110726423] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"203.602951ms","start":"2026-10-15T14:23:02.024818Z","end":"2026-10-15T14:23:02.22842Z","steps":["trace[1110726423] 'process raft request' (duration: 203.432947ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:02.228401Z","caller":"traceutil/trace.go:171","msg":"trace[1807061171] linearizableReadLoop","detail":"{readStateIndex:5482; appliedIndex:5480; }","duration":"146.896534ms","start":"2026-10-15T14:23:02.081484Z","end":"2026-10-15T14:23:02.22838Z","steps":["trace[1807061171] 'read index received' (duration: 90.220659ms)","trace[1807061171] 'applied index is now lower than readState.Index' (duration: 56.674765ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:02.228588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.086928ms","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":"info","ts":"2026-10-15T14:23:02.228623Z","caller":"traceutil/trace.go:171","msg":"trace[2018252234] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5280; }","duration":"147.16452ms","start":"2026-10-15T14:23:02.081449Z","end":"2026-10-15T14:23:02.228613Z","steps":["trace[2018252234] 'agreement among raft nodes before linearized reading' (duration: 147.093589ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:02.514419Z","caller":"traceutil/trace.go:171","msg":"trace[862477051] linearizableReadLoop","detail":"{readStateIndex:5483; appliedIndex:5482; }","duration":"280.237419ms","start":"2026-10-15T14:23:02.234157Z","end":"2026-10-15T14:23:02.514394Z","steps":["trace[862477051] 'read index received' (duration: 280.119746ms)","trace[862477051] 'applied index is now lower than readState.Index' (duration: 116.383µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:02.514494Z","caller":"traceutil/trace.go:171","msg":"trace[1961984791] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"280.600577ms","start":"2026-10-15T14:23:02.233861Z","end":"2026-10-15T14:23:02.514461Z","steps":["trace[1961984791] 'process raft request' (duration: 280.388433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:02.514814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.631707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62871"} {"level":"info","ts":"2026-10-15T14:23:02.514881Z","caller":"traceutil/trace.go:171","msg":"trace[477955349] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5281; }","duration":"280.721069ms","start":"2026-10-15T14:23:02.234141Z","end":"2026-10-15T14:23:02.514862Z","steps":["trace[477955349] 'agreement among raft nodes before linearized reading' (duration: 280.419272ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:02.526891Z","caller":"traceutil/trace.go:171","msg":"trace[1364571068] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"292.696655ms","start":"2026-10-15T14:23:02.234166Z","end":"2026-10-15T14:23:02.526863Z","steps":["trace[1364571068] 'process raft request' (duration: 292.46597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:02.527002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.885766ms","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-10-15T14:23:02.526989Z","caller":"traceutil/trace.go:171","msg":"trace[667522523] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"271.221799ms","start":"2026-10-15T14:23:02.255716Z","end":"2026-10-15T14:23:02.526938Z","steps":["trace[667522523] 'process raft request' (duration: 271.095456ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:02.527063Z","caller":"traceutil/trace.go:171","msg":"trace[908004839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5284; }","duration":"282.011588ms","start":"2026-10-15T14:23:02.245037Z","end":"2026-10-15T14:23:02.527049Z","steps":["trace[908004839] 'agreement among raft nodes before linearized reading' (duration: 281.818864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:02.527071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.230345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T14:23:02.527127Z","caller":"traceutil/trace.go:171","msg":"trace[619767574] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5284; }","duration":"260.300078ms","start":"2026-10-15T14:23:02.266807Z","end":"2026-10-15T14:23:02.527107Z","steps":["trace[619767574] 'agreement among raft nodes before linearized reading' (duration: 260.124924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:02.527234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.455251ms","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-10-15T14:23:02.527242Z","caller":"traceutil/trace.go:171","msg":"trace[647062135] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"289.472873ms","start":"2026-10-15T14:23:02.237758Z","end":"2026-10-15T14:23:02.527231Z","steps":["trace[647062135] 'process raft request' (duration: 288.980912ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:02.527281Z","caller":"traceutil/trace.go:171","msg":"trace[1201667923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5284; }","duration":"260.557813ms","start":"2026-10-15T14:23:02.266712Z","end":"2026-10-15T14:23:02.52727Z","steps":["trace[1201667923] 'agreement among raft nodes before linearized reading' (duration: 260.439631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:02.527421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.094624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786897"} {"level":"info","ts":"2026-10-15T14:23:02.527464Z","caller":"traceutil/trace.go:171","msg":"trace[1260292572] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5284; }","duration":"233.174536ms","start":"2026-10-15T14:23:02.29428Z","end":"2026-10-15T14:23:02.527454Z","steps":["trace[1260292572] 'agreement among raft nodes before linearized reading' (duration: 232.718706ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:02.848207Z","caller":"traceutil/trace.go:171","msg":"trace[265397413] linearizableReadLoop","detail":"{readStateIndex:5495; appliedIndex:5494; }","duration":"199.50935ms","start":"2026-10-15T14:23:02.64867Z","end":"2026-10-15T14:23:02.848179Z","steps":["trace[265397413] 'read index received' (duration: 116.945491ms)","trace[265397413] 'applied index is now lower than readState.Index' (duration: 82.562549ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:02.848238Z","caller":"traceutil/trace.go:171","msg":"trace[636999492] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"216.400175ms","start":"2026-10-15T14:23:02.631812Z","end":"2026-10-15T14:23:02.848212Z","steps":["trace[636999492] 'process raft request' (duration: 133.776634ms)","trace[636999492] 'compare' (duration: 82.473067ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:02.848461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.501394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-15T14:23:02.848517Z","caller":"traceutil/trace.go:171","msg":"trace[228329540] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5293; }","duration":"159.592406ms","start":"2026-10-15T14:23:02.68891Z","end":"2026-10-15T14:23:02.848502Z","steps":["trace[228329540] 'agreement among raft nodes before linearized reading' (duration: 159.440463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:02.84846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.781037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/vector\" ","response":"range_response_count:1 size:5083"} {"level":"info","ts":"2026-10-15T14:23:02.84863Z","caller":"traceutil/trace.go:171","msg":"trace[807216039] range","detail":"{range_begin:/registry/daemonsets/monitoring/vector; range_end:; response_count:1; response_revision:5293; }","duration":"200.000311ms","start":"2026-10-15T14:23:02.648611Z","end":"2026-10-15T14:23:02.848611Z","steps":["trace[807216039] 'agreement among raft nodes before linearized reading' (duration: 199.677724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:02.848752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.530669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414015"} {"level":"info","ts":"2026-10-15T14:23:02.848836Z","caller":"traceutil/trace.go:171","msg":"trace[1301338541] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5293; }","duration":"124.712123ms","start":"2026-10-15T14:23:02.724107Z","end":"2026-10-15T14:23:02.848819Z","steps":["trace[1301338541] 'agreement among raft nodes before linearized reading' (duration: 124.351135ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:04.779109Z","caller":"traceutil/trace.go:171","msg":"trace[1377729762] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5525; }","duration":"101.09202ms","start":"2026-10-15T14:23:04.677995Z","end":"2026-10-15T14:23:04.779087Z","steps":["trace[1377729762] 'read index received' (duration: 99.905824ms)","trace[1377729762] 'applied index is now lower than readState.Index' (duration: 1.185086ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:04.779181Z","caller":"traceutil/trace.go:171","msg":"trace[1188079987] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"117.841ms","start":"2026-10-15T14:23:04.661314Z","end":"2026-10-15T14:23:04.779155Z","steps":["trace[1188079987] 'process raft request' (duration: 116.652424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:04.77926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.243743ms","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-10-15T14:23:04.779495Z","caller":"traceutil/trace.go:171","msg":"trace[475688417] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5322; }","duration":"101.327594ms","start":"2026-10-15T14:23:04.677955Z","end":"2026-10-15T14:23:04.779283Z","steps":["trace[475688417] 'agreement among raft nodes before linearized reading' (duration: 101.227332ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:16.213461Z","caller":"traceutil/trace.go:171","msg":"trace[1372125708] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"236.97232ms","start":"2026-10-15T14:23:15.976458Z","end":"2026-10-15T14:23:16.213431Z","steps":["trace[1372125708] 'process raft request' (duration: 236.787116ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:19.757209Z","caller":"traceutil/trace.go:171","msg":"trace[1230808341] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"106.04411ms","start":"2026-10-15T14:23:19.65114Z","end":"2026-10-15T14:23:19.757184Z","steps":["trace[1230808341] 'process raft request' (duration: 103.535464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:23.565795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.363228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110657"} {"level":"info","ts":"2026-10-15T14:23:23.565902Z","caller":"traceutil/trace.go:171","msg":"trace[243224688] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5692; }","duration":"107.498871ms","start":"2026-10-15T14:23:23.458378Z","end":"2026-10-15T14:23:23.565877Z","steps":["trace[243224688] 'range keys from in-memory index tree' (duration: 107.066662ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:23.944709Z","caller":"traceutil/trace.go:171","msg":"trace[867726854] linearizableReadLoop","detail":"{readStateIndex:5909; appliedIndex:5908; }","duration":"164.330711ms","start":"2026-10-15T14:23:23.780354Z","end":"2026-10-15T14:23:23.944684Z","steps":["trace[867726854] 'read index received' (duration: 78.857817ms)","trace[867726854] 'applied index is now lower than readState.Index' (duration: 85.471514ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:23.944751Z","caller":"traceutil/trace.go:171","msg":"trace[2111724561] transaction","detail":"{read_only:false; response_revision:5694; number_of_response:1; }","duration":"176.246454ms","start":"2026-10-15T14:23:23.768453Z","end":"2026-10-15T14:23:23.944699Z","steps":["trace[2111724561] 'process raft request' (duration: 90.712019ms)","trace[2111724561] 'compare' (duration: 85.30929ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:23.945104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.735759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:23:23.945189Z","caller":"traceutil/trace.go:171","msg":"trace[2098667156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5694; }","duration":"164.862132ms","start":"2026-10-15T14:23:23.780303Z","end":"2026-10-15T14:23:23.945165Z","steps":["trace[2098667156] 'agreement among raft nodes before linearized reading' (duration: 164.478044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:24.223724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.966569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:23:24.223849Z","caller":"traceutil/trace.go:171","msg":"trace[1666335145] linearizableReadLoop","detail":"{readStateIndex:5910; appliedIndex:5909; }","duration":"176.333576ms","start":"2026-10-15T14:23:24.047499Z","end":"2026-10-15T14:23:24.223833Z","steps":["trace[1666335145] 'read index received' (duration: 166.633µs)","trace[1666335145] 'applied index is now lower than readState.Index' (duration: 176.165933ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:24.22399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.49029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93a48b95-7773-441b-a29f-0e939e4bef6d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-15T14:23:24.22402Z","caller":"traceutil/trace.go:171","msg":"trace[65449692] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93a48b95-7773-441b-a29f-0e939e4bef6d; range_end:; response_count:1; response_revision:5695; }","duration":"176.563611ms","start":"2026-10-15T14:23:24.047448Z","end":"2026-10-15T14:23:24.224011Z","steps":["trace[65449692] 'agreement among raft nodes before linearized reading' (duration: 176.432278ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:24.22423Z","caller":"traceutil/trace.go:171","msg":"trace[1803081541] transaction","detail":"{read_only:false; response_revision:5695; number_of_response:1; }","duration":"274.490632ms","start":"2026-10-15T14:23:23.949726Z","end":"2026-10-15T14:23:24.224217Z","steps":["trace[1803081541] 'process raft request' (duration: 97.93837ms)","trace[1803081541] 'compare' (duration: 175.768664ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:26.41483Z","caller":"traceutil/trace.go:171","msg":"trace[799264783] transaction","detail":"{read_only:false; response_revision:5712; number_of_response:1; }","duration":"317.945694ms","start":"2026-10-15T14:23:26.096854Z","end":"2026-10-15T14:23:26.4148Z","steps":["trace[799264783] 'process raft request' (duration: 233.509154ms)","trace[799264783] 'compare' (duration: 84.276547ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:26.415009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.096824Z","time spent":"318.098977ms","remote":"127.0.0.1:41048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":966,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:23:26.418119Z","caller":"traceutil/trace.go:171","msg":"trace[1021707535] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"320.860319ms","start":"2026-10-15T14:23:26.097206Z","end":"2026-10-15T14:23:26.418067Z","steps":["trace[1021707535] 'process raft request' (duration: 320.423939ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:26.418197Z","caller":"traceutil/trace.go:171","msg":"trace[545257807] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"320.932531ms","start":"2026-10-15T14:23:26.097218Z","end":"2026-10-15T14:23:26.418151Z","steps":["trace[545257807] 'process raft request' (duration: 320.717536ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:26.418304Z","caller":"traceutil/trace.go:171","msg":"trace[374804471] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"320.990311ms","start":"2026-10-15T14:23:26.097288Z","end":"2026-10-15T14:23:26.418279Z","steps":["trace[374804471] 'process raft request' (duration: 320.768547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.418324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.097198Z","time spent":"321.005222ms","remote":"127.0.0.1:49130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:23:26.418378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.097204Z","time spent":"321.057203ms","remote":"127.0.0.1:41048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1080,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:23:26.418413Z","caller":"traceutil/trace.go:171","msg":"trace[2146907835] linearizableReadLoop","detail":"{readStateIndex:5933; appliedIndex:5927; }","duration":"190.299676ms","start":"2026-10-15T14:23:26.228093Z","end":"2026-10-15T14:23:26.418392Z","steps":["trace[2146907835] 'read index received' (duration: 102.349977ms)","trace[2146907835] 'applied index is now lower than readState.Index' (duration: 87.948649ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:26.418689Z","caller":"traceutil/trace.go:171","msg":"trace[1207989858] transaction","detail":"{read_only:false; response_revision:5717; number_of_response:1; }","duration":"321.217866ms","start":"2026-10-15T14:23:26.097447Z","end":"2026-10-15T14:23:26.418665Z","steps":["trace[1207989858] 'process raft request' (duration: 320.790107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.418701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.604132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:23:26.418754Z","caller":"traceutil/trace.go:171","msg":"trace[416825969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5717; }","duration":"190.705675ms","start":"2026-10-15T14:23:26.228033Z","end":"2026-10-15T14:23:26.418739Z","steps":["trace[416825969] 'agreement among raft nodes before linearized reading' (duration: 190.450549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.418823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.097345Z","time spent":"321.393751ms","remote":"127.0.0.1:49130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:23:26.418398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.097261Z","time spent":"321.085344ms","remote":"127.0.0.1:41048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":896,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:23:26.418349Z","caller":"traceutil/trace.go:171","msg":"trace[1104069208] transaction","detail":"{read_only:false; response_revision:5716; number_of_response:1; }","duration":"321.014412ms","start":"2026-10-15T14:23:26.09729Z","end":"2026-10-15T14:23:26.418304Z","steps":["trace[1104069208] 'process raft request' (duration: 320.841208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:26.42119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:26.097261Z","time spent":"323.790644ms","remote":"127.0.0.1:49130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:23:36.421666Z","caller":"traceutil/trace.go:171","msg":"trace[1917402531] linearizableReadLoop","detail":"{readStateIndex:6070; appliedIndex:6066; }","duration":"131.424761ms","start":"2026-10-15T14:23:36.290215Z","end":"2026-10-15T14:23:36.42164Z","steps":["trace[1917402531] 'read index received' (duration: 43.406661ms)","trace[1917402531] 'applied index is now lower than readState.Index' (duration: 88.01702ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:36.421905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.668707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-15T14:23:36.421952Z","caller":"traceutil/trace.go:171","msg":"trace[2046037248] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5852; }","duration":"131.773129ms","start":"2026-10-15T14:23:36.290169Z","end":"2026-10-15T14:23:36.421942Z","steps":["trace[2046037248] 'agreement among raft nodes before linearized reading' (duration: 131.565524ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:36.422362Z","caller":"traceutil/trace.go:171","msg":"trace[665388345] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"168.251188ms","start":"2026-10-15T14:23:36.254097Z","end":"2026-10-15T14:23:36.422348Z","steps":["trace[665388345] 'process raft request' (duration: 79.42471ms)","trace[665388345] 'compare' (duration: 87.761804ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:36.422557Z","caller":"traceutil/trace.go:171","msg":"trace[1590087190] transaction","detail":"{read_only:false; response_revision:5851; number_of_response:1; }","duration":"167.371958ms","start":"2026-10-15T14:23:36.255122Z","end":"2026-10-15T14:23:36.422494Z","steps":["trace[1590087190] 'process raft request' (duration: 166.428807ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:36.422598Z","caller":"traceutil/trace.go:171","msg":"trace[2109310848] transaction","detail":"{read_only:false; response_revision:5852; number_of_response:1; }","duration":"166.505279ms","start":"2026-10-15T14:23:36.256059Z","end":"2026-10-15T14:23:36.422564Z","steps":["trace[2109310848] 'process raft request' (duration: 165.538497ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:36.422684Z","caller":"traceutil/trace.go:171","msg":"trace[3780064] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"167.86508ms","start":"2026-10-15T14:23:36.254776Z","end":"2026-10-15T14:23:36.422641Z","steps":["trace[3780064] 'process raft request' (duration: 166.686493ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:41.896884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2972} {"level":"info","ts":"2026-10-15T14:23:41.977506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2972,"took":"78.902918ms","hash":1853619734,"current-db-size-bytes":56442880,"current-db-size":"56 MB","current-db-size-in-use-bytes":32378880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:23:41.977628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1853619734,"revision":2972,"compact-revision":-1} {"level":"info","ts":"2026-10-15T14:24:15.207967Z","caller":"traceutil/trace.go:171","msg":"trace[441020124] transaction","detail":"{read_only:false; response_revision:6199; number_of_response:1; }","duration":"130.119904ms","start":"2026-10-15T14:24:15.077805Z","end":"2026-10-15T14:24:15.207925Z","steps":["trace[441020124] 'process raft request' (duration: 129.820597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:24:15.208377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.999031ms","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-10-15T14:24:15.208437Z","caller":"traceutil/trace.go:171","msg":"trace[434558230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6200; }","duration":"139.126114ms","start":"2026-10-15T14:24:15.069298Z","end":"2026-10-15T14:24:15.208424Z","steps":["trace[434558230] 'agreement among raft nodes before linearized reading' (duration: 138.92223ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:28:41.904304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5894} {"level":"info","ts":"2026-10-15T14:28:41.992802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5894,"took":"86.891154ms","hash":490627277,"current-db-size-bytes":56442880,"current-db-size":"56 MB","current-db-size-in-use-bytes":26165248,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T14:28:41.992846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":490627277,"revision":5894,"compact-revision":2972} {"level":"info","ts":"2026-10-15T14:29:28.278982Z","caller":"traceutil/trace.go:171","msg":"trace[753815639] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"126.307572ms","start":"2026-10-15T14:29:28.152638Z","end":"2026-10-15T14:29:28.278945Z","steps":["trace[753815639] 'process raft request' (duration: 125.610336ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:28.471239Z","caller":"traceutil/trace.go:171","msg":"trace[678387246] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"180.179201ms","start":"2026-10-15T14:29:28.291028Z","end":"2026-10-15T14:29:28.471207Z","steps":["trace[678387246] 'process raft request' (duration: 175.059417ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:39.153336Z","caller":"traceutil/trace.go:171","msg":"trace[823789187] linearizableReadLoop","detail":"{readStateIndex:8885; appliedIndex:8884; }","duration":"124.139754ms","start":"2026-10-15T14:29:39.029167Z","end":"2026-10-15T14:29:39.153307Z","steps":["trace[823789187] 'read index received' (duration: 28.050635ms)","trace[823789187] 'applied index is now lower than readState.Index' (duration: 96.087819ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:29:39.153512Z","caller":"traceutil/trace.go:171","msg":"trace[1982907770] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"150.222145ms","start":"2026-10-15T14:29:39.003272Z","end":"2026-10-15T14:29:39.153494Z","steps":["trace[1982907770] 'process raft request' (duration: 54.018072ms)","trace[1982907770] 'compare' (duration: 95.786983ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:29:39.153868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.668356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kcnvc\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:29:39.153972Z","caller":"traceutil/trace.go:171","msg":"trace[752691349] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kcnvc; range_end:; response_count:1; response_revision:8579; }","duration":"124.8403ms","start":"2026-10-15T14:29:39.029108Z","end":"2026-10-15T14:29:39.153948Z","steps":["trace[752691349] 'agreement among raft nodes before linearized reading' (duration: 124.447731ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:40.661236Z","caller":"traceutil/trace.go:171","msg":"trace[1949608313] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"130.008965ms","start":"2026-10-15T14:29:40.531195Z","end":"2026-10-15T14:29:40.661204Z","steps":["trace[1949608313] 'process raft request' (duration: 129.696958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:41.02224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.829178ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:29:41.022331Z","caller":"traceutil/trace.go:171","msg":"trace[1870831844] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8583; }","duration":"159.940051ms","start":"2026-10-15T14:29:40.862378Z","end":"2026-10-15T14:29:41.022318Z","steps":["trace[1870831844] 'range keys from in-memory index tree' (duration: 159.811598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:41.022599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.286943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:29:41.022736Z","caller":"traceutil/trace.go:171","msg":"trace[183263359] linearizableReadLoop","detail":"{readStateIndex:8891; appliedIndex:8890; }","duration":"230.316047ms","start":"2026-10-15T14:29:40.792398Z","end":"2026-10-15T14:29:41.022714Z","steps":["trace[183263359] 'read index received' (duration: 60.081µs)","trace[183263359] 'applied index is now lower than readState.Index' (duration: 230.254036ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:29:41.022786Z","caller":"traceutil/trace.go:171","msg":"trace[1200651506] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"351.391484ms","start":"2026-10-15T14:29:40.671336Z","end":"2026-10-15T14:29:41.022728Z","steps":["trace[1200651506] 'process raft request' (duration: 100.851536ms)","trace[1200651506] 'compare' (duration: 250.129159ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:29:41.02291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.509123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T14:29:41.022941Z","caller":"traceutil/trace.go:171","msg":"trace[2035950262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8584; }","duration":"230.575013ms","start":"2026-10-15T14:29:40.792357Z","end":"2026-10-15T14:29:41.022932Z","steps":["trace[2035950262] 'agreement among raft nodes before linearized reading' (duration: 230.44106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:41.022943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:40.671293Z","time spent":"351.571608ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:29:41.023041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.784751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T14:29:41.023098Z","caller":"traceutil/trace.go:171","msg":"trace[825654320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8584; }","duration":"227.896454ms","start":"2026-10-15T14:29:40.795186Z","end":"2026-10-15T14:29:41.023083Z","steps":["trace[825654320] 'agreement among raft nodes before linearized reading' (duration: 227.690789ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:42.314981Z","caller":"traceutil/trace.go:171","msg":"trace[1131359168] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"100.217261ms","start":"2026-10-15T14:29:42.214717Z","end":"2026-10-15T14:29:42.314935Z","steps":["trace[1131359168] 'process raft request' (duration: 99.932345ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:09.892906Z","caller":"traceutil/trace.go:171","msg":"trace[1741876437] transaction","detail":"{read_only:false; response_revision:8797; number_of_response:1; }","duration":"261.723536ms","start":"2026-10-15T14:30:09.631154Z","end":"2026-10-15T14:30:09.892878Z","steps":["trace[1741876437] 'process raft request' (duration: 243.867408ms)","trace[1741876437] 'compare' (duration: 17.50711ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:10.583571Z","caller":"traceutil/trace.go:171","msg":"trace[1112388480] transaction","detail":"{read_only:false; response_revision:8798; number_of_response:1; }","duration":"119.48369ms","start":"2026-10-15T14:30:10.464058Z","end":"2026-10-15T14:30:10.583541Z","steps":["trace[1112388480] 'process raft request' (duration: 119.317646ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:16.683957Z","caller":"traceutil/trace.go:171","msg":"trace[1237512030] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"262.234218ms","start":"2026-10-15T14:30:16.4217Z","end":"2026-10-15T14:30:16.683934Z","steps":["trace[1237512030] 'process raft request' (duration: 261.906731ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:16.684174Z","caller":"traceutil/trace.go:171","msg":"trace[1012183933] linearizableReadLoop","detail":"{readStateIndex:9148; appliedIndex:9147; }","duration":"135.67671ms","start":"2026-10-15T14:30:16.54817Z","end":"2026-10-15T14:30:16.683847Z","steps":["trace[1012183933] 'read index received' (duration: 135.487576ms)","trace[1012183933] 'applied index is now lower than readState.Index' (duration: 188.294µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:30:16.684293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.10055ms","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-10-15T14:30:16.684389Z","caller":"traceutil/trace.go:171","msg":"trace[142178967] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8832; }","duration":"136.249193ms","start":"2026-10-15T14:30:16.548126Z","end":"2026-10-15T14:30:16.684376Z","steps":["trace[142178967] 'agreement among raft nodes before linearized reading' (duration: 136.11205ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:16.770446Z","caller":"traceutil/trace.go:171","msg":"trace[775663782] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"167.96756ms","start":"2026-10-15T14:30:16.602438Z","end":"2026-10-15T14:30:16.770405Z","steps":["trace[775663782] 'process raft request' (duration: 132.712305ms)","trace[775663782] 'compare' (duration: 35.135642ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:17.487532Z","caller":"traceutil/trace.go:171","msg":"trace[757702756] linearizableReadLoop","detail":"{readStateIndex:9151; appliedIndex:9150; }","duration":"254.568698ms","start":"2026-10-15T14:30:17.232946Z","end":"2026-10-15T14:30:17.487515Z","steps":["trace[757702756] 'read index received' (duration: 254.437445ms)","trace[757702756] 'applied index is now lower than readState.Index' (duration: 130.373µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:17.487606Z","caller":"traceutil/trace.go:171","msg":"trace[1927506810] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"263.426995ms","start":"2026-10-15T14:30:17.224171Z","end":"2026-10-15T14:30:17.487598Z","steps":["trace[1927506810] 'process raft request' (duration: 263.228171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:17.487802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.849445ms","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-10-15T14:30:17.48783Z","caller":"traceutil/trace.go:171","msg":"trace[1978495123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8835; }","duration":"254.932116ms","start":"2026-10-15T14:30:17.232892Z","end":"2026-10-15T14:30:17.487824Z","steps":["trace[1978495123] 'agreement among raft nodes before linearized reading' (duration: 254.810903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:17.49116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.206151ms","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-10-15T14:30:17.491222Z","caller":"traceutil/trace.go:171","msg":"trace[1340640380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8836; }","duration":"237.273433ms","start":"2026-10-15T14:30:17.253936Z","end":"2026-10-15T14:30:17.49121Z","steps":["trace[1340640380] 'agreement among raft nodes before linearized reading' (duration: 237.12902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:17.491306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.790092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T14:30:17.491347Z","caller":"traceutil/trace.go:171","msg":"trace[486062756] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8836; }","duration":"108.873344ms","start":"2026-10-15T14:30:17.382463Z","end":"2026-10-15T14:30:17.491336Z","steps":["trace[486062756] 'agreement among raft nodes before linearized reading' (duration: 108.746941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:17.49136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.88655ms","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-10-15T14:30:17.491378Z","caller":"traceutil/trace.go:171","msg":"trace[1022455483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8836; }","duration":"234.926841ms","start":"2026-10-15T14:30:17.256446Z","end":"2026-10-15T14:30:17.491373Z","steps":["trace[1022455483] 'agreement among raft nodes before linearized reading' (duration: 234.875169ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.533962Z","caller":"traceutil/trace.go:171","msg":"trace[431758428] linearizableReadLoop","detail":"{readStateIndex:9222; appliedIndex:9220; }","duration":"230.090419ms","start":"2026-10-15T14:30:23.303825Z","end":"2026-10-15T14:30:23.533915Z","steps":["trace[431758428] 'read index received' (duration: 6.595197ms)","trace[431758428] 'applied index is now lower than readState.Index' (duration: 223.493942ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:23.534241Z","caller":"traceutil/trace.go:171","msg":"trace[808590850] transaction","detail":"{read_only:false; response_revision:8902; number_of_response:1; }","duration":"230.770875ms","start":"2026-10-15T14:30:23.303394Z","end":"2026-10-15T14:30:23.534165Z","steps":["trace[808590850] 'process raft request' (duration: 178.033799ms)","trace[808590850] 'compare' (duration: 52.281035ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:30:23.534297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.460117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-15T14:30:23.534368Z","caller":"traceutil/trace.go:171","msg":"trace[550631004] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8902; }","duration":"230.55441ms","start":"2026-10-15T14:30:23.303797Z","end":"2026-10-15T14:30:23.534351Z","steps":["trace[550631004] 'agreement among raft nodes before linearized reading' (duration: 230.379296ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.747383Z","caller":"traceutil/trace.go:171","msg":"trace[674449431] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"186.971897ms","start":"2026-10-15T14:30:23.560361Z","end":"2026-10-15T14:30:23.747333Z","steps":["trace[674449431] 'process raft request' (duration: 179.92118ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.747519Z","caller":"traceutil/trace.go:171","msg":"trace[352674284] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"187.10784ms","start":"2026-10-15T14:30:23.560371Z","end":"2026-10-15T14:30:23.747479Z","steps":["trace[352674284] 'process raft request' (duration: 186.966567ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.747825Z","caller":"traceutil/trace.go:171","msg":"trace[286881685] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"186.18802ms","start":"2026-10-15T14:30:23.561617Z","end":"2026-10-15T14:30:23.747805Z","steps":["trace[286881685] 'process raft request' (duration: 185.810681ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.747954Z","caller":"traceutil/trace.go:171","msg":"trace[748985993] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"182.375245ms","start":"2026-10-15T14:30:23.565563Z","end":"2026-10-15T14:30:23.747939Z","steps":["trace[748985993] 'process raft request' (duration: 182.014006ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.74827Z","caller":"traceutil/trace.go:171","msg":"trace[874592548] linearizableReadLoop","detail":"{readStateIndex:9226; appliedIndex:9224; }","duration":"187.555821ms","start":"2026-10-15T14:30:23.560698Z","end":"2026-10-15T14:30:23.748254Z","steps":["trace[874592548] 'read index received' (duration: 63.107616ms)","trace[874592548] 'applied index is now lower than readState.Index' (duration: 124.447355ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:23.748045Z","caller":"traceutil/trace.go:171","msg":"trace[1306058275] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"179.615934ms","start":"2026-10-15T14:30:23.56838Z","end":"2026-10-15T14:30:23.747996Z","steps":["trace[1306058275] 'process raft request' (duration: 179.399119ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.748497Z","caller":"traceutil/trace.go:171","msg":"trace[1689925928] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"174.888868ms","start":"2026-10-15T14:30:23.573583Z","end":"2026-10-15T14:30:23.748472Z","steps":["trace[1689925928] 'process raft request' (duration: 174.53134ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.748693Z","caller":"traceutil/trace.go:171","msg":"trace[3004298] transaction","detail":"{read_only:false; response_revision:8911; number_of_response:1; }","duration":"140.636575ms","start":"2026-10-15T14:30:23.608043Z","end":"2026-10-15T14:30:23.748679Z","steps":["trace[3004298] 'process raft request' (duration: 140.160404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:23.748704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.098175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tsxqd\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-10-15T14:30:23.748744Z","caller":"traceutil/trace.go:171","msg":"trace[581121996] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tsxqd; range_end:; response_count:1; response_revision:8911; }","duration":"141.174056ms","start":"2026-10-15T14:30:23.607559Z","end":"2026-10-15T14:30:23.748733Z","steps":["trace[581121996] 'agreement among raft nodes before linearized reading' (duration: 141.091054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:23.748818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.78286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tsxqd\" ","response":"range_response_count:1 size:4644"} {"level":"warn","ts":"2026-10-15T14:30:23.749014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.296537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228437"} {"level":"info","ts":"2026-10-15T14:30:23.749069Z","caller":"traceutil/trace.go:171","msg":"trace[706863097] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8911; }","duration":"188.367988ms","start":"2026-10-15T14:30:23.560688Z","end":"2026-10-15T14:30:23.749056Z","steps":["trace[706863097] 'agreement among raft nodes before linearized reading' (duration: 187.916578ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:23.749061Z","caller":"traceutil/trace.go:171","msg":"trace[1646093567] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tsxqd; range_end:; response_count:1; response_revision:8911; }","duration":"181.057195ms","start":"2026-10-15T14:30:23.567991Z","end":"2026-10-15T14:30:23.749048Z","steps":["trace[1646093567] 'agreement among raft nodes before linearized reading' (duration: 180.646946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:24.086933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.963941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:30:24.087148Z","caller":"traceutil/trace.go:171","msg":"trace[329387765] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"146.614807ms","start":"2026-10-15T14:30:23.940515Z","end":"2026-10-15T14:30:24.08713Z","steps":["trace[329387765] 'process raft request' (duration: 146.521385ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:24.087287Z","caller":"traceutil/trace.go:171","msg":"trace[102632368] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"329.159106ms","start":"2026-10-15T14:30:23.758097Z","end":"2026-10-15T14:30:24.087256Z","steps":["trace[102632368] 'process raft request' (duration: 187.780316ms)","trace[102632368] 'compare' (duration: 140.745956ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:30:24.087312Z","caller":"traceutil/trace.go:171","msg":"trace[1342614571] linearizableReadLoop","detail":"{readStateIndex:9232; appliedIndex:9231; }","duration":"326.958737ms","start":"2026-10-15T14:30:23.760325Z","end":"2026-10-15T14:30:24.087284Z","steps":["trace[1342614571] 'read index received' (duration: 185.568936ms)","trace[1342614571] 'applied index is now lower than readState.Index' (duration: 141.387801ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:30:24.087443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.75807Z","time spent":"329.292439ms","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4634,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:30:24.087465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.12904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tsxqd\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-10-15T14:30:24.087496Z","caller":"traceutil/trace.go:171","msg":"trace[798464762] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tsxqd; range_end:; response_count:1; response_revision:8913; }","duration":"327.196402ms","start":"2026-10-15T14:30:23.76029Z","end":"2026-10-15T14:30:24.087486Z","steps":["trace[798464762] 'agreement among raft nodes before linearized reading' (duration: 327.1076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:30:24.087533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:30:23.760273Z","time spent":"327.252724ms","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4672,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tsxqd\" "} {"level":"warn","ts":"2026-10-15T14:30:24.087592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.388455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-10-15T14:30:24.087603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.208743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:30:24.087689Z","caller":"traceutil/trace.go:171","msg":"trace[375557336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8913; }","duration":"113.352596ms","start":"2026-10-15T14:30:23.974314Z","end":"2026-10-15T14:30:24.087667Z","steps":["trace[375557336] 'agreement among raft nodes before linearized reading' (duration: 113.174772ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:24.087635Z","caller":"traceutil/trace.go:171","msg":"trace[1224459056] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8913; }","duration":"200.475617ms","start":"2026-10-15T14:30:23.887147Z","end":"2026-10-15T14:30:24.087623Z","steps":["trace[1224459056] 'agreement among raft nodes before linearized reading' (duration: 200.316954ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:24.409418Z","caller":"traceutil/trace.go:171","msg":"trace[1370871602] transaction","detail":"{read_only:false; response_revision:8914; number_of_response:1; }","duration":"108.068119ms","start":"2026-10-15T14:30:24.301313Z","end":"2026-10-15T14:30:24.409381Z","steps":["trace[1370871602] 'process raft request' (duration: 107.798473ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:25.205381Z","caller":"traceutil/trace.go:171","msg":"trace[1451202504] transaction","detail":"{read_only:false; response_revision:8916; number_of_response:1; }","duration":"221.903386ms","start":"2026-10-15T14:30:24.983452Z","end":"2026-10-15T14:30:25.205356Z","steps":["trace[1451202504] 'process raft request' (duration: 221.688441ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:30:44.16182Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024c6.wal"} {"level":"warn","ts":"2026-10-15T14:31:44.452073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.043559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-15T14:31:44.452175Z","caller":"traceutil/trace.go:171","msg":"trace[673603815] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:9618; }","duration":"139.223883ms","start":"2026-10-15T14:31:44.312927Z","end":"2026-10-15T14:31:44.45215Z","steps":["trace[673603815] 'range keys from in-memory index tree' (duration: 138.817374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:31:44.452073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.904003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-10-15T14:31:44.452297Z","caller":"traceutil/trace.go:171","msg":"trace[429690631] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:9618; }","duration":"116.171899ms","start":"2026-10-15T14:31:44.33608Z","end":"2026-10-15T14:31:44.452252Z","steps":["trace[429690631] 'range keys from in-memory index tree' (duration: 115.75908ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:31:44.630871Z","caller":"traceutil/trace.go:171","msg":"trace[1036333026] linearizableReadLoop","detail":"{readStateIndex:9962; appliedIndex:9961; }","duration":"150.455354ms","start":"2026-10-15T14:31:44.480393Z","end":"2026-10-15T14:31:44.630849Z","steps":["trace[1036333026] 'read index received' (duration: 150.392782ms)","trace[1036333026] 'applied index is now lower than readState.Index' (duration: 61.092µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:31:44.630935Z","caller":"traceutil/trace.go:171","msg":"trace[1353550900] transaction","detail":"{read_only:false; response_revision:9619; number_of_response:1; }","duration":"226.56957ms","start":"2026-10-15T14:31:44.404338Z","end":"2026-10-15T14:31:44.630907Z","steps":["trace[1353550900] 'process raft request' (duration: 226.334425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:31:44.631071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.653268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-15T14:31:44.63114Z","caller":"traceutil/trace.go:171","msg":"trace[1922565630] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:9619; }","duration":"150.754ms","start":"2026-10-15T14:31:44.480352Z","end":"2026-10-15T14:31:44.631106Z","steps":["trace[1922565630] 'agreement among raft nodes before linearized reading' (duration: 150.592007ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:31:44.705146Z","caller":"traceutil/trace.go:171","msg":"trace[206877910] transaction","detail":"{read_only:false; response_revision:9620; number_of_response:1; }","duration":"202.952274ms","start":"2026-10-15T14:31:44.502144Z","end":"2026-10-15T14:31:44.705096Z","steps":["trace[206877910] 'process raft request' (duration: 156.972809ms)","trace[206877910] 'compare' (duration: 45.256119ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:31:44.860178Z","caller":"traceutil/trace.go:171","msg":"trace[1330035116] transaction","detail":"{read_only:false; response_revision:9621; number_of_response:1; }","duration":"105.336749ms","start":"2026-10-15T14:31:44.754805Z","end":"2026-10-15T14:31:44.860142Z","steps":["trace[1330035116] 'process raft request' (duration: 105.124544ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:31:47.299668Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"282167b6747c9cda","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:31:47.306159Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-10-15T14:31:47.306263Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-10-15T14:32:20.903245Z","caller":"traceutil/trace.go:171","msg":"trace[736472587] transaction","detail":"{read_only:false; response_revision:9958; number_of_response:1; }","duration":"113.04329ms","start":"2026-10-15T14:32:20.790166Z","end":"2026-10-15T14:32:20.90321Z","steps":["trace[736472587] 'process raft request' (duration: 112.828275ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:20.903628Z","caller":"traceutil/trace.go:171","msg":"trace[1110744450] transaction","detail":"{read_only:false; response_revision:9959; number_of_response:1; }","duration":"103.15081ms","start":"2026-10-15T14:32:20.800441Z","end":"2026-10-15T14:32:20.903591Z","steps":["trace[1110744450] 'process raft request' (duration: 102.687539ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:41.913075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8073} {"level":"info","ts":"2026-10-15T14:33:41.993935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8073,"took":"76.174575ms","hash":2375912419,"current-db-size-bytes":56442880,"current-db-size":"56 MB","current-db-size-in-use-bytes":29057024,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T14:33:41.994004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2375912419,"revision":8073,"compact-revision":5894} {"level":"info","ts":"2026-10-15T14:34:17.875006Z","caller":"traceutil/trace.go:171","msg":"trace[1175022870] transaction","detail":"{read_only:false; response_revision:10882; number_of_response:1; }","duration":"104.682459ms","start":"2026-10-15T14:34:17.770301Z","end":"2026-10-15T14:34:17.874983Z","steps":["trace[1175022870] 'process raft request' (duration: 104.533266ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:35:26.286849Z","caller":"traceutil/trace.go:171","msg":"trace[754645433] transaction","detail":"{read_only:false; response_revision:11552; number_of_response:1; }","duration":"113.511816ms","start":"2026-10-15T14:35:26.173307Z","end":"2026-10-15T14:35:26.286818Z","steps":["trace[754645433] 'process raft request' (duration: 113.287021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:35:27.177064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.831247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:35:27.177164Z","caller":"traceutil/trace.go:171","msg":"trace[474065476] transaction","detail":"{read_only:false; response_revision:11554; number_of_response:1; }","duration":"411.62662ms","start":"2026-10-15T14:35:26.765519Z","end":"2026-10-15T14:35:27.177145Z","steps":["trace[474065476] 'process raft request' (duration: 144.635019ms)","trace[474065476] 'compare' (duration: 266.664204ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:35:27.177223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:35:26.765502Z","time spent":"411.692951ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:38:41.920995Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10561} {"level":"info","ts":"2026-10-15T14:38:42.015334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10561,"took":"91.496405ms","hash":4196671869,"current-db-size-bytes":56442880,"current-db-size":"56 MB","current-db-size-in-use-bytes":27865088,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:38:42.015426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4196671869,"revision":10561,"compact-revision":8073} {"level":"info","ts":"2026-10-15T14:41:39.125778Z","caller":"traceutil/trace.go:171","msg":"trace[634585461] transaction","detail":"{read_only:false; response_revision:14060; number_of_response:1; }","duration":"238.283433ms","start":"2026-10-15T14:41:38.887475Z","end":"2026-10-15T14:41:39.125758Z","steps":["trace[634585461] 'process raft request' (duration: 237.978496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:39.125993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.351716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:41:39.126062Z","caller":"traceutil/trace.go:171","msg":"trace[295964017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14060; }","duration":"151.458878ms","start":"2026-10-15T14:41:38.974588Z","end":"2026-10-15T14:41:39.126047Z","steps":["trace[295964017] 'agreement among raft nodes before linearized reading' (duration: 151.325805ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:41:39.125663Z","caller":"traceutil/trace.go:171","msg":"trace[395076357] linearizableReadLoop","detail":"{readStateIndex:14546; appliedIndex:14545; }","duration":"151.022538ms","start":"2026-10-15T14:41:38.974617Z","end":"2026-10-15T14:41:39.125639Z","steps":["trace[395076357] 'read index received' (duration: 150.876545ms)","trace[395076357] 'applied index is now lower than readState.Index' (duration: 145.213µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:41:39.153769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.57274ms","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-10-15T14:41:39.153799Z","caller":"traceutil/trace.go:171","msg":"trace[141195542] transaction","detail":"{read_only:false; response_revision:14061; number_of_response:1; }","duration":"129.583663ms","start":"2026-10-15T14:41:39.02419Z","end":"2026-10-15T14:41:39.153773Z","steps":["trace[141195542] 'process raft request' (duration: 129.265426ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:41:39.153885Z","caller":"traceutil/trace.go:171","msg":"trace[2133175283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14061; }","duration":"114.730624ms","start":"2026-10-15T14:41:39.039132Z","end":"2026-10-15T14:41:39.153862Z","steps":["trace[2133175283] 'agreement among raft nodes before linearized reading' (duration: 114.502849ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:41:55.252861Z","caller":"traceutil/trace.go:171","msg":"trace[1454789287] linearizableReadLoop","detail":"{readStateIndex:14650; appliedIndex:14649; }","duration":"276.684464ms","start":"2026-10-15T14:41:54.976156Z","end":"2026-10-15T14:41:55.25284Z","steps":["trace[1454789287] 'read index received' (duration: 276.556761ms)","trace[1454789287] 'applied index is now lower than readState.Index' (duration: 126.813µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:41:55.252982Z","caller":"traceutil/trace.go:171","msg":"trace[1643112851] transaction","detail":"{read_only:false; response_revision:14159; number_of_response:1; }","duration":"327.336008ms","start":"2026-10-15T14:41:54.925608Z","end":"2026-10-15T14:41:55.252944Z","steps":["trace[1643112851] 'process raft request' (duration: 327.082772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:55.253134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:41:54.925582Z","time spent":"327.476391ms","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:41:55.25318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.960141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:41:55.253603Z","caller":"traceutil/trace.go:171","msg":"trace[902378550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14159; }","duration":"277.256938ms","start":"2026-10-15T14:41:54.976098Z","end":"2026-10-15T14:41:55.253354Z","steps":["trace[902378550] 'agreement among raft nodes before linearized reading' (duration: 276.880059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:55.282315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.077468ms","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-10-15T14:41:55.282399Z","caller":"traceutil/trace.go:171","msg":"trace[939359296] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14160; }","duration":"256.220791ms","start":"2026-10-15T14:41:55.026158Z","end":"2026-10-15T14:41:55.282379Z","steps":["trace[939359296] 'agreement among raft nodes before linearized reading' (duration: 255.966106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:55.516217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.396394ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-15T14:41:55.51633Z","caller":"traceutil/trace.go:171","msg":"trace[1864792113] linearizableReadLoop","detail":"{readStateIndex:14652; appliedIndex:14651; }","duration":"231.538234ms","start":"2026-10-15T14:41:55.284772Z","end":"2026-10-15T14:41:55.51631Z","steps":["trace[1864792113] 'read index received' (duration: 58.054788ms)","trace[1864792113] 'applied index is now lower than readState.Index' (duration: 173.481896ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:41:55.516535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.756568ms","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-10-15T14:41:55.51657Z","caller":"traceutil/trace.go:171","msg":"trace[1714060609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14160; }","duration":"231.83068ms","start":"2026-10-15T14:41:55.284731Z","end":"2026-10-15T14:41:55.516561Z","steps":["trace[1714060609] 'agreement among raft nodes before linearized reading' (duration: 231.675487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:55.516881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.204766ms","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-10-15T14:41:55.516919Z","caller":"traceutil/trace.go:171","msg":"trace[276689118] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14160; }","duration":"231.269967ms","start":"2026-10-15T14:41:55.285637Z","end":"2026-10-15T14:41:55.516907Z","steps":["trace[276689118] 'agreement among raft nodes before linearized reading' (duration: 231.143114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:55.517033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.875925ms","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-10-15T14:41:55.517102Z","caller":"traceutil/trace.go:171","msg":"trace[1156984473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14160; }","duration":"151.003748ms","start":"2026-10-15T14:41:55.366082Z","end":"2026-10-15T14:41:55.517086Z","steps":["trace[1156984473] 'agreement among raft nodes before linearized reading' (duration: 150.765363ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:41:55.517687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.201449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kcnvc\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:41:55.517772Z","caller":"traceutil/trace.go:171","msg":"trace[239922029] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kcnvc; range_end:; response_count:1; response_revision:14160; }","duration":"205.886365ms","start":"2026-10-15T14:41:55.311864Z","end":"2026-10-15T14:41:55.51775Z","steps":["trace[239922029] 'agreement among raft nodes before linearized reading' (duration: 205.018985ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:42:12.725649Z","caller":"traceutil/trace.go:171","msg":"trace[159229505] transaction","detail":"{read_only:false; response_revision:14376; number_of_response:1; }","duration":"415.232497ms","start":"2026-10-15T14:42:12.310386Z","end":"2026-10-15T14:42:12.725619Z","steps":["trace[159229505] 'process raft request' (duration: 415.073493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:12.725803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:42:12.310359Z","time spent":"415.370559ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:42:12.725762Z","caller":"traceutil/trace.go:171","msg":"trace[1714398466] linearizableReadLoop","detail":"{readStateIndex:14879; appliedIndex:14879; }","duration":"269.073506ms","start":"2026-10-15T14:42:12.456657Z","end":"2026-10-15T14:42:12.72573Z","steps":["trace[1714398466] 'read index received' (duration: 269.061406ms)","trace[1714398466] 'applied index is now lower than readState.Index' (duration: 9.57µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:42:12.72772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.02302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:92 size:990790"} {"level":"info","ts":"2026-10-15T14:42:12.727865Z","caller":"traceutil/trace.go:171","msg":"trace[1811202086] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:92; response_revision:14376; }","duration":"271.216573ms","start":"2026-10-15T14:42:12.456623Z","end":"2026-10-15T14:42:12.72784Z","steps":["trace[1811202086] 'agreement among raft nodes before linearized reading' (duration: 269.333801ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:42:12.735965Z","caller":"traceutil/trace.go:171","msg":"trace[215903345] transaction","detail":"{read_only:false; response_revision:14377; number_of_response:1; }","duration":"239.701075ms","start":"2026-10-15T14:42:12.496243Z","end":"2026-10-15T14:42:12.735944Z","steps":["trace[215903345] 'process raft request' (duration: 239.534821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:42:12.735979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.801387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:42:12.736042Z","caller":"traceutil/trace.go:171","msg":"trace[403941934] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:14377; }","duration":"216.90012ms","start":"2026-10-15T14:42:12.519123Z","end":"2026-10-15T14:42:12.736024Z","steps":["trace[403941934] 'agreement among raft nodes before linearized reading' (duration: 216.782607ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:43:41.927269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12825} {"level":"info","ts":"2026-10-15T14:43:42.014237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12825,"took":"84.572836ms","hash":3538374738,"current-db-size-bytes":56442880,"current-db-size":"56 MB","current-db-size-in-use-bytes":30085120,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T14:43:42.014306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3538374738,"revision":12825,"compact-revision":10561} {"level":"info","ts":"2026-10-15T14:43:44.229283Z","caller":"traceutil/trace.go:171","msg":"trace[1249703251] transaction","detail":"{read_only:false; response_revision:15280; number_of_response:1; }","duration":"111.750238ms","start":"2026-10-15T14:43:44.117506Z","end":"2026-10-15T14:43:44.229256Z","steps":["trace[1249703251] 'process raft request' (duration: 111.577454ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:43:44.41602Z","caller":"traceutil/trace.go:171","msg":"trace[1579372470] transaction","detail":"{read_only:false; response_revision:15283; number_of_response:1; }","duration":"181.817551ms","start":"2026-10-15T14:43:44.234163Z","end":"2026-10-15T14:43:44.415981Z","steps":["trace[1579372470] 'process raft request' (duration: 85.898834ms)","trace[1579372470] 'compare' (duration: 95.647071ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:43:44.416253Z","caller":"traceutil/trace.go:171","msg":"trace[310212091] transaction","detail":"{read_only:false; response_revision:15285; number_of_response:1; }","duration":"162.486852ms","start":"2026-10-15T14:43:44.253737Z","end":"2026-10-15T14:43:44.416224Z","steps":["trace[310212091] 'process raft request' (duration: 162.192256ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:43:44.416334Z","caller":"traceutil/trace.go:171","msg":"trace[669956576] transaction","detail":"{read_only:false; response_revision:15284; number_of_response:1; }","duration":"181.973915ms","start":"2026-10-15T14:43:44.234325Z","end":"2026-10-15T14:43:44.416299Z","steps":["trace[669956576] 'process raft request' (duration: 181.525955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.733533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.617852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T14:43:50.733964Z","caller":"traceutil/trace.go:171","msg":"trace[872697180] linearizableReadLoop","detail":"{readStateIndex:15843; appliedIndex:15842; }","duration":"435.921755ms","start":"2026-10-15T14:43:50.298015Z","end":"2026-10-15T14:43:50.733937Z","steps":["trace[872697180] 'read index received' (duration: 232.880023ms)","trace[872697180] 'applied index is now lower than readState.Index' (duration: 203.040202ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:43:50.734178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.14745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T14:43:50.734253Z","caller":"traceutil/trace.go:171","msg":"trace[1391861216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15314; }","duration":"436.258382ms","start":"2026-10-15T14:43:50.297975Z","end":"2026-10-15T14:43:50.734233Z","steps":["trace[1391861216] 'agreement among raft nodes before linearized reading' (duration: 436.044777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.73429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:43:50.29796Z","time spent":"436.322004ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T14:43:50.734705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.369645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T14:43:50.734757Z","caller":"traceutil/trace.go:171","msg":"trace[758552388] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15315; }","duration":"109.440237ms","start":"2026-10-15T14:43:50.625307Z","end":"2026-10-15T14:43:50.734747Z","steps":["trace[758552388] 'agreement among raft nodes before linearized reading' (duration: 109.265853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.734809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.32716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19540"} {"level":"warn","ts":"2026-10-15T14:43:50.734791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.566308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-xlcr4\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-15T14:43:50.734869Z","caller":"traceutil/trace.go:171","msg":"trace[651020620] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:15315; }","duration":"204.381131ms","start":"2026-10-15T14:43:50.530475Z","end":"2026-10-15T14:43:50.734856Z","steps":["trace[651020620] 'agreement among raft nodes before linearized reading' (duration: 204.271819ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:43:50.734903Z","caller":"traceutil/trace.go:171","msg":"trace[1446892814] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-xlcr4; range_end:; response_count:1; response_revision:15315; }","duration":"182.700081ms","start":"2026-10-15T14:43:50.552174Z","end":"2026-10-15T14:43:50.734874Z","steps":["trace[1446892814] 'agreement among raft nodes before linearized reading' (duration: 182.444956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.73472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.483316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-15T14:43:50.734819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.100494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T14:43:50.735048Z","caller":"traceutil/trace.go:171","msg":"trace[2018610183] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15315; }","duration":"435.831093ms","start":"2026-10-15T14:43:50.299203Z","end":"2026-10-15T14:43:50.735034Z","steps":["trace[2018610183] 'agreement among raft nodes before linearized reading' (duration: 435.372553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.735048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.741219ms","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-10-15T14:43:50.735065Z","caller":"traceutil/trace.go:171","msg":"trace[1405400626] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15315; }","duration":"111.373329ms","start":"2026-10-15T14:43:50.623667Z","end":"2026-10-15T14:43:50.735041Z","steps":["trace[1405400626] 'agreement among raft nodes before linearized reading' (duration: 111.031821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.735088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:43:50.299197Z","time spent":"435.874104ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T14:43:50.735101Z","caller":"traceutil/trace.go:171","msg":"trace[1576703441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15315; }","duration":"296.825601ms","start":"2026-10-15T14:43:50.438261Z","end":"2026-10-15T14:43:50.735086Z","steps":["trace[1576703441] 'agreement among raft nodes before linearized reading' (duration: 296.701898ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:43:50.735184Z","caller":"traceutil/trace.go:171","msg":"trace[100731525] transaction","detail":"{read_only:false; response_revision:15315; number_of_response:1; }","duration":"296.665978ms","start":"2026-10-15T14:43:50.438487Z","end":"2026-10-15T14:43:50.735153Z","steps":["trace[100731525] 'process raft request' (duration: 295.40982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.735194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.6686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:43:50.735268Z","caller":"traceutil/trace.go:171","msg":"trace[532702998] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15315; }","duration":"113.775683ms","start":"2026-10-15T14:43:50.621471Z","end":"2026-10-15T14:43:50.735247Z","steps":["trace[532702998] 'agreement among raft nodes before linearized reading' (duration: 113.681571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.735381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.63183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T14:43:50.735412Z","caller":"traceutil/trace.go:171","msg":"trace[408747563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15315; }","duration":"293.691541ms","start":"2026-10-15T14:43:50.44171Z","end":"2026-10-15T14:43:50.735401Z","steps":["trace[408747563] 'agreement among raft nodes before linearized reading' (duration: 293.592179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:43:50.735761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.400628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-xlcr4\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-15T14:43:50.735818Z","caller":"traceutil/trace.go:171","msg":"trace[542883412] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-xlcr4; range_end:; response_count:1; response_revision:15315; }","duration":"110.527051ms","start":"2026-10-15T14:43:50.625273Z","end":"2026-10-15T14:43:50.7358Z","steps":["trace[542883412] 'agreement among raft nodes before linearized reading' (duration: 109.620901ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:41.935125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15269} {"level":"info","ts":"2026-10-15T14:48:42.036982Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15269,"took":"98.793265ms","hash":935227924,"current-db-size-bytes":56442880,"current-db-size":"56 MB","current-db-size-in-use-bytes":32264192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:48:42.037039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":935227924,"revision":15269,"compact-revision":12825} {"level":"info","ts":"2026-10-15T14:49:28.759836Z","caller":"traceutil/trace.go:171","msg":"trace[1889052467] transaction","detail":"{read_only:false; response_revision:17847; number_of_response:1; }","duration":"171.883842ms","start":"2026-10-15T14:49:28.587907Z","end":"2026-10-15T14:49:28.759791Z","steps":["trace[1889052467] 'process raft request' (duration: 141.243455ms)","trace[1889052467] 'compare' (duration: 30.337581ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:49:28.783984Z","caller":"traceutil/trace.go:171","msg":"trace[1106583699] transaction","detail":"{read_only:false; response_revision:17848; number_of_response:1; }","duration":"178.461178ms","start":"2026-10-15T14:49:28.605499Z","end":"2026-10-15T14:49:28.78396Z","steps":["trace[1106583699] 'process raft request' (duration: 178.306914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:29.44826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.191949ms","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:8"} {"level":"info","ts":"2026-10-15T14:49:29.448341Z","caller":"traceutil/trace.go:171","msg":"trace[1984213297] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:17850; }","duration":"215.284621ms","start":"2026-10-15T14:49:29.233036Z","end":"2026-10-15T14:49:29.448321Z","steps":["trace[1984213297] 'count revisions from in-memory index tree' (duration: 215.109117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:29.448502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.802158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-xlcr4\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-10-15T14:49:29.448603Z","caller":"traceutil/trace.go:171","msg":"trace[1331197733] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-xlcr4; range_end:; response_count:1; response_revision:17850; }","duration":"218.952692ms","start":"2026-10-15T14:49:29.229628Z","end":"2026-10-15T14:49:29.448581Z","steps":["trace[1331197733] 'range keys from in-memory index tree' (duration: 218.557634ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:52:21.836572Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"282167b6747c9cda","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:52:21.845592Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-10-15T14:52:21.845716Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-10-15T14:53:41.94204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17501} {"level":"info","ts":"2026-10-15T14:53:42.048795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17501,"took":"104.366253ms","hash":3321243470,"current-db-size-bytes":56442880,"current-db-size":"56 MB","current-db-size-in-use-bytes":34430976,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T14:53:42.048889Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3321243470,"revision":17501,"compact-revision":15269} {"level":"info","ts":"2026-10-15T14:55:55.406121Z","caller":"traceutil/trace.go:171","msg":"trace[3610257] linearizableReadLoop","detail":"{readStateIndex:21439; appliedIndex:21438; }","duration":"319.254114ms","start":"2026-10-15T14:55:55.08684Z","end":"2026-10-15T14:55:55.406094Z","steps":["trace[3610257] 'read index received' (duration: 319.183843ms)","trace[3610257] 'applied index is now lower than readState.Index' (duration: 68.981µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:55.406349Z","caller":"traceutil/trace.go:171","msg":"trace[140686076] transaction","detail":"{read_only:false; response_revision:20732; number_of_response:1; }","duration":"390.894885ms","start":"2026-10-15T14:55:55.015408Z","end":"2026-10-15T14:55:55.406303Z","steps":["trace[140686076] 'process raft request' (duration: 390.514826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:55.406503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.656163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-15T14:55:55.406581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:55.015381Z","time spent":"391.072169ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:55:55.406624Z","caller":"traceutil/trace.go:171","msg":"trace[254689077] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20732; }","duration":"319.831207ms","start":"2026-10-15T14:55:55.086771Z","end":"2026-10-15T14:55:55.406602Z","steps":["trace[254689077] 'agreement among raft nodes before linearized reading' (duration: 319.544951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:55.406675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:55.08675Z","time spent":"319.915269ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T14:55:55.751124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.390168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T14:55:55.751324Z","caller":"traceutil/trace.go:171","msg":"trace[1425262982] transaction","detail":"{read_only:false; response_revision:20733; number_of_response:1; }","duration":"587.486643ms","start":"2026-10-15T14:55:55.163807Z","end":"2026-10-15T14:55:55.751294Z","steps":["trace[1425262982] 'process raft request' (duration: 412.85178ms)","trace[1425262982] 'compare' (duration: 174.098091ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:55.751383Z","caller":"traceutil/trace.go:171","msg":"trace[678884823] transaction","detail":"{read_only:false; response_revision:20735; number_of_response:1; }","duration":"185.8283ms","start":"2026-10-15T14:55:55.565538Z","end":"2026-10-15T14:55:55.751366Z","steps":["trace[678884823] 'process raft request' (duration: 185.797179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:55.751461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:55.16378Z","time spent":"587.608835ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:55:55.751546Z","caller":"traceutil/trace.go:171","msg":"trace[1954360901] linearizableReadLoop","detail":"{readStateIndex:21440; appliedIndex:21439; }","duration":"345.32662ms","start":"2026-10-15T14:55:55.406211Z","end":"2026-10-15T14:55:55.751538Z","steps":["trace[1954360901] 'read index received' (duration: 170.539853ms)","trace[1954360901] 'applied index is now lower than readState.Index' (duration: 174.785777ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:55.751775Z","caller":"traceutil/trace.go:171","msg":"trace[1782051112] transaction","detail":"{read_only:false; response_revision:20734; number_of_response:1; }","duration":"340.076144ms","start":"2026-10-15T14:55:55.41169Z","end":"2026-10-15T14:55:55.751766Z","steps":["trace[1782051112] 'process raft request' (duration: 339.566403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:55.751844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:55.411674Z","time spent":"340.124026ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:55:55.752008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.986624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.95\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-15T14:55:55.752035Z","caller":"traceutil/trace.go:171","msg":"trace[1451862067] range","detail":"{range_begin:/registry/masterleases/162.253.55.95; range_end:; response_count:1; response_revision:20735; }","duration":"467.052776ms","start":"2026-10-15T14:55:55.284976Z","end":"2026-10-15T14:55:55.752029Z","steps":["trace[1451862067] 'agreement among raft nodes before linearized reading' (duration: 466.959334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:55.752054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:55.284954Z","time spent":"467.094397ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.95\" "} {"level":"warn","ts":"2026-10-15T14:55:55.752209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.917533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T14:55:55.752315Z","caller":"traceutil/trace.go:171","msg":"trace[781737623] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20735; }","duration":"191.070976ms","start":"2026-10-15T14:55:55.561221Z","end":"2026-10-15T14:55:55.752292Z","steps":["trace[781737623] 'agreement among raft nodes before linearized reading' (duration: 190.79258ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:55.929662Z","caller":"traceutil/trace.go:171","msg":"trace[802022432] linearizableReadLoop","detail":"{readStateIndex:21443; appliedIndex:21442; }","duration":"173.702783ms","start":"2026-10-15T14:55:55.75594Z","end":"2026-10-15T14:55:55.929642Z","steps":["trace[802022432] 'read index received' (duration: 140.642043ms)","trace[802022432] 'applied index is now lower than readState.Index' (duration: 33.05897ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:55:55.929919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.958408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T14:55:55.929961Z","caller":"traceutil/trace.go:171","msg":"trace[1695078394] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20736; }","duration":"174.03608ms","start":"2026-10-15T14:55:55.755915Z","end":"2026-10-15T14:55:55.929951Z","steps":["trace[1695078394] 'agreement among raft nodes before linearized reading' (duration: 173.811585ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:56.219983Z","caller":"traceutil/trace.go:171","msg":"trace[1606798527] linearizableReadLoop","detail":"{readStateIndex:21445; appliedIndex:21444; }","duration":"288.861233ms","start":"2026-10-15T14:55:55.9311Z","end":"2026-10-15T14:55:56.219961Z","steps":["trace[1606798527] 'read index received' (duration: 288.541676ms)","trace[1606798527] 'applied index is now lower than readState.Index' (duration: 318.537µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:56.220034Z","caller":"traceutil/trace.go:171","msg":"trace[1973529059] transaction","detail":"{read_only:false; response_revision:20737; number_of_response:1; }","duration":"289.451796ms","start":"2026-10-15T14:55:55.930548Z","end":"2026-10-15T14:55:56.22Z","steps":["trace[1973529059] 'process raft request' (duration: 289.19525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:56.220279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.16341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19016"} {"level":"info","ts":"2026-10-15T14:55:56.220319Z","caller":"traceutil/trace.go:171","msg":"trace[928964482] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20737; }","duration":"289.239372ms","start":"2026-10-15T14:55:55.931071Z","end":"2026-10-15T14:55:56.22031Z","steps":["trace[928964482] 'agreement among raft nodes before linearized reading' (duration: 289.014927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:56.2203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.17838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:56.220569Z","caller":"traceutil/trace.go:171","msg":"trace[746424505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20737; }","duration":"245.487216ms","start":"2026-10-15T14:55:55.975033Z","end":"2026-10-15T14:55:56.220521Z","steps":["trace[746424505] 'agreement among raft nodes before linearized reading' (duration: 245.151349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:56.220645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.664379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-15T14:55:56.220627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.180251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-15T14:55:56.220696Z","caller":"traceutil/trace.go:171","msg":"trace[1770479826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20737; }","duration":"181.749241ms","start":"2026-10-15T14:55:56.038934Z","end":"2026-10-15T14:55:56.220683Z","steps":["trace[1770479826] 'agreement among raft nodes before linearized reading' (duration: 181.548667ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:56.22072Z","caller":"traceutil/trace.go:171","msg":"trace[1565506874] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:20737; }","duration":"139.308874ms","start":"2026-10-15T14:55:56.08139Z","end":"2026-10-15T14:55:56.220699Z","steps":["trace[1565506874] 'agreement among raft nodes before linearized reading' (duration: 139.047239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:56.220586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.849989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:56.220844Z","caller":"traceutil/trace.go:171","msg":"trace[988220314] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:20737; }","duration":"214.118845ms","start":"2026-10-15T14:55:56.006689Z","end":"2026-10-15T14:55:56.220808Z","steps":["trace[988220314] 'agreement among raft nodes before linearized reading' (duration: 213.841609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:56.221408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.01142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T14:55:56.221499Z","caller":"traceutil/trace.go:171","msg":"trace[2012853498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20737; }","duration":"174.178284ms","start":"2026-10-15T14:55:56.0473Z","end":"2026-10-15T14:55:56.221478Z","steps":["trace[2012853498] 'agreement among raft nodes before linearized reading' (duration: 173.051929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:56.489776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.669302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T14:55:56.489914Z","caller":"traceutil/trace.go:171","msg":"trace[1022614173] linearizableReadLoop","detail":"{readStateIndex:21446; appliedIndex:21445; }","duration":"263.462634ms","start":"2026-10-15T14:55:56.226435Z","end":"2026-10-15T14:55:56.489898Z","steps":["trace[1022614173] 'read index received' (duration: 128.68941ms)","trace[1022614173] 'applied index is now lower than readState.Index' (duration: 134.772024ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:55:56.490019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.592977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T14:55:56.490011Z","caller":"traceutil/trace.go:171","msg":"trace[439595740] transaction","detail":"{read_only:false; response_revision:20738; number_of_response:1; }","duration":"264.18311ms","start":"2026-10-15T14:55:56.225793Z","end":"2026-10-15T14:55:56.489977Z","steps":["trace[439595740] 'process raft request' (duration: 129.228732ms)","trace[439595740] 'compare' (duration: 134.402645ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:56.490049Z","caller":"traceutil/trace.go:171","msg":"trace[986657254] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:20738; }","duration":"263.652658ms","start":"2026-10-15T14:55:56.226385Z","end":"2026-10-15T14:55:56.490037Z","steps":["trace[986657254] 'agreement among raft nodes before linearized reading' (duration: 263.582846ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:56.492283Z","caller":"traceutil/trace.go:171","msg":"trace[1554731006] transaction","detail":"{read_only:false; response_revision:20739; number_of_response:1; }","duration":"264.974837ms","start":"2026-10-15T14:55:56.227281Z","end":"2026-10-15T14:55:56.492256Z","steps":["trace[1554731006] 'process raft request' (duration: 264.761692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:56.492301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.024318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-15T14:55:56.492358Z","caller":"traceutil/trace.go:171","msg":"trace[583041208] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:20739; }","duration":"265.111641ms","start":"2026-10-15T14:55:56.227231Z","end":"2026-10-15T14:55:56.492342Z","steps":["trace[583041208] 'agreement among raft nodes before linearized reading' (duration: 264.932947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:56.492601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.144991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:56.492679Z","caller":"traceutil/trace.go:171","msg":"trace[204037715] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20739; }","duration":"178.250174ms","start":"2026-10-15T14:55:56.314408Z","end":"2026-10-15T14:55:56.492658Z","steps":["trace[204037715] 'agreement among raft nodes before linearized reading' (duration: 178.116611ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:56.755238Z","caller":"traceutil/trace.go:171","msg":"trace[833874639] transaction","detail":"{read_only:false; response_revision:20740; number_of_response:1; }","duration":"136.312718ms","start":"2026-10-15T14:55:56.618892Z","end":"2026-10-15T14:55:56.755205Z","steps":["trace[833874639] 'process raft request' (duration: 115.723594ms)","trace[833874639] 'compare' (duration: 20.465681ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:57:06.379733Z","caller":"traceutil/trace.go:171","msg":"trace[863992242] transaction","detail":"{read_only:false; response_revision:21371; number_of_response:1; }","duration":"111.191453ms","start":"2026-10-15T14:57:06.268515Z","end":"2026-10-15T14:57:06.379706Z","steps":["trace[863992242] 'process raft request' (duration: 111.006679ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:41.956284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19982} {"level":"info","ts":"2026-10-15T14:58:42.074619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19982,"took":"114.941576ms","hash":322805846,"current-db-size-bytes":56442880,"current-db-size":"56 MB","current-db-size-in-use-bytes":30515200,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T14:58:42.074684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":322805846,"revision":19982,"compact-revision":17501} {"level":"info","ts":"2026-10-15T14:59:44.060013Z","caller":"traceutil/trace.go:171","msg":"trace[1728721104] transaction","detail":"{read_only:false; response_revision:23130; number_of_response:1; }","duration":"130.802237ms","start":"2026-10-15T14:59:43.929173Z","end":"2026-10-15T14:59:44.059975Z","steps":["trace[1728721104] 'process raft request' (duration: 130.564732ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:59:44.480657Z","caller":"traceutil/trace.go:171","msg":"trace[502105931] linearizableReadLoop","detail":"{readStateIndex:23912; appliedIndex:23911; }","duration":"356.219ms","start":"2026-10-15T14:59:44.124402Z","end":"2026-10-15T14:59:44.480621Z","steps":["trace[502105931] 'read index received' (duration: 320.482789ms)","trace[502105931] 'applied index is now lower than readState.Index' (duration: 35.734911ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:59:44.481147Z","caller":"traceutil/trace.go:171","msg":"trace[622053584] transaction","detail":"{read_only:false; response_revision:23132; number_of_response:1; }","duration":"397.482144ms","start":"2026-10-15T14:59:44.083644Z","end":"2026-10-15T14:59:44.481126Z","steps":["trace[622053584] 'process raft request' (duration: 361.246011ms)","trace[622053584] 'compare' (duration: 35.605899ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:59:44.481346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:59:44.083631Z","time spent":"397.604477ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:59:44.481858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.247923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-15T14:59:44.481905Z","caller":"traceutil/trace.go:171","msg":"trace[781745261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23132; }","duration":"357.57057ms","start":"2026-10-15T14:59:44.124321Z","end":"2026-10-15T14:59:44.481891Z","steps":["trace[781745261] 'agreement among raft nodes before linearized reading' (duration: 357.225712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:59:44.481934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:59:44.124297Z","time spent":"357.629451ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T14:59:44.482122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.429781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-10-15T14:59:44.482156Z","caller":"traceutil/trace.go:171","msg":"trace[562102044] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:23132; }","duration":"145.507663ms","start":"2026-10-15T14:59:44.33664Z","end":"2026-10-15T14:59:44.482147Z","steps":["trace[562102044] 'agreement among raft nodes before linearized reading' (duration: 145.433862ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:55.04459Z","caller":"traceutil/trace.go:171","msg":"trace[1203320195] linearizableReadLoop","detail":"{readStateIndex:25940; appliedIndex:25939; }","duration":"271.407072ms","start":"2026-10-15T15:02:54.773154Z","end":"2026-10-15T15:02:55.044561Z","steps":["trace[1203320195] 'read index received' (duration: 271.141226ms)","trace[1203320195] 'applied index is now lower than readState.Index' (duration: 264.676µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:02:55.04472Z","caller":"traceutil/trace.go:171","msg":"trace[940347938] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"464.18716ms","start":"2026-10-15T15:02:54.580467Z","end":"2026-10-15T15:02:55.044654Z","steps":["trace[940347938] 'process raft request' (duration: 463.920314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.044865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.686028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T15:02:55.04491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:54.580449Z","time spent":"464.353724ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:02:55.044948Z","caller":"traceutil/trace.go:171","msg":"trace[314643012] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25114; }","duration":"271.79181ms","start":"2026-10-15T15:02:54.773138Z","end":"2026-10-15T15:02:55.04493Z","steps":["trace[314643012] 'agreement among raft nodes before linearized reading' (duration: 271.545255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.096394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.074902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:02:55.09647Z","caller":"traceutil/trace.go:171","msg":"trace[1823095709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25115; }","duration":"121.209594ms","start":"2026-10-15T15:02:54.975243Z","end":"2026-10-15T15:02:55.096453Z","steps":["trace[1823095709] 'agreement among raft nodes before linearized reading' (duration: 121.077392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.096531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.412159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T15:02:55.096536Z","caller":"traceutil/trace.go:171","msg":"trace[1721803449] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"278.426387ms","start":"2026-10-15T15:02:54.818084Z","end":"2026-10-15T15:02:55.09651Z","steps":["trace[1721803449] 'process raft request' (duration: 249.24693ms)","trace[1721803449] 'compare' (duration: 28.627155ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:02:55.096602Z","caller":"traceutil/trace.go:171","msg":"trace[1760745291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25115; }","duration":"265.515921ms","start":"2026-10-15T15:02:54.831068Z","end":"2026-10-15T15:02:55.096584Z","steps":["trace[1760745291] 'agreement among raft nodes before linearized reading' (duration: 265.283586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.097074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.222425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T15:02:55.09711Z","caller":"traceutil/trace.go:171","msg":"trace[1867989302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25115; }","duration":"270.320798ms","start":"2026-10-15T15:02:54.826778Z","end":"2026-10-15T15:02:55.097099Z","steps":["trace[1867989302] 'agreement among raft nodes before linearized reading' (duration: 270.166714ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:55.350333Z","caller":"traceutil/trace.go:171","msg":"trace[717484952] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"249.65508ms","start":"2026-10-15T15:02:55.100645Z","end":"2026-10-15T15:02:55.3503Z","steps":["trace[717484952] 'process raft request' (duration: 216.324432ms)","trace[717484952] 'compare' (duration: 32.925089ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:02:55.419163Z","caller":"traceutil/trace.go:171","msg":"trace[2143480506] linearizableReadLoop","detail":"{readStateIndex:25943; appliedIndex:25941; }","duration":"262.76336ms","start":"2026-10-15T15:02:55.156374Z","end":"2026-10-15T15:02:55.419137Z","steps":["trace[2143480506] 'read index received' (duration: 160.526055ms)","trace[2143480506] 'applied index is now lower than readState.Index' (duration: 102.236405ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:02:55.419271Z","caller":"traceutil/trace.go:171","msg":"trace[1789701992] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"318.533165ms","start":"2026-10-15T15:02:55.100727Z","end":"2026-10-15T15:02:55.419261Z","steps":["trace[1789701992] 'process raft request' (duration: 318.27325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.419369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:55.100685Z","time spent":"318.612957ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:02:55.419523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.153589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:02:55.419561Z","caller":"traceutil/trace.go:171","msg":"trace[497434062] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25117; }","duration":"263.22687ms","start":"2026-10-15T15:02:55.156323Z","end":"2026-10-15T15:02:55.419549Z","steps":["trace[497434062] 'agreement among raft nodes before linearized reading' (duration: 263.164168ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:55.709007Z","caller":"traceutil/trace.go:171","msg":"trace[1053766174] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"343.412277ms","start":"2026-10-15T15:02:55.365573Z","end":"2026-10-15T15:02:55.708985Z","steps":["trace[1053766174] 'process raft request' (duration: 343.151741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.709155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:55.365548Z","time spent":"343.533179ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:02:55.709154Z","caller":"traceutil/trace.go:171","msg":"trace[1181870662] linearizableReadLoop","detail":"{readStateIndex:25945; appliedIndex:25943; }","duration":"289.906031ms","start":"2026-10-15T15:02:55.419228Z","end":"2026-10-15T15:02:55.709134Z","steps":["trace[1181870662] 'read index received' (duration: 289.513772ms)","trace[1181870662] 'applied index is now lower than readState.Index' (duration: 391.059µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:02:55.709222Z","caller":"traceutil/trace.go:171","msg":"trace[1925802482] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"295.758391ms","start":"2026-10-15T15:02:55.413422Z","end":"2026-10-15T15:02:55.709181Z","steps":["trace[1925802482] 'process raft request' (duration: 295.489035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.709281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.963766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:02:55.709317Z","caller":"traceutil/trace.go:171","msg":"trace[108049391] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25119; }","duration":"417.036048ms","start":"2026-10-15T15:02:55.292269Z","end":"2026-10-15T15:02:55.709305Z","steps":["trace[108049391] 'agreement among raft nodes before linearized reading' (duration: 416.964226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.70936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:55.292248Z","time spent":"417.100159ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:02:55.709406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.719581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.95\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-10-15T15:02:55.709402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.70873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T15:02:55.709495Z","caller":"traceutil/trace.go:171","msg":"trace[1376610996] range","detail":"{range_begin:/registry/masterleases/162.253.55.95; range_end:; response_count:1; response_revision:25119; }","duration":"398.844284ms","start":"2026-10-15T15:02:55.310629Z","end":"2026-10-15T15:02:55.709473Z","steps":["trace[1376610996] 'agreement among raft nodes before linearized reading' (duration: 398.582789ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:02:55.709492Z","caller":"traceutil/trace.go:171","msg":"trace[1212516942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25119; }","duration":"537.833602ms","start":"2026-10-15T15:02:55.171643Z","end":"2026-10-15T15:02:55.709477Z","steps":["trace[1212516942] 'agreement among raft nodes before linearized reading' (duration: 537.628508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.709537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:55.310614Z","time spent":"398.913536ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.95\" "} {"level":"warn","ts":"2026-10-15T15:02:55.709563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:02:55.171624Z","time spent":"537.929205ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T15:02:55.710028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.455076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-15T15:02:55.71009Z","caller":"traceutil/trace.go:171","msg":"trace[1624552164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25120; }","duration":"240.564259ms","start":"2026-10-15T15:02:55.469509Z","end":"2026-10-15T15:02:55.710073Z","steps":["trace[1624552164] 'agreement among raft nodes before linearized reading' (duration: 240.352904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.710029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.785414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T15:02:55.710173Z","caller":"traceutil/trace.go:171","msg":"trace[392365490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25120; }","duration":"171.973649ms","start":"2026-10-15T15:02:55.538184Z","end":"2026-10-15T15:02:55.710158Z","steps":["trace[392365490] 'agreement among raft nodes before linearized reading' (duration: 171.742373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:02:55.710177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.69805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-56b68bcbfc-tnwjx\" ","response":"range_response_count:1 size:8651"} {"level":"info","ts":"2026-10-15T15:02:55.710231Z","caller":"traceutil/trace.go:171","msg":"trace[1495068941] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-56b68bcbfc-tnwjx; range_end:; response_count:1; response_revision:25120; }","duration":"207.800413ms","start":"2026-10-15T15:02:55.502416Z","end":"2026-10-15T15:02:55.710216Z","steps":["trace[1495068941] 'agreement among raft nodes before linearized reading' (duration: 207.594368ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:25.862906Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066a4.wal"} {"level":"info","ts":"2026-10-15T15:03:41.969409Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22048} {"level":"info","ts":"2026-10-15T15:03:42.099448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22048,"took":"126.152654ms","hash":1774323076,"current-db-size-bytes":56864768,"current-db-size":"57 MB","current-db-size-in-use-bytes":45133824,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-15T15:03:42.099535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1774323076,"revision":22048,"compact-revision":19982} {"level":"info","ts":"2026-10-15T15:03:56.159727Z","caller":"traceutil/trace.go:171","msg":"trace[771985628] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"493.48261ms","start":"2026-10-15T15:03:55.666221Z","end":"2026-10-15T15:03:56.159704Z","steps":["trace[771985628] 'process raft request' (duration: 493.361768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.159927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:55.6662Z","time spent":"493.594512ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:03:56.160181Z","caller":"traceutil/trace.go:171","msg":"trace[2071546250] linearizableReadLoop","detail":"{readStateIndex:26508; appliedIndex:26508; }","duration":"388.484215ms","start":"2026-10-15T15:03:55.771679Z","end":"2026-10-15T15:03:56.160164Z","steps":["trace[2071546250] 'read index received' (duration: 388.474744ms)","trace[2071546250] 'applied index is now lower than readState.Index' (duration: 8.27µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:03:56.160394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.703359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T15:03:56.160444Z","caller":"traceutil/trace.go:171","msg":"trace[1451640288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25666; }","duration":"388.795051ms","start":"2026-10-15T15:03:55.771636Z","end":"2026-10-15T15:03:56.160431Z","steps":["trace[1451640288] 'agreement among raft nodes before linearized reading' (duration: 388.619948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.160528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:55.771613Z","time spent":"388.863273ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T15:03:56.644682Z","caller":"traceutil/trace.go:171","msg":"trace[501042656] linearizableReadLoop","detail":"{readStateIndex:26516; appliedIndex:26508; }","duration":"484.43264ms","start":"2026-10-15T15:03:56.160233Z","end":"2026-10-15T15:03:56.644665Z","steps":["trace[501042656] 'read index received' (duration: 472.990186ms)","trace[501042656] 'applied index is now lower than readState.Index' (duration: 11.441814ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:03:56.644849Z","caller":"traceutil/trace.go:171","msg":"trace[588527686] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"669.99879ms","start":"2026-10-15T15:03:55.974843Z","end":"2026-10-15T15:03:56.644842Z","steps":["trace[588527686] 'process raft request' (duration: 658.373092ms)","trace[588527686] 'compare' (duration: 11.209369ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:03:56.645019Z","caller":"traceutil/trace.go:171","msg":"trace[457039167] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"528.497007ms","start":"2026-10-15T15:03:56.116504Z","end":"2026-10-15T15:03:56.645001Z","steps":["trace[457039167] 'process raft request' (duration: 528.138779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.085349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T15:03:56.64509Z","caller":"traceutil/trace.go:171","msg":"trace[1714054847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25674; }","duration":"594.114169ms","start":"2026-10-15T15:03:56.05097Z","end":"2026-10-15T15:03:56.645084Z","steps":["trace[1714054847] 'agreement among raft nodes before linearized reading' (duration: 594.026667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.050921Z","time spent":"594.180351ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T15:03:56.645119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.11649Z","time spent":"528.579058ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:03:56.645106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.620654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:03:56.645171Z","caller":"traceutil/trace.go:171","msg":"trace[951515702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25674; }","duration":"670.715626ms","start":"2026-10-15T15:03:55.974438Z","end":"2026-10-15T15:03:56.645154Z","steps":["trace[951515702] 'agreement among raft nodes before linearized reading' (duration: 670.591683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.810363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-15T15:03:56.64519Z","caller":"traceutil/trace.go:171","msg":"trace[1246734278] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"667.673249ms","start":"2026-10-15T15:03:55.977512Z","end":"2026-10-15T15:03:56.645185Z","steps":["trace[1246734278] 'process raft request' (duration: 667.012434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:55.974424Z","time spent":"670.768617ms","remote":"127.0.0.1:40856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T15:03:56.645218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:55.977506Z","time spent":"667.698819ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:03:56.645239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.105574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T15:03:56.645255Z","caller":"traceutil/trace.go:171","msg":"trace[2044377676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25674; }","duration":"582.145414ms","start":"2026-10-15T15:03:56.063106Z","end":"2026-10-15T15:03:56.645252Z","steps":["trace[2044377676] 'agreement among raft nodes before linearized reading' (duration: 582.107013ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:56.645257Z","caller":"traceutil/trace.go:171","msg":"trace[1282208515] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"666.765128ms","start":"2026-10-15T15:03:55.978487Z","end":"2026-10-15T15:03:56.645252Z","steps":["trace[1282208515] 'process raft request' (duration: 666.061883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.063092Z","time spent":"582.170814ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T15:03:56.645279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:55.978482Z","time spent":"666.784489ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:03:56.645305Z","caller":"traceutil/trace.go:171","msg":"trace[899607425] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"662.057654ms","start":"2026-10-15T15:03:55.983243Z","end":"2026-10-15T15:03:56.645301Z","steps":["trace[899607425] 'process raft request' (duration: 661.345278ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:56.64532Z","caller":"traceutil/trace.go:171","msg":"trace[481896552] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"573.143235ms","start":"2026-10-15T15:03:56.072171Z","end":"2026-10-15T15:03:56.645314Z","steps":["trace[481896552] 'process raft request' (duration: 572.43524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:55.983237Z","time spent":"662.077465ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:03:56.645347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.072152Z","time spent":"573.180866ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:03:56.645381Z","caller":"traceutil/trace.go:171","msg":"trace[803580737] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"665.618083ms","start":"2026-10-15T15:03:55.979758Z","end":"2026-10-15T15:03:56.645377Z","steps":["trace[803580737] 'process raft request' (duration: 664.809255ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:56.645384Z","caller":"traceutil/trace.go:171","msg":"trace[391638975] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"539.315556ms","start":"2026-10-15T15:03:56.106064Z","end":"2026-10-15T15:03:56.64538Z","steps":["trace[391638975] 'process raft request' (duration: 538.560009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:55.979754Z","time spent":"665.640033ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:03:56.645407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.106048Z","time spent":"539.347396ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:03:56.645339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.209138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:03:56.645447Z","caller":"traceutil/trace.go:171","msg":"trace[1613547213] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25674; }","duration":"634.35157ms","start":"2026-10-15T15:03:56.01109Z","end":"2026-10-15T15:03:56.645442Z","steps":["trace[1613547213] 'agreement among raft nodes before linearized reading' (duration: 634.224758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.011076Z","time spent":"634.384872ms","remote":"127.0.0.1:36676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:03:56.64547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:55.974834Z","time spent":"670.035171ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:03:56.645202Z","caller":"traceutil/trace.go:171","msg":"trace[1874438426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25674; }","duration":"579.843513ms","start":"2026-10-15T15:03:56.065352Z","end":"2026-10-15T15:03:56.645196Z","steps":["trace[1874438426] 'agreement among raft nodes before linearized reading' (duration: 579.805162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.645544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.065342Z","time spent":"580.198232ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T15:03:56.666258Z","caller":"traceutil/trace.go:171","msg":"trace[1967348572] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"501.863956ms","start":"2026-10-15T15:03:56.164372Z","end":"2026-10-15T15:03:56.666236Z","steps":["trace[1967348572] 'process raft request' (duration: 501.710753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.666496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.117272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:03:56.666539Z","caller":"traceutil/trace.go:171","msg":"trace[1517485983] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25676; }","duration":"251.207014ms","start":"2026-10-15T15:03:56.415324Z","end":"2026-10-15T15:03:56.666531Z","steps":["trace[1517485983] 'agreement among raft nodes before linearized reading' (duration: 251.139853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.666629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.065749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:03:56.666711Z","caller":"traceutil/trace.go:171","msg":"trace[1196977178] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"176.454799ms","start":"2026-10-15T15:03:56.490238Z","end":"2026-10-15T15:03:56.666693Z","steps":["trace[1196977178] 'process raft request' (duration: 175.955278ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:56.666764Z","caller":"traceutil/trace.go:171","msg":"trace[1251008380] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25676; }","duration":"450.228942ms","start":"2026-10-15T15:03:56.216483Z","end":"2026-10-15T15:03:56.666712Z","steps":["trace[1251008380] 'agreement among raft nodes before linearized reading' (duration: 450.011238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.666872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.216435Z","time spent":"450.369105ms","remote":"127.0.0.1:52802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:03:56.667306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.164355Z","time spent":"501.970759ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:03:56.927797Z","caller":"traceutil/trace.go:171","msg":"trace[1460799982] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"275.666416ms","start":"2026-10-15T15:03:56.652109Z","end":"2026-10-15T15:03:56.927775Z","steps":["trace[1460799982] 'process raft request' (duration: 274.893019ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:56.927862Z","caller":"traceutil/trace.go:171","msg":"trace[75441091] linearizableReadLoop","detail":"{readStateIndex:26521; appliedIndex:26518; }","duration":"261.41079ms","start":"2026-10-15T15:03:56.66643Z","end":"2026-10-15T15:03:56.92784Z","steps":["trace[75441091] 'read index received' (duration: 260.588812ms)","trace[75441091] 'applied index is now lower than readState.Index' (duration: 820.818µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:03:56.928064Z","caller":"traceutil/trace.go:171","msg":"trace[1121351499] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"274.686534ms","start":"2026-10-15T15:03:56.653365Z","end":"2026-10-15T15:03:56.928051Z","steps":["trace[1121351499] 'process raft request' (duration: 274.395657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:56.928118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.884694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:03:56.928261Z","caller":"traceutil/trace.go:171","msg":"trace[1122315581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25679; }","duration":"282.016086ms","start":"2026-10-15T15:03:56.646187Z","end":"2026-10-15T15:03:56.928203Z","steps":["trace[1122315581] 'agreement among raft nodes before linearized reading' (duration: 281.726ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:56.928255Z","caller":"traceutil/trace.go:171","msg":"trace[1426340797] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"275.724458ms","start":"2026-10-15T15:03:56.652498Z","end":"2026-10-15T15:03:56.928223Z","steps":["trace[1426340797] 'process raft request' (duration: 275.164405ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:57.139582Z","caller":"traceutil/trace.go:171","msg":"trace[481366852] linearizableReadLoop","detail":"{readStateIndex:26522; appliedIndex:26521; }","duration":"164.490273ms","start":"2026-10-15T15:03:56.975059Z","end":"2026-10-15T15:03:57.139549Z","steps":["trace[481366852] 'read index received' (duration: 104.515845ms)","trace[481366852] 'applied index is now lower than readState.Index' (duration: 59.973148ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:03:57.139757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.677918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:03:57.139885Z","caller":"traceutil/trace.go:171","msg":"trace[336914964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25680; }","duration":"164.848122ms","start":"2026-10-15T15:03:56.975023Z","end":"2026-10-15T15:03:57.139871Z","steps":["trace[336914964] 'agreement among raft nodes before linearized reading' (duration: 164.625097ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:57.140154Z","caller":"traceutil/trace.go:171","msg":"trace[1506452290] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"466.983014ms","start":"2026-10-15T15:03:56.673157Z","end":"2026-10-15T15:03:57.14014Z","steps":["trace[1506452290] 'process raft request' (duration: 406.485104ms)","trace[1506452290] 'compare' (duration: 59.62688ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:03:57.140251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:56.673138Z","time spent":"467.049796ms","remote":"127.0.0.1:49126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:07:32.566577Z","caller":"traceutil/trace.go:171","msg":"trace[970179095] transaction","detail":"{read_only:false; response_revision:27901; number_of_response:1; }","duration":"109.274786ms","start":"2026-10-15T15:07:32.457256Z","end":"2026-10-15T15:07:32.566531Z","steps":["trace[970179095] 'process raft request' (duration: 109.099152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:37.806358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.308035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:07:37.806463Z","caller":"traceutil/trace.go:171","msg":"trace[1447040947] transaction","detail":"{read_only:false; response_revision:27958; number_of_response:1; }","duration":"133.150554ms","start":"2026-10-15T15:07:37.673294Z","end":"2026-10-15T15:07:37.806445Z","steps":["trace[1447040947] 'compare' (duration: 127.201673ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:37.900449Z","caller":"traceutil/trace.go:171","msg":"trace[324166335] transaction","detail":"{read_only:false; response_revision:27959; number_of_response:1; }","duration":"198.830327ms","start":"2026-10-15T15:07:37.701583Z","end":"2026-10-15T15:07:37.900413Z","steps":["trace[324166335] 'process raft request' (duration: 198.711694ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:37.900456Z","caller":"traceutil/trace.go:171","msg":"trace[1965989572] linearizableReadLoop","detail":"{readStateIndex:28857; appliedIndex:28857; }","duration":"182.70208ms","start":"2026-10-15T15:07:37.717716Z","end":"2026-10-15T15:07:37.900418Z","steps":["trace[1965989572] 'read index received' (duration: 182.68682ms)","trace[1965989572] 'applied index is now lower than readState.Index' (duration: 12.33µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:07:37.900628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.892494ms","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:6"} {"level":"info","ts":"2026-10-15T15:07:37.900669Z","caller":"traceutil/trace.go:171","msg":"trace[771021205] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:27959; }","duration":"182.983036ms","start":"2026-10-15T15:07:37.717675Z","end":"2026-10-15T15:07:37.900658Z","steps":["trace[771021205] 'agreement among raft nodes before linearized reading' (duration: 182.880044ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:37.940417Z","caller":"traceutil/trace.go:171","msg":"trace[59581591] transaction","detail":"{read_only:false; response_revision:27961; number_of_response:1; }","duration":"185.598444ms","start":"2026-10-15T15:07:37.754794Z","end":"2026-10-15T15:07:37.940392Z","steps":["trace[59581591] 'process raft request' (duration: 185.453571ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:37.940491Z","caller":"traceutil/trace.go:171","msg":"trace[1064300477] transaction","detail":"{read_only:false; response_revision:27962; number_of_response:1; }","duration":"185.501692ms","start":"2026-10-15T15:07:37.754965Z","end":"2026-10-15T15:07:37.940467Z","steps":["trace[1064300477] 'process raft request' (duration: 185.341298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:37.940548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.632836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T15:07:37.94064Z","caller":"traceutil/trace.go:171","msg":"trace[206837827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27962; }","duration":"124.781409ms","start":"2026-10-15T15:07:37.815838Z","end":"2026-10-15T15:07:37.940619Z","steps":["trace[206837827] 'agreement among raft nodes before linearized reading' (duration: 124.534363ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:37.940586Z","caller":"traceutil/trace.go:171","msg":"trace[495924402] transaction","detail":"{read_only:false; response_revision:27960; number_of_response:1; }","duration":"214.206417ms","start":"2026-10-15T15:07:37.726365Z","end":"2026-10-15T15:07:37.940572Z","steps":["trace[495924402] 'process raft request' (duration: 185.711507ms)","trace[495924402] 'compare' (duration: 28.020669ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:08:41.981236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25571} {"level":"info","ts":"2026-10-15T15:08:42.148688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25571,"took":"163.317222ms","hash":2455518083,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":35913728,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T15:08:42.14876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2455518083,"revision":25571,"compact-revision":22048} {"level":"info","ts":"2026-10-15T15:10:03.509441Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"282167b6747c9cda","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:10:03.524861Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-10-15T15:10:03.525026Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-10-15T15:13:41.988543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28456} {"level":"info","ts":"2026-10-15T15:13:42.145463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28456,"took":"152.277687ms","hash":1375625158,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:13:42.145537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1375625158,"revision":28456,"compact-revision":25571} {"level":"info","ts":"2026-10-15T15:18:41.998866Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30683} {"level":"info","ts":"2026-10-15T15:18:42.153698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30683,"took":"150.599022ms","hash":4247974542,"current-db-size-bytes":58138624,"current-db-size":"58 MB","current-db-size-in-use-bytes":32456704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:18:42.153765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4247974542,"revision":30683,"compact-revision":28456} {"level":"info","ts":"2026-10-15T15:23:42.005783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32940} {"level":"info","ts":"2026-10-15T15:23:42.265472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32940,"took":"255.875224ms","hash":2372971426,"current-db-size-bytes":59183104,"current-db-size":"59 MB","current-db-size-in-use-bytes":31870976,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:23:42.265528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2372971426,"revision":32940,"compact-revision":30683} {"level":"info","ts":"2026-10-15T15:28:42.015164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35242} {"level":"info","ts":"2026-10-15T15:28:42.156131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35242,"took":"136.649244ms","hash":3810693386,"current-db-size-bytes":59183104,"current-db-size":"59 MB","current-db-size-in-use-bytes":33157120,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T15:28:42.156205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3810693386,"revision":35242,"compact-revision":32940} {"level":"info","ts":"2026-10-15T15:31:31.798907Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"282167b6747c9cda","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:31:31.813666Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-10-15T15:31:31.813818Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-10-15T15:33:40.45684Z","caller":"traceutil/trace.go:171","msg":"trace[576710829] linearizableReadLoop","detail":"{readStateIndex:40970; appliedIndex:40969; }","duration":"140.065294ms","start":"2026-10-15T15:33:40.31676Z","end":"2026-10-15T15:33:40.456826Z","steps":["trace[576710829] 'read index received' (duration: 132.718702ms)","trace[576710829] 'applied index is now lower than readState.Index' (duration: 7.345722ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:33:40.456813Z","caller":"traceutil/trace.go:171","msg":"trace[893979544] transaction","detail":"{read_only:false; response_revision:39736; number_of_response:1; }","duration":"141.596327ms","start":"2026-10-15T15:33:40.315184Z","end":"2026-10-15T15:33:40.45678Z","steps":["trace[893979544] 'process raft request' (duration: 134.303686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:33:40.457068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.287819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/placement-placement-ks-service\" ","response":"range_response_count:1 size:773"} {"level":"info","ts":"2026-10-15T15:33:40.457107Z","caller":"traceutil/trace.go:171","msg":"trace[595648537] range","detail":"{range_begin:/registry/rolebindings/openstack/placement-placement-ks-service; range_end:; response_count:1; response_revision:39736; }","duration":"140.36809ms","start":"2026-10-15T15:33:40.316724Z","end":"2026-10-15T15:33:40.457092Z","steps":["trace[595648537] 'agreement among raft nodes before linearized reading' (duration: 140.153915ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:33:40.457118Z","caller":"traceutil/trace.go:171","msg":"trace[1801696851] transaction","detail":"{read_only:false; response_revision:39737; number_of_response:1; }","duration":"139.846809ms","start":"2026-10-15T15:33:40.317244Z","end":"2026-10-15T15:33:40.457091Z","steps":["trace[1801696851] 'process raft request' (duration: 139.544982ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:33:42.033144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37470} {"level":"info","ts":"2026-10-15T15:33:42.190632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37470,"took":"153.420518ms","hash":1409009590,"current-db-size-bytes":59183104,"current-db-size":"59 MB","current-db-size-in-use-bytes":31698944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:33:42.190694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1409009590,"revision":37470,"compact-revision":35242}