{"level":"warn","ts":"2026-09-10T01:57:57.793404Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:57:57.793499Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.149: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.149:2380","--initial-cluster=instance=https://162.253.55.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.149: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-09-10T01:57:57.79356Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:57:57.793569Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.149:2380"]} {"level":"info","ts":"2026-09-10T01:57:57.793597Z","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-09-10T01:57:57.794194Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.149:2379"]} {"level":"info","ts":"2026-09-10T01:57:57.794275Z","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.149:2380"],"listen-peer-urls":["https://162.253.55.149:2380"],"advertise-client-urls":["https://162.253.55.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.149: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-09-10T01:57:57.798927Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.386515ms"} {"level":"info","ts":"2026-09-10T01:57:57.806491Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab61124f07c17f20","cluster-id":"c5f62e84ad801ad2"} {"level":"info","ts":"2026-09-10T01:57:57.806569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T01:57:57.806588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became follower at term 0"} {"level":"info","ts":"2026-09-10T01:57:57.806596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab61124f07c17f20 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T01:57:57.806602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became follower at term 1"} {"level":"info","ts":"2026-09-10T01:57:57.806636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 switched to configuration voters=(12349171783868448544)"} {"level":"warn","ts":"2026-09-10T01:57:57.814145Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T01:57:57.815943Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T01:57:57.817841Z","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-09-10T01:57:57.820702Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab61124f07c17f20","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T01:57:57.821182Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab61124f07c17f20","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T01:57:57.821332Z","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-09-10T01:57:57.821438Z","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-09-10T01:57:57.821472Z","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-09-10T01:57:57.822753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 switched to configuration voters=(12349171783868448544)"} {"level":"info","ts":"2026-09-10T01:57:57.823757Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5f62e84ad801ad2","local-member-id":"ab61124f07c17f20","added-peer-id":"ab61124f07c17f20","added-peer-peer-urls":["https://162.253.55.149:2380"]} {"level":"info","ts":"2026-09-10T01:57:57.825896Z","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-09-10T01:57:57.826018Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.149:2380"} {"level":"info","ts":"2026-09-10T01:57:57.826057Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.149:2380"} {"level":"info","ts":"2026-09-10T01:57:57.826164Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab61124f07c17f20","initial-advertise-peer-urls":["https://162.253.55.149:2380"],"listen-peer-urls":["https://162.253.55.149:2380"],"advertise-client-urls":["https://162.253.55.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T01:57:57.826222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T01:57:58.706813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T01:57:58.706861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T01:57:58.706885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 received MsgPreVoteResp from ab61124f07c17f20 at term 1"} {"level":"info","ts":"2026-09-10T01:57:58.706897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became candidate at term 2"} {"level":"info","ts":"2026-09-10T01:57:58.706904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 received MsgVoteResp from ab61124f07c17f20 at term 2"} {"level":"info","ts":"2026-09-10T01:57:58.706914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became leader at term 2"} {"level":"info","ts":"2026-09-10T01:57:58.706923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab61124f07c17f20 elected leader ab61124f07c17f20 at term 2"} {"level":"info","ts":"2026-09-10T01:57:58.707917Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:57:58.708947Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:57:58.708953Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab61124f07c17f20","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.149:2379]}","request-path":"/0/members/ab61124f07c17f20/attributes","cluster-id":"c5f62e84ad801ad2","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T01:57:58.70902Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:57:58.709377Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5f62e84ad801ad2","local-member-id":"ab61124f07c17f20","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:57:58.70938Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T01:57:58.709422Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T01:57:58.709471Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:57:58.709496Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:57:58.710961Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T01:57:58.711947Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.149:2379"} {"level":"info","ts":"2026-09-10T01:58:03.681726Z","caller":"traceutil/trace.go:171","msg":"trace[1328637404] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"119.324259ms","start":"2026-09-10T01:58:03.562339Z","end":"2026-09-10T01:58:03.681663Z","steps":["trace[1328637404] 'process raft request' (duration: 108.816492ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.154924Z","caller":"traceutil/trace.go:171","msg":"trace[1926600948] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"137.451621ms","start":"2026-09-10T01:58:42.01745Z","end":"2026-09-10T01:58:42.154902Z","steps":["trace[1926600948] 'process raft request' (duration: 131.731552ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.155733Z","caller":"traceutil/trace.go:171","msg":"trace[736954151] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"130.945261ms","start":"2026-09-10T01:58:42.024777Z","end":"2026-09-10T01:58:42.155722Z","steps":["trace[736954151] 'process raft request' (duration: 130.818798ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.155749Z","caller":"traceutil/trace.go:171","msg":"trace[2016719503] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"131.563108ms","start":"2026-09-10T01:58:42.024156Z","end":"2026-09-10T01:58:42.155719Z","steps":["trace[2016719503] 'process raft request' (duration: 131.384203ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.15581Z","caller":"traceutil/trace.go:171","msg":"trace[1349390656] transaction","detail":"{read_only:false; number_of_response:1; response_revision:547; }","duration":"130.025165ms","start":"2026-09-10T01:58:42.025766Z","end":"2026-09-10T01:58:42.155791Z","steps":["trace[1349390656] 'process raft request' (duration: 129.874371ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.156033Z","caller":"traceutil/trace.go:171","msg":"trace[1624208934] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"129.526232ms","start":"2026-09-10T01:58:42.026497Z","end":"2026-09-10T01:58:42.156023Z","steps":["trace[1624208934] 'process raft request' (duration: 129.175782ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.156113Z","caller":"traceutil/trace.go:171","msg":"trace[2100424195] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"129.241644ms","start":"2026-09-10T01:58:42.026862Z","end":"2026-09-10T01:58:42.156103Z","steps":["trace[2100424195] 'process raft request' (duration: 128.889174ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.156206Z","caller":"traceutil/trace.go:171","msg":"trace[629410057] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"127.612768ms","start":"2026-09-10T01:58:42.028585Z","end":"2026-09-10T01:58:42.156197Z","steps":["trace[629410057] 'process raft request' (duration: 127.348291ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.156565Z","caller":"traceutil/trace.go:171","msg":"trace[1070912997] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"116.169511ms","start":"2026-09-10T01:58:42.040378Z","end":"2026-09-10T01:58:42.156548Z","steps":["trace[1070912997] 'process raft request' (duration: 116.020107ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.156532Z","caller":"traceutil/trace.go:171","msg":"trace[2065211386] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"122.2801ms","start":"2026-09-10T01:58:42.034223Z","end":"2026-09-10T01:58:42.156503Z","steps":["trace[2065211386] 'process raft request' (duration: 121.845718ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.15669Z","caller":"traceutil/trace.go:171","msg":"trace[1752090165] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"114.661858ms","start":"2026-09-10T01:58:42.041998Z","end":"2026-09-10T01:58:42.15666Z","steps":["trace[1752090165] 'process raft request' (duration: 114.498004ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:42.156911Z","caller":"traceutil/trace.go:171","msg":"trace[476025234] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"107.211884ms","start":"2026-09-10T01:58:42.049692Z","end":"2026-09-10T01:58:42.156904Z","steps":["trace[476025234] 'process raft request' (duration: 106.994337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:48.916723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9160498152497197935,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-10T01:58:48.96478Z","caller":"traceutil/trace.go:171","msg":"trace[1815549592] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"549.154158ms","start":"2026-09-10T01:58:48.415602Z","end":"2026-09-10T01:58:48.964756Z","steps":["trace[1815549592] 'read index received' (duration: 539.650644ms)","trace[1815549592] 'applied index is now lower than readState.Index' (duration: 9.502174ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:58:48.964879Z","caller":"traceutil/trace.go:171","msg":"trace[27748941] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"562.682722ms","start":"2026-09-10T01:58:48.402165Z","end":"2026-09-10T01:58:48.964848Z","steps":["trace[27748941] 'process raft request' (duration: 553.060035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:48.965004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.384694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zsckn\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-09-10T01:58:48.964972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.449797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:58:48.965078Z","caller":"traceutil/trace.go:171","msg":"trace[310354675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"487.56778ms","start":"2026-09-10T01:58:48.477491Z","end":"2026-09-10T01:58:48.965059Z","steps":["trace[310354675] 'agreement among raft nodes before linearized reading' (duration: 487.413026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:48.96511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.323351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-10T01:58:48.965113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:58:48.477456Z","time spent":"487.649302ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-10T01:58:48.965143Z","caller":"traceutil/trace.go:171","msg":"trace[836969599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"140.370952ms","start":"2026-09-10T01:58:48.824756Z","end":"2026-09-10T01:58:48.965127Z","steps":["trace[836969599] 'agreement among raft nodes before linearized reading' (duration: 140.30285ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:58:48.965038Z","caller":"traceutil/trace.go:171","msg":"trace[1649548203] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zsckn; range_end:; response_count:1; response_revision:649; }","duration":"549.466186ms","start":"2026-09-10T01:58:48.415562Z","end":"2026-09-10T01:58:48.965028Z","steps":["trace[1649548203] 'agreement among raft nodes before linearized reading' (duration: 549.283611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:48.96542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:58:48.41555Z","time spent":"549.844286ms","remote":"127.0.0.1:48658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-zsckn\" "} {"level":"warn","ts":"2026-09-10T01:58:48.965584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.658039ms","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-09-10T01:58:48.965653Z","caller":"traceutil/trace.go:171","msg":"trace[1495871067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"277.745632ms","start":"2026-09-10T01:58:48.68788Z","end":"2026-09-10T01:58:48.965626Z","steps":["trace[1495871067] 'agreement among raft nodes before linearized reading' (duration: 277.503555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:48.965371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.789408ms","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-09-10T01:58:48.965714Z","caller":"traceutil/trace.go:171","msg":"trace[384453326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"126.168848ms","start":"2026-09-10T01:58:48.839524Z","end":"2026-09-10T01:58:48.965693Z","steps":["trace[384453326] 'agreement among raft nodes before linearized reading' (duration: 125.673985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:58:48.965981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:58:48.402136Z","time spent":"562.808516ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:59:06.523552Z","caller":"traceutil/trace.go:171","msg":"trace[1971946907] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"166.763405ms","start":"2026-09-10T01:59:06.356755Z","end":"2026-09-10T01:59:06.523518Z","steps":["trace[1971946907] 'read index received' (duration: 166.408274ms)","trace[1971946907] 'applied index is now lower than readState.Index' (duration: 353.921µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:59:06.523643Z","caller":"traceutil/trace.go:171","msg":"trace[1698931779] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"171.325101ms","start":"2026-09-10T01:59:06.35229Z","end":"2026-09-10T01:59:06.523615Z","steps":["trace[1698931779] 'process raft request' (duration: 170.981101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:59:06.523725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.934699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:59:06.523789Z","caller":"traceutil/trace.go:171","msg":"trace[1131389946] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:824; }","duration":"167.044533ms","start":"2026-09-10T01:59:06.356728Z","end":"2026-09-10T01:59:06.523772Z","steps":["trace[1131389946] 'agreement among raft nodes before linearized reading' (duration: 166.923929ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:59:38.144665Z","caller":"traceutil/trace.go:171","msg":"trace[2064361055] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"191.232752ms","start":"2026-09-10T01:59:37.953405Z","end":"2026-09-10T01:59:38.144638Z","steps":["trace[2064361055] 'process raft request' (duration: 191.033577ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:02.253039Z","caller":"traceutil/trace.go:171","msg":"trace[400368446] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"149.510152ms","start":"2026-09-10T02:00:02.103499Z","end":"2026-09-10T02:00:02.253009Z","steps":["trace[400368446] 'process raft request' (duration: 121.465526ms)","trace[400368446] 'compare' (duration: 27.917173ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:02.400679Z","caller":"traceutil/trace.go:171","msg":"trace[588783926] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"128.55193ms","start":"2026-09-10T02:00:02.272099Z","end":"2026-09-10T02:00:02.400651Z","steps":["trace[588783926] 'process raft request' (duration: 95.802246ms)","trace[588783926] 'compare' (duration: 32.627061ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:00:27.414273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.850093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26d22daa-60a0-4d45-9108-e1437b5d0700\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-10T02:00:27.414392Z","caller":"traceutil/trace.go:171","msg":"trace[77703845] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26d22daa-60a0-4d45-9108-e1437b5d0700; range_end:; response_count:1; response_revision:1895; }","duration":"254.034077ms","start":"2026-09-10T02:00:27.160334Z","end":"2026-09-10T02:00:27.414368Z","steps":["trace[77703845] 'range keys from in-memory index tree' (duration: 253.515684ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:27.528675Z","caller":"traceutil/trace.go:171","msg":"trace[565493025] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"183.755059ms","start":"2026-09-10T02:00:27.344893Z","end":"2026-09-10T02:00:27.528648Z","steps":["trace[565493025] 'process raft request' (duration: 183.41906ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:27.664888Z","caller":"traceutil/trace.go:171","msg":"trace[1996327085] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1967; }","duration":"136.271611ms","start":"2026-09-10T02:00:27.528592Z","end":"2026-09-10T02:00:27.664864Z","steps":["trace[1996327085] 'read index received' (duration: 41.135603ms)","trace[1996327085] 'applied index is now lower than readState.Index' (duration: 95.134858ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:27.664919Z","caller":"traceutil/trace.go:171","msg":"trace[1062149701] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"161.84584ms","start":"2026-09-10T02:00:27.503045Z","end":"2026-09-10T02:00:27.664891Z","steps":["trace[1062149701] 'process raft request' (duration: 161.646084ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:27.665102Z","caller":"traceutil/trace.go:171","msg":"trace[1633020553] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"130.091883ms","start":"2026-09-10T02:00:27.534985Z","end":"2026-09-10T02:00:27.665077Z","steps":["trace[1633020553] 'process raft request' (duration: 129.842516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:27.665238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.349089ms","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-09-10T02:00:27.665282Z","caller":"traceutil/trace.go:171","msg":"trace[1791217137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1898; }","duration":"134.434021ms","start":"2026-09-10T02:00:27.530837Z","end":"2026-09-10T02:00:27.665271Z","steps":["trace[1791217137] 'agreement among raft nodes before linearized reading' (duration: 134.195324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:27.665366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.86814ms","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-09-10T02:00:27.665425Z","caller":"traceutil/trace.go:171","msg":"trace[261730140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1898; }","duration":"110.005144ms","start":"2026-09-10T02:00:27.555405Z","end":"2026-09-10T02:00:27.66541Z","steps":["trace[261730140] 'agreement among raft nodes before linearized reading' (duration: 109.88296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:27.665613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.45643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:00:27.665704Z","caller":"traceutil/trace.go:171","msg":"trace[831270603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"187.587013ms","start":"2026-09-10T02:00:27.478094Z","end":"2026-09-10T02:00:27.665681Z","steps":["trace[831270603] 'agreement among raft nodes before linearized reading' (duration: 186.896793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:28.006732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.264228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T02:00:28.007097Z","caller":"traceutil/trace.go:171","msg":"trace[747441509] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"230.825112ms","start":"2026-09-10T02:00:27.776249Z","end":"2026-09-10T02:00:28.007074Z","steps":["trace[747441509] 'process raft request' (duration: 230.762641ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:28.007485Z","caller":"traceutil/trace.go:171","msg":"trace[248408690] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1971; }","duration":"314.058217ms","start":"2026-09-10T02:00:27.69338Z","end":"2026-09-10T02:00:28.007438Z","steps":["trace[248408690] 'read index received' (duration: 18.112285ms)","trace[248408690] 'applied index is now lower than readState.Index' (duration: 295.942772ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:28.007469Z","caller":"traceutil/trace.go:171","msg":"trace[1560689180] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"335.517662ms","start":"2026-09-10T02:00:27.67191Z","end":"2026-09-10T02:00:28.007427Z","steps":["trace[1560689180] 'process raft request' (duration: 334.936665ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:28.007521Z","caller":"traceutil/trace.go:171","msg":"trace[122530623] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"335.604525ms","start":"2026-09-10T02:00:27.671882Z","end":"2026-09-10T02:00:28.007487Z","steps":["trace[122530623] 'process raft request' (duration: 93.249376ms)","trace[122530623] 'compare' (duration: 241.119924ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:28.007585Z","caller":"traceutil/trace.go:171","msg":"trace[1683263994] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"335.151603ms","start":"2026-09-10T02:00:27.672409Z","end":"2026-09-10T02:00:28.007561Z","steps":["trace[1683263994] 'process raft request' (duration: 334.541285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:28.007597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.211191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-10T02:00:28.007594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:00:27.671855Z","time spent":"335.681157ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:00:28.007629Z","caller":"traceutil/trace.go:171","msg":"trace[705898219] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1903; }","duration":"314.279423ms","start":"2026-09-10T02:00:27.69334Z","end":"2026-09-10T02:00:28.00762Z","steps":["trace[705898219] 'agreement among raft nodes before linearized reading' (duration: 314.213581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:28.007658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:00:27.693327Z","time spent":"314.322274ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-10T02:00:28.007658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:00:27.671856Z","time spent":"335.726188ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:00:28.007662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:00:27.672396Z","time spent":"335.226234ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:00:28.007815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.909071ms","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-09-10T02:00:28.007896Z","caller":"traceutil/trace.go:171","msg":"trace[48066210] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1903; }","duration":"162.036015ms","start":"2026-09-10T02:00:27.845839Z","end":"2026-09-10T02:00:28.007875Z","steps":["trace[48066210] 'agreement among raft nodes before linearized reading' (duration: 161.788808ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:36.26321Z","caller":"traceutil/trace.go:171","msg":"trace[317462892] linearizableReadLoop","detail":"{readStateIndex:2057; appliedIndex:2056; }","duration":"135.029988ms","start":"2026-09-10T02:00:36.128152Z","end":"2026-09-10T02:00:36.263182Z","steps":["trace[317462892] 'read index received' (duration: 109.745878ms)","trace[317462892] 'applied index is now lower than readState.Index' (duration: 25.28305ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:36.263294Z","caller":"traceutil/trace.go:171","msg":"trace[1946590924] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"194.981425ms","start":"2026-09-10T02:00:36.068283Z","end":"2026-09-10T02:00:36.263265Z","steps":["trace[1946590924] 'process raft request' (duration: 169.593492ms)","trace[1946590924] 'compare' (duration: 25.062694ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:00:36.263472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.205706ms","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-09-10T02:00:36.263513Z","caller":"traceutil/trace.go:171","msg":"trace[1832284555] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1984; }","duration":"193.307609ms","start":"2026-09-10T02:00:36.07019Z","end":"2026-09-10T02:00:36.263498Z","steps":["trace[1832284555] 'agreement among raft nodes before linearized reading' (duration: 193.123744ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:36.265806Z","caller":"traceutil/trace.go:171","msg":"trace[782741610] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"133.438784ms","start":"2026-09-10T02:00:36.132343Z","end":"2026-09-10T02:00:36.265781Z","steps":["trace[782741610] 'process raft request' (duration: 133.330481ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:41.95271Z","caller":"traceutil/trace.go:171","msg":"trace[526166747] linearizableReadLoop","detail":"{readStateIndex:2090; appliedIndex:2089; }","duration":"177.645932ms","start":"2026-09-10T02:00:41.775035Z","end":"2026-09-10T02:00:41.952681Z","steps":["trace[526166747] 'read index received' (duration: 177.304701ms)","trace[526166747] 'applied index is now lower than readState.Index' (duration: 340.051µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:41.952886Z","caller":"traceutil/trace.go:171","msg":"trace[760499859] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"203.391178ms","start":"2026-09-10T02:00:41.749439Z","end":"2026-09-10T02:00:41.95283Z","steps":["trace[760499859] 'process raft request' (duration: 203.062868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:41.953009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.911695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-cjkm8\" ","response":"range_response_count:1 size:6111"} {"level":"warn","ts":"2026-09-10T02:00:41.953032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.985081ms","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-09-10T02:00:41.953085Z","caller":"traceutil/trace.go:171","msg":"trace[2012942740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2016; }","duration":"178.066733ms","start":"2026-09-10T02:00:41.775006Z","end":"2026-09-10T02:00:41.953072Z","steps":["trace[2012942740] 'agreement among raft nodes before linearized reading' (duration: 177.837527ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:41.953072Z","caller":"traceutil/trace.go:171","msg":"trace[721988154] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-cjkm8; range_end:; response_count:1; response_revision:2016; }","duration":"129.026559ms","start":"2026-09-10T02:00:41.824025Z","end":"2026-09-10T02:00:41.953052Z","steps":["trace[721988154] 'agreement among raft nodes before linearized reading' (duration: 128.847204ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:01:12.299809Z","caller":"traceutil/trace.go:171","msg":"trace[1559858994] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2277; }","duration":"116.458398ms","start":"2026-09-10T02:01:12.183292Z","end":"2026-09-10T02:01:12.29975Z","steps":["trace[1559858994] 'read index received' (duration: 87.964156ms)","trace[1559858994] 'applied index is now lower than readState.Index' (duration: 28.492692ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:01:12.299932Z","caller":"traceutil/trace.go:171","msg":"trace[1699338175] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"150.535686ms","start":"2026-09-10T02:01:12.149352Z","end":"2026-09-10T02:01:12.299888Z","steps":["trace[1699338175] 'process raft request' (duration: 121.967382ms)","trace[1699338175] 'compare' (duration: 28.077111ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:01:12.300074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.756978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-10T02:01:12.30009Z","caller":"traceutil/trace.go:171","msg":"trace[2109392899] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"150.693321ms","start":"2026-09-10T02:01:12.149369Z","end":"2026-09-10T02:01:12.300063Z","steps":["trace[2109392899] 'process raft request' (duration: 150.292949ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:01:12.300143Z","caller":"traceutil/trace.go:171","msg":"trace[1142397788] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2197; }","duration":"116.892342ms","start":"2026-09-10T02:01:12.183236Z","end":"2026-09-10T02:01:12.300128Z","steps":["trace[1142397788] 'agreement among raft nodes before linearized reading' (duration: 116.695355ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:01:12.610726Z","caller":"traceutil/trace.go:171","msg":"trace[947146577] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"168.203829ms","start":"2026-09-10T02:01:12.442485Z","end":"2026-09-10T02:01:12.610689Z","steps":["trace[947146577] 'process raft request' (duration: 143.671767ms)","trace[947146577] 'compare' (duration: 24.332726ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:01:18.464544Z","caller":"traceutil/trace.go:171","msg":"trace[13823352] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"137.016271ms","start":"2026-09-10T02:01:18.32751Z","end":"2026-09-10T02:01:18.464526Z","steps":["trace[13823352] 'process raft request' (duration: 136.909608ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:01:18.51027Z","caller":"traceutil/trace.go:171","msg":"trace[602421469] linearizableReadLoop","detail":"{readStateIndex:2312; appliedIndex:2311; }","duration":"136.004732ms","start":"2026-09-10T02:01:18.374227Z","end":"2026-09-10T02:01:18.510232Z","steps":["trace[602421469] 'read index received' (duration: 90.291031ms)","trace[602421469] 'applied index is now lower than readState.Index' (duration: 45.712191ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:01:18.510588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.332091ms","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-09-10T02:01:18.510719Z","caller":"traceutil/trace.go:171","msg":"trace[1401127630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2229; }","duration":"136.499727ms","start":"2026-09-10T02:01:18.374199Z","end":"2026-09-10T02:01:18.510698Z","steps":["trace[1401127630] 'agreement among raft nodes before linearized reading' (duration: 136.172617ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:01:18.510663Z","caller":"traceutil/trace.go:171","msg":"trace[644053763] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"180.835419ms","start":"2026-09-10T02:01:18.329513Z","end":"2026-09-10T02:01:18.510348Z","steps":["trace[644053763] 'process raft request' (duration: 180.50718ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:02:18.048918Z","caller":"traceutil/trace.go:171","msg":"trace[672119877] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"108.037025ms","start":"2026-09-10T02:02:17.940847Z","end":"2026-09-10T02:02:18.048884Z","steps":["trace[672119877] 'process raft request' (duration: 107.8472ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:02:48.335463Z","caller":"traceutil/trace.go:171","msg":"trace[1267818495] linearizableReadLoop","detail":"{readStateIndex:3017; appliedIndex:3016; }","duration":"151.488642ms","start":"2026-09-10T02:02:48.183939Z","end":"2026-09-10T02:02:48.335428Z","steps":["trace[1267818495] 'read index received' (duration: 151.158553ms)","trace[1267818495] 'applied index is now lower than readState.Index' (duration: 328.679µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:02:48.335585Z","caller":"traceutil/trace.go:171","msg":"trace[1958451299] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"165.907163ms","start":"2026-09-10T02:02:48.169639Z","end":"2026-09-10T02:02:48.335546Z","steps":["trace[1958451299] 'process raft request' (duration: 165.547443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:02:48.335856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.854731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-10T02:02:48.335936Z","caller":"traceutil/trace.go:171","msg":"trace[988058888] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2909; }","duration":"152.029967ms","start":"2026-09-10T02:02:48.183863Z","end":"2026-09-10T02:02:48.335893Z","steps":["trace[988058888] 'agreement among raft nodes before linearized reading' (duration: 151.718028ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:02:59.280049Z","caller":"traceutil/trace.go:171","msg":"trace[1361429129] linearizableReadLoop","detail":"{readStateIndex:3079; appliedIndex:3078; }","duration":"114.191929ms","start":"2026-09-10T02:02:59.16583Z","end":"2026-09-10T02:02:59.280022Z","steps":["trace[1361429129] 'read index received' (duration: 37.366674ms)","trace[1361429129] 'applied index is now lower than readState.Index' (duration: 76.824085ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:02:59.280245Z","caller":"traceutil/trace.go:171","msg":"trace[341314994] transaction","detail":"{read_only:false; response_revision:2969; number_of_response:1; }","duration":"134.866394ms","start":"2026-09-10T02:02:59.145316Z","end":"2026-09-10T02:02:59.280182Z","steps":["trace[341314994] 'process raft request' (duration: 57.933546ms)","trace[341314994] 'compare' (duration: 76.63135ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:02:59.280313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.468476ms","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-09-10T02:02:59.280379Z","caller":"traceutil/trace.go:171","msg":"trace[983566939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2969; }","duration":"114.61071ms","start":"2026-09-10T02:02:59.165752Z","end":"2026-09-10T02:02:59.280363Z","steps":["trace[983566939] 'agreement among raft nodes before linearized reading' (duration: 114.419565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:02:59.280456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.404501ms","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-09-10T02:02:59.280595Z","caller":"traceutil/trace.go:171","msg":"trace[2032279761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2969; }","duration":"107.580536ms","start":"2026-09-10T02:02:59.172988Z","end":"2026-09-10T02:02:59.280568Z","steps":["trace[2032279761] 'agreement among raft nodes before linearized reading' (duration: 107.245697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:02:59.280545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.451893ms","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-09-10T02:02:59.280725Z","caller":"traceutil/trace.go:171","msg":"trace[1508229473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2969; }","duration":"107.69994ms","start":"2026-09-10T02:02:59.172997Z","end":"2026-09-10T02:02:59.280697Z","steps":["trace[1508229473] 'agreement among raft nodes before linearized reading' (duration: 107.274998ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:05:24.783857Z","caller":"traceutil/trace.go:171","msg":"trace[1048557661] transaction","detail":"{read_only:false; response_revision:3852; number_of_response:1; }","duration":"104.566274ms","start":"2026-09-10T02:05:24.679267Z","end":"2026-09-10T02:05:24.783833Z","steps":["trace[1048557661] 'process raft request' (duration: 101.434898ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:05:24.784374Z","caller":"traceutil/trace.go:171","msg":"trace[268538277] transaction","detail":"{read_only:false; response_revision:3854; number_of_response:1; }","duration":"100.236954ms","start":"2026-09-10T02:05:24.684124Z","end":"2026-09-10T02:05:24.784361Z","steps":["trace[268538277] 'process raft request' (duration: 100.211944ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:05:24.78444Z","caller":"traceutil/trace.go:171","msg":"trace[219562623] transaction","detail":"{read_only:false; response_revision:3853; number_of_response:1; }","duration":"102.233509ms","start":"2026-09-10T02:05:24.682168Z","end":"2026-09-10T02:05:24.784402Z","steps":["trace[219562623] 'process raft request' (duration: 102.105456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:27.60531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.014466ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-10T02:07:32.175881Z","caller":"traceutil/trace.go:171","msg":"trace[1891350551] linearizableReadLoop","detail":"{readStateIndex:5527; appliedIndex:5526; }","duration":"206.934567ms","start":"2026-09-10T02:07:31.968922Z","end":"2026-09-10T02:07:32.175857Z","steps":["trace[1891350551] 'read index received' (duration: 206.844544ms)","trace[1891350551] 'applied index is now lower than readState.Index' (duration: 89.073µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:07:32.175946Z","caller":"traceutil/trace.go:171","msg":"trace[448883918] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"297.911184ms","start":"2026-09-10T02:07:31.878006Z","end":"2026-09-10T02:07:32.175918Z","steps":["trace[448883918] 'process raft request' (duration: 297.683688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:32.176166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.224486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-10T02:07:32.176216Z","caller":"traceutil/trace.go:171","msg":"trace[748087120] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5330; }","duration":"207.317277ms","start":"2026-09-10T02:07:31.968884Z","end":"2026-09-10T02:07:32.176202Z","steps":["trace[748087120] 'agreement among raft nodes before linearized reading' (duration: 207.140132ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:32.277433Z","caller":"traceutil/trace.go:171","msg":"trace[773513752] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"213.328784ms","start":"2026-09-10T02:07:32.064067Z","end":"2026-09-10T02:07:32.277396Z","steps":["trace[773513752] 'process raft request' (duration: 212.350247ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:32.277486Z","caller":"traceutil/trace.go:171","msg":"trace[1894836317] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"186.490632ms","start":"2026-09-10T02:07:32.090928Z","end":"2026-09-10T02:07:32.277419Z","steps":["trace[1894836317] 'process raft request' (duration: 186.351818ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:32.277505Z","caller":"traceutil/trace.go:171","msg":"trace[1191790190] linearizableReadLoop","detail":"{readStateIndex:5530; appliedIndex:5527; }","duration":"101.52195ms","start":"2026-09-10T02:07:32.175961Z","end":"2026-09-10T02:07:32.277483Z","steps":["trace[1191790190] 'read index received' (duration: 100.45785ms)","trace[1191790190] 'applied index is now lower than readState.Index' (duration: 1.06291ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:07:32.277667Z","caller":"traceutil/trace.go:171","msg":"trace[725170307] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"185.244206ms","start":"2026-09-10T02:07:32.092369Z","end":"2026-09-10T02:07:32.277613Z","steps":["trace[725170307] 'process raft request' (duration: 185.00032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:32.277755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.302503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c912db23-bcad-4b86-85f2-3a47ef0a6193\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-09-10T02:07:32.277753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.390968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.149\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-09-10T02:07:32.277749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.007956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T02:07:32.277792Z","caller":"traceutil/trace.go:171","msg":"trace[1747847747] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c912db23-bcad-4b86-85f2-3a47ef0a6193; range_end:; response_count:1; response_revision:5333; }","duration":"149.382235ms","start":"2026-09-10T02:07:32.128397Z","end":"2026-09-10T02:07:32.277779Z","steps":["trace[1747847747] 'agreement among raft nodes before linearized reading' (duration: 149.264322ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:32.277802Z","caller":"traceutil/trace.go:171","msg":"trace[1275907045] range","detail":"{range_begin:/registry/masterleases/162.253.55.149; range_end:; response_count:1; response_revision:5333; }","duration":"178.48851ms","start":"2026-09-10T02:07:32.099299Z","end":"2026-09-10T02:07:32.277787Z","steps":["trace[1275907045] 'agreement among raft nodes before linearized reading' (duration: 178.267164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:32.27779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.660384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T02:07:32.277837Z","caller":"traceutil/trace.go:171","msg":"trace[887306960] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5333; }","duration":"127.16367ms","start":"2026-09-10T02:07:32.150652Z","end":"2026-09-10T02:07:32.277816Z","steps":["trace[887306960] 'agreement among raft nodes before linearized reading' (duration: 126.868692ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:32.277864Z","caller":"traceutil/trace.go:171","msg":"trace[1544466362] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:5333; }","duration":"161.799009ms","start":"2026-09-10T02:07:32.116039Z","end":"2026-09-10T02:07:32.277838Z","steps":["trace[1544466362] 'agreement among raft nodes before linearized reading' (duration: 161.645164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:32.278434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.68927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728747"} {"level":"info","ts":"2026-09-10T02:07:32.278508Z","caller":"traceutil/trace.go:171","msg":"trace[893424275] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5333; }","duration":"120.814994ms","start":"2026-09-10T02:07:32.157681Z","end":"2026-09-10T02:07:32.278496Z","steps":["trace[893424275] 'agreement among raft nodes before linearized reading' (duration: 119.9446ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:32.282141Z","caller":"traceutil/trace.go:171","msg":"trace[87358791] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"101.53589ms","start":"2026-09-10T02:07:32.180591Z","end":"2026-09-10T02:07:32.282127Z","steps":["trace[87358791] 'process raft request' (duration: 101.384885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:34.525403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.33696ms","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-09-10T02:07:34.52553Z","caller":"traceutil/trace.go:171","msg":"trace[748013167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5370; }","duration":"137.507435ms","start":"2026-09-10T02:07:34.388Z","end":"2026-09-10T02:07:34.525507Z","steps":["trace[748013167] 'range keys from in-memory index tree' (duration: 137.118925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:46.998625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.465799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-10T02:07:46.998773Z","caller":"traceutil/trace.go:171","msg":"trace[1306137579] linearizableReadLoop","detail":"{readStateIndex:5809; appliedIndex:5808; }","duration":"239.773476ms","start":"2026-09-10T02:07:46.758976Z","end":"2026-09-10T02:07:46.998749Z","steps":["trace[1306137579] 'read index received' (duration: 104.150352ms)","trace[1306137579] 'applied index is now lower than readState.Index' (duration: 135.621444ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:07:46.998855Z","caller":"traceutil/trace.go:171","msg":"trace[1433772062] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"272.642385ms","start":"2026-09-10T02:07:46.726195Z","end":"2026-09-10T02:07:46.998837Z","steps":["trace[1433772062] 'process raft request' (duration: 136.893018ms)","trace[1433772062] 'compare' (duration: 135.284654ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:07:46.998965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.971768ms","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-09-10T02:07:46.99904Z","caller":"traceutil/trace.go:171","msg":"trace[177088516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5603; }","duration":"241.064971ms","start":"2026-09-10T02:07:46.757956Z","end":"2026-09-10T02:07:46.999021Z","steps":["trace[177088516] 'agreement among raft nodes before linearized reading' (duration: 240.807454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:46.999063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.004542ms","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-09-10T02:07:46.999107Z","caller":"traceutil/trace.go:171","msg":"trace[891805748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5603; }","duration":"244.077854ms","start":"2026-09-10T02:07:46.755018Z","end":"2026-09-10T02:07:46.999096Z","steps":["trace[891805748] 'agreement among raft nodes before linearized reading' (duration: 243.812287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:46.999099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.981744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18252"} {"level":"info","ts":"2026-09-10T02:07:46.999248Z","caller":"traceutil/trace.go:171","msg":"trace[717182295] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5603; }","duration":"239.143808ms","start":"2026-09-10T02:07:46.760096Z","end":"2026-09-10T02:07:46.99924Z","steps":["trace[717182295] 'agreement among raft nodes before linearized reading' (duration: 238.917722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:46.998975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.104784ms","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-09-10T02:07:46.999329Z","caller":"traceutil/trace.go:171","msg":"trace[36102313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5603; }","duration":"249.484204ms","start":"2026-09-10T02:07:46.749828Z","end":"2026-09-10T02:07:46.999312Z","steps":["trace[36102313] 'agreement among raft nodes before linearized reading' (duration: 249.058673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:46.999198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.253599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-10T02:07:46.999526Z","caller":"traceutil/trace.go:171","msg":"trace[1861956801] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5603; }","duration":"236.575528ms","start":"2026-09-10T02:07:46.762933Z","end":"2026-09-10T02:07:46.999509Z","steps":["trace[1861956801] 'agreement among raft nodes before linearized reading' (duration: 236.192627ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:58.741928Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-09-10T02:07:58.823914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"80.474687ms","hash":2483636848,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":32137216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-10T02:07:58.824002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2483636848,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-09-10T02:07:59.329981Z","caller":"traceutil/trace.go:171","msg":"trace[942503362] linearizableReadLoop","detail":"{readStateIndex:6053; appliedIndex:6052; }","duration":"162.016523ms","start":"2026-09-10T02:07:59.167942Z","end":"2026-09-10T02:07:59.329958Z","steps":["trace[942503362] 'read index received' (duration: 161.878439ms)","trace[942503362] 'applied index is now lower than readState.Index' (duration: 137.294µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:07:59.330234Z","caller":"traceutil/trace.go:171","msg":"trace[2109920923] transaction","detail":"{read_only:false; response_revision:5841; number_of_response:1; }","duration":"177.925053ms","start":"2026-09-10T02:07:59.152217Z","end":"2026-09-10T02:07:59.330142Z","steps":["trace[2109920923] 'process raft request' (duration: 177.620225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:59.330412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.459232ms","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-09-10T02:07:59.330515Z","caller":"traceutil/trace.go:171","msg":"trace[1318125807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5841; }","duration":"177.619506ms","start":"2026-09-10T02:07:59.152882Z","end":"2026-09-10T02:07:59.330501Z","steps":["trace[1318125807] 'agreement among raft nodes before linearized reading' (duration: 177.315238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:59.330937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.057778ms","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-09-10T02:07:59.331047Z","caller":"traceutil/trace.go:171","msg":"trace[1901521140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5841; }","duration":"178.193611ms","start":"2026-09-10T02:07:59.152839Z","end":"2026-09-10T02:07:59.331033Z","steps":["trace[1901521140] 'agreement among raft nodes before linearized reading' (duration: 177.265105ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:59.481001Z","caller":"traceutil/trace.go:171","msg":"trace[1921932788] transaction","detail":"{read_only:false; response_revision:5843; number_of_response:1; }","duration":"146.639937ms","start":"2026-09-10T02:07:59.334335Z","end":"2026-09-10T02:07:59.480975Z","steps":["trace[1921932788] 'process raft request' (duration: 146.078062ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:59.481234Z","caller":"traceutil/trace.go:171","msg":"trace[783501367] transaction","detail":"{read_only:false; response_revision:5844; number_of_response:1; }","duration":"145.545428ms","start":"2026-09-10T02:07:59.335654Z","end":"2026-09-10T02:07:59.481199Z","steps":["trace[783501367] 'process raft request' (duration: 144.850349ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:59.48125Z","caller":"traceutil/trace.go:171","msg":"trace[2126412582] transaction","detail":"{read_only:false; response_revision:5842; number_of_response:1; }","duration":"309.053523ms","start":"2026-09-10T02:07:59.172183Z","end":"2026-09-10T02:07:59.481236Z","steps":["trace[2126412582] 'process raft request' (duration: 218.663281ms)","trace[2126412582] 'compare' (duration: 89.338853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:07:59.483814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:07:59.172163Z","time spent":"309.404473ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:07:59.718946Z","caller":"traceutil/trace.go:171","msg":"trace[1923826831] linearizableReadLoop","detail":"{readStateIndex:6057; appliedIndex:6056; }","duration":"169.317795ms","start":"2026-09-10T02:07:59.549605Z","end":"2026-09-10T02:07:59.718922Z","steps":["trace[1923826831] 'read index received' (duration: 169.168931ms)","trace[1923826831] 'applied index is now lower than readState.Index' (duration: 147.854µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:07:59.71901Z","caller":"traceutil/trace.go:171","msg":"trace[2124894741] transaction","detail":"{read_only:false; response_revision:5845; number_of_response:1; }","duration":"227.482005ms","start":"2026-09-10T02:07:59.491501Z","end":"2026-09-10T02:07:59.718983Z","steps":["trace[2124894741] 'process raft request' (duration: 227.240078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:07:59.719223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.609493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-10T02:07:59.719383Z","caller":"traceutil/trace.go:171","msg":"trace[231992439] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5845; }","duration":"169.827779ms","start":"2026-09-10T02:07:59.549534Z","end":"2026-09-10T02:07:59.719362Z","steps":["trace[231992439] 'agreement among raft nodes before linearized reading' (duration: 169.532561ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:07:59.728093Z","caller":"traceutil/trace.go:171","msg":"trace[407893037] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"157.270883ms","start":"2026-09-10T02:07:59.5708Z","end":"2026-09-10T02:07:59.728071Z","steps":["trace[407893037] 'process raft request' (duration: 156.877691ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:08:40.97788Z","caller":"traceutil/trace.go:171","msg":"trace[1928897745] transaction","detail":"{read_only:false; response_revision:6234; number_of_response:1; }","duration":"136.063611ms","start":"2026-09-10T02:08:40.841765Z","end":"2026-09-10T02:08:40.977829Z","steps":["trace[1928897745] 'process raft request' (duration: 135.866625ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:12:58.74663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5837} {"level":"info","ts":"2026-09-10T02:12:58.836661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5837,"took":"88.342286ms","hash":1986038315,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":26796032,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-10T02:12:58.836751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1986038315,"revision":5837,"compact-revision":2964} {"level":"info","ts":"2026-09-10T02:13:57.217515Z","caller":"traceutil/trace.go:171","msg":"trace[219380784] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"191.235936ms","start":"2026-09-10T02:13:57.02624Z","end":"2026-09-10T02:13:57.217476Z","steps":["trace[219380784] 'process raft request' (duration: 191.102252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:13:57.447782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.240798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T02:13:57.447953Z","caller":"traceutil/trace.go:171","msg":"trace[1343091154] linearizableReadLoop","detail":"{readStateIndex:8881; appliedIndex:8880; }","duration":"125.724301ms","start":"2026-09-10T02:13:57.322187Z","end":"2026-09-10T02:13:57.447911Z","steps":["trace[1343091154] 'read index received' (duration: 61.152µs)","trace[1343091154] 'applied index is now lower than readState.Index' (duration: 125.661179ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:13:57.448031Z","caller":"traceutil/trace.go:171","msg":"trace[477286064] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"312.730701ms","start":"2026-09-10T02:13:57.135266Z","end":"2026-09-10T02:13:57.447996Z","steps":["trace[477286064] 'process raft request' (duration: 152.162994ms)","trace[477286064] 'compare' (duration: 160.085763ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:13:57.448085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.888366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:13:57.448159Z","caller":"traceutil/trace.go:171","msg":"trace[485366566] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8582; }","duration":"126.01768ms","start":"2026-09-10T02:13:57.322122Z","end":"2026-09-10T02:13:57.44814Z","steps":["trace[485366566] 'agreement among raft nodes before linearized reading' (duration: 125.889396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:13:57.448162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:13:57.135247Z","time spent":"312.854803ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:14:09.863587Z","caller":"traceutil/trace.go:171","msg":"trace[1707340646] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"276.300701ms","start":"2026-09-10T02:14:09.587258Z","end":"2026-09-10T02:14:09.863558Z","steps":["trace[1707340646] 'process raft request' (duration: 276.140307ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:09.863843Z","caller":"traceutil/trace.go:171","msg":"trace[653873753] linearizableReadLoop","detail":"{readStateIndex:8952; appliedIndex:8952; }","duration":"273.933545ms","start":"2026-09-10T02:14:09.589878Z","end":"2026-09-10T02:14:09.863811Z","steps":["trace[653873753] 'read index received' (duration: 273.73088ms)","trace[653873753] 'applied index is now lower than readState.Index' (duration: 199.905µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:14:09.864117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.215305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-10T02:14:09.864824Z","caller":"traceutil/trace.go:171","msg":"trace[1901948152] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8649; }","duration":"274.959505ms","start":"2026-09-10T02:14:09.589847Z","end":"2026-09-10T02:14:09.864806Z","steps":["trace[1901948152] 'agreement among raft nodes before linearized reading' (duration: 274.059619ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:10.016873Z","caller":"traceutil/trace.go:171","msg":"trace[1946569425] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"166.016198ms","start":"2026-09-10T02:14:09.850832Z","end":"2026-09-10T02:14:10.016849Z","steps":["trace[1946569425] 'process raft request' (duration: 97.235544ms)","trace[1946569425] 'compare' (duration: 68.65801ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:14:10.017209Z","caller":"traceutil/trace.go:171","msg":"trace[1692001947] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"163.371985ms","start":"2026-09-10T02:14:09.853795Z","end":"2026-09-10T02:14:10.017167Z","steps":["trace[1692001947] 'process raft request' (duration: 163.028415ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:10.017343Z","caller":"traceutil/trace.go:171","msg":"trace[1896221853] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"148.060011ms","start":"2026-09-10T02:14:09.869261Z","end":"2026-09-10T02:14:10.017321Z","steps":["trace[1896221853] 'process raft request' (duration: 147.912497ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:10.017535Z","caller":"traceutil/trace.go:171","msg":"trace[1403029975] linearizableReadLoop","detail":"{readStateIndex:8954; appliedIndex:8952; }","duration":"152.856042ms","start":"2026-09-10T02:14:09.864671Z","end":"2026-09-10T02:14:10.017527Z","steps":["trace[1403029975] 'read index received' (duration: 83.436581ms)","trace[1403029975] 'applied index is now lower than readState.Index' (duration: 69.418401ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:14:10.017669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.791291ms","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:5"} {"level":"info","ts":"2026-09-10T02:14:10.017703Z","caller":"traceutil/trace.go:171","msg":"trace[851864130] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8652; }","duration":"130.863984ms","start":"2026-09-10T02:14:09.886825Z","end":"2026-09-10T02:14:10.017689Z","steps":["trace[851864130] 'agreement among raft nodes before linearized reading' (duration: 130.788502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:10.017872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.723084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T02:14:10.017875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.699276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:14:10.017919Z","caller":"traceutil/trace.go:171","msg":"trace[2031783842] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8652; }","duration":"115.802277ms","start":"2026-09-10T02:14:09.902104Z","end":"2026-09-10T02:14:10.017906Z","steps":["trace[2031783842] 'agreement among raft nodes before linearized reading' (duration: 115.725245ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:10.01794Z","caller":"traceutil/trace.go:171","msg":"trace[140108580] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8652; }","duration":"114.803089ms","start":"2026-09-10T02:14:09.903117Z","end":"2026-09-10T02:14:10.017921Z","steps":["trace[140108580] 'agreement among raft nodes before linearized reading' (duration: 114.684556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:10.01803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.445132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:14:10.018049Z","caller":"traceutil/trace.go:171","msg":"trace[1149547735] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8652; }","duration":"130.475643ms","start":"2026-09-10T02:14:09.887569Z","end":"2026-09-10T02:14:10.018045Z","steps":["trace[1149547735] 'agreement among raft nodes before linearized reading' (duration: 130.442562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:10.018139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.183673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:14:10.018157Z","caller":"traceutil/trace.go:171","msg":"trace[1210729391] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8652; }","duration":"131.242235ms","start":"2026-09-10T02:14:09.88691Z","end":"2026-09-10T02:14:10.018152Z","steps":["trace[1210729391] 'agreement among raft nodes before linearized reading' (duration: 131.213054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:10.018155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.865899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:14:10.018184Z","caller":"traceutil/trace.go:171","msg":"trace[630559265] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8652; }","duration":"115.934211ms","start":"2026-09-10T02:14:09.90224Z","end":"2026-09-10T02:14:10.018175Z","steps":["trace[630559265] 'agreement among raft nodes before linearized reading' (duration: 115.88857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:10.019097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.256848ms","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-09-10T02:14:10.019214Z","caller":"traceutil/trace.go:171","msg":"trace[1749295043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8652; }","duration":"273.458104ms","start":"2026-09-10T02:14:09.745737Z","end":"2026-09-10T02:14:10.019195Z","steps":["trace[1749295043] 'agreement among raft nodes before linearized reading' (duration: 271.861389ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:10.815686Z","caller":"traceutil/trace.go:171","msg":"trace[643816405] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"136.872781ms","start":"2026-09-10T02:14:10.678778Z","end":"2026-09-10T02:14:10.815651Z","steps":["trace[643816405] 'process raft request' (duration: 136.619684ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:38.53013Z","caller":"traceutil/trace.go:171","msg":"trace[491058172] transaction","detail":"{read_only:false; response_revision:8856; number_of_response:1; }","duration":"528.456045ms","start":"2026-09-10T02:14:38.00164Z","end":"2026-09-10T02:14:38.530096Z","steps":["trace[491058172] 'process raft request' (duration: 528.221778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:38.53076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:38.001614Z","time spent":"529.049732ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:14:38.552139Z","caller":"traceutil/trace.go:171","msg":"trace[143384912] transaction","detail":"{read_only:false; response_revision:8857; number_of_response:1; }","duration":"430.461121ms","start":"2026-09-10T02:14:38.121661Z","end":"2026-09-10T02:14:38.552122Z","steps":["trace[143384912] 'process raft request' (duration: 430.334307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:38.552246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:38.121642Z","time spent":"430.548383ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:14:38.552334Z","caller":"traceutil/trace.go:171","msg":"trace[87292337] linearizableReadLoop","detail":"{readStateIndex:9169; appliedIndex:9166; }","duration":"383.486581ms","start":"2026-09-10T02:14:38.168812Z","end":"2026-09-10T02:14:38.552299Z","steps":["trace[87292337] 'read index received' (duration: 361.040349ms)","trace[87292337] 'applied index is now lower than readState.Index' (duration: 22.443751ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:14:38.552481Z","caller":"traceutil/trace.go:171","msg":"trace[996550363] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"418.358245ms","start":"2026-09-10T02:14:38.134112Z","end":"2026-09-10T02:14:38.552471Z","steps":["trace[996550363] 'process raft request' (duration: 417.977145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:38.552648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.777038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-09-10T02:14:38.552666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:38.134099Z","time spent":"418.437238ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:14:38.552695Z","caller":"traceutil/trace.go:171","msg":"trace[1858141367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8858; }","duration":"383.83842ms","start":"2026-09-10T02:14:38.168846Z","end":"2026-09-10T02:14:38.552685Z","steps":["trace[1858141367] 'agreement among raft nodes before linearized reading' (duration: 383.679896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:38.552724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:38.168841Z","time spent":"383.875491ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-10T02:14:38.552859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.045356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-10T02:14:38.552891Z","caller":"traceutil/trace.go:171","msg":"trace[1861245302] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8858; }","duration":"384.104758ms","start":"2026-09-10T02:14:38.168777Z","end":"2026-09-10T02:14:38.552881Z","steps":["trace[1861245302] 'agreement among raft nodes before linearized reading' (duration: 384.016255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:38.552913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:38.168762Z","time spent":"384.145778ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-10T02:14:38.553054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.534499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rpcvk\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-10T02:14:38.553099Z","caller":"traceutil/trace.go:171","msg":"trace[162952052] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rpcvk; range_end:; response_count:1; response_revision:8858; }","duration":"358.635442ms","start":"2026-09-10T02:14:38.19445Z","end":"2026-09-10T02:14:38.553086Z","steps":["trace[162952052] 'agreement among raft nodes before linearized reading' (duration: 358.337643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:38.553127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:38.194423Z","time spent":"358.698204ms","remote":"127.0.0.1:48658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-rpcvk\" "} {"level":"info","ts":"2026-09-10T02:14:39.876989Z","caller":"traceutil/trace.go:171","msg":"trace[484621567] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"393.754425ms","start":"2026-09-10T02:14:39.483209Z","end":"2026-09-10T02:14:39.876964Z","steps":["trace[484621567] 'process raft request' (duration: 393.636502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:39.877116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:39.483189Z","time spent":"393.871568ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:14:39.971281Z","caller":"traceutil/trace.go:171","msg":"trace[681502798] linearizableReadLoop","detail":"{readStateIndex:9174; appliedIndex:9173; }","duration":"247.529856ms","start":"2026-09-10T02:14:39.723724Z","end":"2026-09-10T02:14:39.971253Z","steps":["trace[681502798] 'read index received' (duration: 153.905952ms)","trace[681502798] 'applied index is now lower than readState.Index' (duration: 93.623014ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:14:39.971436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.703341ms","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-09-10T02:14:39.971419Z","caller":"traceutil/trace.go:171","msg":"trace[364340440] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"290.005832ms","start":"2026-09-10T02:14:39.681374Z","end":"2026-09-10T02:14:39.97138Z","steps":["trace[364340440] 'process raft request' (duration: 258.482609ms)","trace[364340440] 'compare' (duration: 31.304117ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:14:39.971472Z","caller":"traceutil/trace.go:171","msg":"trace[998200779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8863; }","duration":"247.754442ms","start":"2026-09-10T02:14:39.723707Z","end":"2026-09-10T02:14:39.971461Z","steps":["trace[998200779] 'agreement among raft nodes before linearized reading' (duration: 247.649539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:46.309227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.865069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:14:46.309298Z","caller":"traceutil/trace.go:171","msg":"trace[314003035] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8897; }","duration":"180.982612ms","start":"2026-09-10T02:14:46.128299Z","end":"2026-09-10T02:14:46.309282Z","steps":["trace[314003035] 'range keys from in-memory index tree' (duration: 180.798067ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:46.358759Z","caller":"traceutil/trace.go:171","msg":"trace[1139919718] linearizableReadLoop","detail":"{readStateIndex:9210; appliedIndex:9209; }","duration":"168.142218ms","start":"2026-09-10T02:14:46.190604Z","end":"2026-09-10T02:14:46.358746Z","steps":["trace[1139919718] 'read index received' (duration: 167.909552ms)","trace[1139919718] 'applied index is now lower than readState.Index' (duration: 231.886µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:14:46.35897Z","caller":"traceutil/trace.go:171","msg":"trace[1542907722] transaction","detail":"{read_only:false; response_revision:8898; number_of_response:1; }","duration":"168.447127ms","start":"2026-09-10T02:14:46.19051Z","end":"2026-09-10T02:14:46.358957Z","steps":["trace[1542907722] 'process raft request' (duration: 168.092117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:46.35943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.798157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-756f7bd47b-tzdch.18d3d3ddcf1df855\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-09-10T02:14:46.359564Z","caller":"traceutil/trace.go:171","msg":"trace[1755157629] range","detail":"{range_begin:/registry/events/openstack/barbican-api-756f7bd47b-tzdch.18d3d3ddcf1df855; range_end:; response_count:1; response_revision:8898; }","duration":"168.992782ms","start":"2026-09-10T02:14:46.190548Z","end":"2026-09-10T02:14:46.359541Z","steps":["trace[1755157629] 'agreement among raft nodes before linearized reading' (duration: 168.353544ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:46.579654Z","caller":"traceutil/trace.go:171","msg":"trace[1959527271] linearizableReadLoop","detail":"{readStateIndex:9211; appliedIndex:9210; }","duration":"216.39424ms","start":"2026-09-10T02:14:46.363197Z","end":"2026-09-10T02:14:46.579591Z","steps":["trace[1959527271] 'read index received' (duration: 177.518368ms)","trace[1959527271] 'applied index is now lower than readState.Index' (duration: 38.874552ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:14:46.579853Z","caller":"traceutil/trace.go:171","msg":"trace[413703157] transaction","detail":"{read_only:false; response_revision:8899; number_of_response:1; }","duration":"217.446269ms","start":"2026-09-10T02:14:46.362386Z","end":"2026-09-10T02:14:46.579833Z","steps":["trace[413703157] 'process raft request' (duration: 178.32092ms)","trace[413703157] 'compare' (duration: 38.543103ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:14:46.58023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.006807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20182"} {"level":"info","ts":"2026-09-10T02:14:46.580309Z","caller":"traceutil/trace.go:171","msg":"trace[853168068] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8899; }","duration":"217.13943ms","start":"2026-09-10T02:14:46.36315Z","end":"2026-09-10T02:14:46.58029Z","steps":["trace[853168068] 'agreement among raft nodes before linearized reading' (duration: 216.910724ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:51.283653Z","caller":"traceutil/trace.go:171","msg":"trace[1208156770] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8935; }","duration":"115.194688ms","start":"2026-09-10T02:14:51.168436Z","end":"2026-09-10T02:14:51.283631Z","steps":["trace[1208156770] 'process raft request' (duration: 114.750376ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:51.283829Z","caller":"traceutil/trace.go:171","msg":"trace[1357302220] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8935; }","duration":"112.064081ms","start":"2026-09-10T02:14:51.171758Z","end":"2026-09-10T02:14:51.283822Z","steps":["trace[1357302220] 'process raft request' (duration: 111.538587ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:51.284014Z","caller":"traceutil/trace.go:171","msg":"trace[633814166] transaction","detail":"{read_only:false; response_revision:8936; number_of_response:1; }","duration":"104.0444ms","start":"2026-09-10T02:14:51.179963Z","end":"2026-09-10T02:14:51.284007Z","steps":["trace[633814166] 'process raft request' (duration: 103.370962ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:51.454459Z","caller":"traceutil/trace.go:171","msg":"trace[1902895031] transaction","detail":"{read_only:false; response_revision:8937; number_of_response:1; }","duration":"153.826484ms","start":"2026-09-10T02:14:51.300611Z","end":"2026-09-10T02:14:51.454438Z","steps":["trace[1902895031] 'process raft request' (duration: 153.735051ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:51.521648Z","caller":"traceutil/trace.go:171","msg":"trace[1677165187] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8937; }","duration":"216.345288ms","start":"2026-09-10T02:14:51.305271Z","end":"2026-09-10T02:14:51.521616Z","steps":["trace[1677165187] 'process raft request' (duration: 216.086951ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:51.521705Z","caller":"traceutil/trace.go:171","msg":"trace[80189642] linearizableReadLoop","detail":"{readStateIndex:9253; appliedIndex:9251; }","duration":"152.26006ms","start":"2026-09-10T02:14:51.369416Z","end":"2026-09-10T02:14:51.521676Z","steps":["trace[80189642] 'read index received' (duration: 85.028975ms)","trace[80189642] 'applied index is now lower than readState.Index' (duration: 67.229625ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:14:51.521819Z","caller":"traceutil/trace.go:171","msg":"trace[836932440] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8938; }","duration":"213.462169ms","start":"2026-09-10T02:14:51.308318Z","end":"2026-09-10T02:14:51.52178Z","steps":["trace[836932440] 'process raft request' (duration: 213.14864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:51.521922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.482316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:14:51.521971Z","caller":"traceutil/trace.go:171","msg":"trace[1872444789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8938; }","duration":"152.582298ms","start":"2026-09-10T02:14:51.369372Z","end":"2026-09-10T02:14:51.521955Z","steps":["trace[1872444789] 'agreement among raft nodes before linearized reading' (duration: 152.433544ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:51.714019Z","caller":"traceutil/trace.go:171","msg":"trace[240145640] transaction","detail":"{read_only:false; response_revision:8939; number_of_response:1; }","duration":"165.753843ms","start":"2026-09-10T02:14:51.548217Z","end":"2026-09-10T02:14:51.713971Z","steps":["trace[240145640] 'process raft request' (duration: 165.515135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:52.040893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.387765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-10T02:14:52.040958Z","caller":"traceutil/trace.go:171","msg":"trace[992933068] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8939; }","duration":"230.515259ms","start":"2026-09-10T02:14:51.810427Z","end":"2026-09-10T02:14:52.040942Z","steps":["trace[992933068] 'range keys from in-memory index tree' (duration: 230.251692ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:52.406634Z","caller":"traceutil/trace.go:171","msg":"trace[327827272] linearizableReadLoop","detail":"{readStateIndex:9255; appliedIndex:9254; }","duration":"386.867102ms","start":"2026-09-10T02:14:52.019726Z","end":"2026-09-10T02:14:52.406594Z","steps":["trace[327827272] 'read index received' (duration: 386.724558ms)","trace[327827272] 'applied index is now lower than readState.Index' (duration: 141.184µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:14:52.406975Z","caller":"traceutil/trace.go:171","msg":"trace[1574066942] transaction","detail":"{read_only:false; response_revision:8940; number_of_response:1; }","duration":"415.011518ms","start":"2026-09-10T02:14:51.991948Z","end":"2026-09-10T02:14:52.40696Z","steps":["trace[1574066942] 'process raft request' (duration: 414.471763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:52.407075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:51.991927Z","time spent":"415.08617ms","remote":"127.0.0.1:48538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T02:14:52.407276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.510369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:14:52.407388Z","caller":"traceutil/trace.go:171","msg":"trace[742216141] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8940; }","duration":"387.642003ms","start":"2026-09-10T02:14:52.019715Z","end":"2026-09-10T02:14:52.407357Z","steps":["trace[742216141] 'agreement among raft nodes before linearized reading' (duration: 387.354485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:52.40745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:52.019645Z","time spent":"387.789517ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-09-10T02:14:52.529993Z","caller":"traceutil/trace.go:171","msg":"trace[1984331396] linearizableReadLoop","detail":"{readStateIndex:9258; appliedIndex:9255; }","duration":"123.211279ms","start":"2026-09-10T02:14:52.406749Z","end":"2026-09-10T02:14:52.52996Z","steps":["trace[1984331396] 'read index received' (duration: 35.828499ms)","trace[1984331396] 'applied index is now lower than readState.Index' (duration: 87.38166ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:14:52.530356Z","caller":"traceutil/trace.go:171","msg":"trace[1354703925] transaction","detail":"{read_only:false; response_revision:8941; number_of_response:1; }","duration":"506.367689ms","start":"2026-09-10T02:14:52.023937Z","end":"2026-09-10T02:14:52.530305Z","steps":["trace[1354703925] 'process raft request' (duration: 418.696711ms)","trace[1354703925] 'compare' (duration: 86.884357ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:14:52.530525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:52.023913Z","time spent":"506.495612ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:14:52.530893Z","caller":"traceutil/trace.go:171","msg":"trace[506116814] transaction","detail":"{read_only:false; response_revision:8942; number_of_response:1; }","duration":"485.04258ms","start":"2026-09-10T02:14:52.04583Z","end":"2026-09-10T02:14:52.530873Z","steps":["trace[506116814] 'process raft request' (duration: 483.996051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:52.530983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:52.045806Z","time spent":"485.132733ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:14:52.531176Z","caller":"traceutil/trace.go:171","msg":"trace[723453928] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"481.548144ms","start":"2026-09-10T02:14:52.049612Z","end":"2026-09-10T02:14:52.531161Z","steps":["trace[723453928] 'process raft request' (duration: 480.291039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:52.531263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:52.049582Z","time spent":"481.633026ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:14:52.531584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.708622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-10T02:14:52.531907Z","caller":"traceutil/trace.go:171","msg":"trace[1957470133] range","detail":"{range_begin:/registry/masterleases/162.253.55.149; range_end:; response_count:1; response_revision:8943; }","duration":"409.794984ms","start":"2026-09-10T02:14:52.121839Z","end":"2026-09-10T02:14:52.531634Z","steps":["trace[1957470133] 'agreement among raft nodes before linearized reading' (duration: 409.594549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:52.532006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:52.121827Z","time spent":"410.125214ms","remote":"127.0.0.1:48494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.149\" "} {"level":"warn","ts":"2026-09-10T02:14:52.532293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.26804ms","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-09-10T02:14:52.532347Z","caller":"traceutil/trace.go:171","msg":"trace[1226594445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8943; }","duration":"467.356123ms","start":"2026-09-10T02:14:52.064978Z","end":"2026-09-10T02:14:52.532334Z","steps":["trace[1226594445] 'agreement among raft nodes before linearized reading' (duration: 467.220779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:14:52.53238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:14:52.064962Z","time spent":"467.410224ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-10T02:14:52.532578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.437761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:14:52.532621Z","caller":"traceutil/trace.go:171","msg":"trace[569485350] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8943; }","duration":"121.517733ms","start":"2026-09-10T02:14:52.411091Z","end":"2026-09-10T02:14:52.532609Z","steps":["trace[569485350] 'agreement among raft nodes before linearized reading' (duration: 121.453331ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:14:56.159595Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000246f.wal"} {"level":"info","ts":"2026-09-10T02:15:05.5821Z","caller":"traceutil/trace.go:171","msg":"trace[308879851] linearizableReadLoop","detail":"{readStateIndex:9399; appliedIndex:9398; }","duration":"105.156852ms","start":"2026-09-10T02:15:05.476915Z","end":"2026-09-10T02:15:05.582072Z","steps":["trace[308879851] 'read index received' (duration: 105.015168ms)","trace[308879851] 'applied index is now lower than readState.Index' (duration: 140.274µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:15:05.582163Z","caller":"traceutil/trace.go:171","msg":"trace[590578471] transaction","detail":"{read_only:false; response_revision:9077; number_of_response:1; }","duration":"126.002806ms","start":"2026-09-10T02:15:05.456118Z","end":"2026-09-10T02:15:05.582121Z","steps":["trace[590578471] 'process raft request' (duration: 125.77923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:15:05.582241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.321685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:15:05.582274Z","caller":"traceutil/trace.go:171","msg":"trace[2078796641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9077; }","duration":"105.421739ms","start":"2026-09-10T02:15:05.476842Z","end":"2026-09-10T02:15:05.582263Z","steps":["trace[2078796641] 'agreement among raft nodes before linearized reading' (duration: 105.339907ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:15:59.08068Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab61124f07c17f20","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-10T02:15:59.088447Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-10T02:15:59.088572Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-10T02:16:15.278072Z","caller":"traceutil/trace.go:171","msg":"trace[1986539345] transaction","detail":"{read_only:false; response_revision:9752; number_of_response:1; }","duration":"250.53396ms","start":"2026-09-10T02:16:15.027506Z","end":"2026-09-10T02:16:15.27804Z","steps":["trace[1986539345] 'process raft request' (duration: 250.370746ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:16:15.319053Z","caller":"traceutil/trace.go:171","msg":"trace[1677081791] transaction","detail":"{read_only:false; response_revision:9753; number_of_response:1; }","duration":"291.366127ms","start":"2026-09-10T02:16:15.027656Z","end":"2026-09-10T02:16:15.319022Z","steps":["trace[1677081791] 'process raft request' (duration: 291.203173ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:16:15.319424Z","caller":"traceutil/trace.go:171","msg":"trace[2089766581] linearizableReadLoop","detail":"{readStateIndex:10095; appliedIndex:10092; }","duration":"182.70421ms","start":"2026-09-10T02:16:15.136681Z","end":"2026-09-10T02:16:15.319385Z","steps":["trace[2089766581] 'read index received' (duration: 141.186305ms)","trace[2089766581] 'applied index is now lower than readState.Index' (duration: 41.508535ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:16:15.31946Z","caller":"traceutil/trace.go:171","msg":"trace[1116371683] transaction","detail":"{read_only:false; response_revision:9754; number_of_response:1; }","duration":"289.688201ms","start":"2026-09-10T02:16:15.029746Z","end":"2026-09-10T02:16:15.319434Z","steps":["trace[1116371683] 'process raft request' (duration: 289.232739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:16:15.31965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.959506ms","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-09-10T02:16:15.319712Z","caller":"traceutil/trace.go:171","msg":"trace[1946371820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9754; }","duration":"183.081249ms","start":"2026-09-10T02:16:15.136615Z","end":"2026-09-10T02:16:15.319697Z","steps":["trace[1946371820] 'agreement among raft nodes before linearized reading' (duration: 182.901625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:16:15.319809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.125198ms","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-09-10T02:16:15.319891Z","caller":"traceutil/trace.go:171","msg":"trace[57626588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9754; }","duration":"164.261001ms","start":"2026-09-10T02:16:15.155612Z","end":"2026-09-10T02:16:15.319873Z","steps":["trace[57626588] 'agreement among raft nodes before linearized reading' (duration: 164.029255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:16:15.320028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.457989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-10T02:16:15.320136Z","caller":"traceutil/trace.go:171","msg":"trace[1457579740] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9754; }","duration":"163.566471ms","start":"2026-09-10T02:16:15.156535Z","end":"2026-09-10T02:16:15.320101Z","steps":["trace[1457579740] 'agreement among raft nodes before linearized reading' (duration: 163.263083ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:16:15.49909Z","caller":"traceutil/trace.go:171","msg":"trace[1887446597] linearizableReadLoop","detail":"{readStateIndex:10098; appliedIndex:10095; }","duration":"173.742093ms","start":"2026-09-10T02:16:15.325325Z","end":"2026-09-10T02:16:15.499067Z","steps":["trace[1887446597] 'read index received' (duration: 6.121529ms)","trace[1887446597] 'applied index is now lower than readState.Index' (duration: 167.619644ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:16:15.499219Z","caller":"traceutil/trace.go:171","msg":"trace[641064096] transaction","detail":"{read_only:false; response_revision:9757; number_of_response:1; }","duration":"174.228246ms","start":"2026-09-10T02:16:15.324981Z","end":"2026-09-10T02:16:15.499209Z","steps":["trace[641064096] 'process raft request' (duration: 173.931497ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:16:15.499307Z","caller":"traceutil/trace.go:171","msg":"trace[676646500] transaction","detail":"{read_only:false; response_revision:9756; number_of_response:1; }","duration":"174.606176ms","start":"2026-09-10T02:16:15.324673Z","end":"2026-09-10T02:16:15.499279Z","steps":["trace[676646500] 'process raft request' (duration: 152.328812ms)","trace[676646500] 'compare' (duration: 21.455062ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:16:15.499424Z","caller":"traceutil/trace.go:171","msg":"trace[1264772591] transaction","detail":"{read_only:false; response_revision:9758; number_of_response:1; }","duration":"174.02587ms","start":"2026-09-10T02:16:15.325387Z","end":"2026-09-10T02:16:15.499413Z","steps":["trace[1264772591] 'process raft request' (duration: 173.632139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:16:15.499775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.890278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-10T02:16:15.499847Z","caller":"traceutil/trace.go:171","msg":"trace[618770690] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:9758; }","duration":"131.97517ms","start":"2026-09-10T02:16:15.367851Z","end":"2026-09-10T02:16:15.499826Z","steps":["trace[618770690] 'agreement among raft nodes before linearized reading' (duration: 131.810836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:16:15.499995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.630847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291247"} {"level":"info","ts":"2026-09-10T02:16:15.500056Z","caller":"traceutil/trace.go:171","msg":"trace[122977459] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9758; }","duration":"174.74585ms","start":"2026-09-10T02:16:15.325294Z","end":"2026-09-10T02:16:15.500039Z","steps":["trace[122977459] 'agreement among raft nodes before linearized reading' (duration: 174.234606ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:17:58.753443Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8044} {"level":"info","ts":"2026-09-10T02:17:58.842079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8044,"took":"86.158144ms","hash":2521295705,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":29057024,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-10T02:17:58.842158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2521295705,"revision":8044,"compact-revision":5837} {"level":"info","ts":"2026-09-10T02:19:57.626562Z","caller":"traceutil/trace.go:171","msg":"trace[1669591412] linearizableReadLoop","detail":"{readStateIndex:11984; appliedIndex:11983; }","duration":"149.094815ms","start":"2026-09-10T02:19:57.477438Z","end":"2026-09-10T02:19:57.626533Z","steps":["trace[1669591412] 'read index received' (duration: 124.337449ms)","trace[1669591412] 'applied index is now lower than readState.Index' (duration: 24.755735ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:19:57.626806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.334381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:19:57.626905Z","caller":"traceutil/trace.go:171","msg":"trace[869193762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11588; }","duration":"149.489545ms","start":"2026-09-10T02:19:57.477397Z","end":"2026-09-10T02:19:57.626886Z","steps":["trace[869193762] 'agreement among raft nodes before linearized reading' (duration: 149.29037ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:22:58.75971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10504} {"level":"info","ts":"2026-09-10T02:22:58.851098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10504,"took":"88.831247ms","hash":225100362,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":27738112,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T02:22:58.851165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":225100362,"revision":10504,"compact-revision":8044} {"level":"info","ts":"2026-09-10T02:25:54.696052Z","caller":"traceutil/trace.go:171","msg":"trace[2066527320] transaction","detail":"{read_only:false; response_revision:14015; number_of_response:1; }","duration":"117.638649ms","start":"2026-09-10T02:25:54.578375Z","end":"2026-09-10T02:25:54.696013Z","steps":["trace[2066527320] 'process raft request' (duration: 116.798075ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:26:14.929486Z","caller":"traceutil/trace.go:171","msg":"trace[1506619347] transaction","detail":"{read_only:false; response_revision:14126; number_of_response:1; }","duration":"135.92897ms","start":"2026-09-10T02:26:14.793499Z","end":"2026-09-10T02:26:14.929428Z","steps":["trace[1506619347] 'process raft request' (duration: 135.678514ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:26:14.930939Z","caller":"traceutil/trace.go:171","msg":"trace[312865623] linearizableReadLoop","detail":"{readStateIndex:14612; appliedIndex:14610; }","duration":"134.422829ms","start":"2026-09-10T02:26:14.796483Z","end":"2026-09-10T02:26:14.930906Z","steps":["trace[312865623] 'read index received' (duration: 132.689572ms)","trace[312865623] 'applied index is now lower than readState.Index' (duration: 1.732047ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:26:14.931189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.676056ms","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-09-10T02:26:14.931235Z","caller":"traceutil/trace.go:171","msg":"trace[923073358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14127; }","duration":"134.796049ms","start":"2026-09-10T02:26:14.796422Z","end":"2026-09-10T02:26:14.931218Z","steps":["trace[923073358] 'agreement among raft nodes before linearized reading' (duration: 134.605674ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:26:14.931216Z","caller":"traceutil/trace.go:171","msg":"trace[1246865853] transaction","detail":"{read_only:false; response_revision:14127; number_of_response:1; }","duration":"137.561975ms","start":"2026-09-10T02:26:14.793621Z","end":"2026-09-10T02:26:14.931183Z","steps":["trace[1246865853] 'process raft request' (duration: 137.096482ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:26:27.532953Z","caller":"traceutil/trace.go:171","msg":"trace[1301227416] linearizableReadLoop","detail":"{readStateIndex:14696; appliedIndex:14695; }","duration":"107.804678ms","start":"2026-09-10T02:26:27.425122Z","end":"2026-09-10T02:26:27.532927Z","steps":["trace[1301227416] 'read index received' (duration: 10.920749ms)","trace[1301227416] 'applied index is now lower than readState.Index' (duration: 96.882469ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:26:27.533159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.995344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T02:26:27.533192Z","caller":"traceutil/trace.go:171","msg":"trace[1357517558] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14207; }","duration":"108.098546ms","start":"2026-09-10T02:26:27.425083Z","end":"2026-09-10T02:26:27.533181Z","steps":["trace[1357517558] 'agreement among raft nodes before linearized reading' (duration: 107.992983ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:26:32.477704Z","caller":"traceutil/trace.go:171","msg":"trace[984238243] linearizableReadLoop","detail":"{readStateIndex:14726; appliedIndex:14725; }","duration":"135.342804ms","start":"2026-09-10T02:26:32.342332Z","end":"2026-09-10T02:26:32.477675Z","steps":["trace[984238243] 'read index received' (duration: 135.063156ms)","trace[984238243] 'applied index is now lower than readState.Index' (duration: 278.508µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:26:32.477826Z","caller":"traceutil/trace.go:171","msg":"trace[497424763] transaction","detail":"{read_only:false; response_revision:14236; number_of_response:1; }","duration":"168.70397ms","start":"2026-09-10T02:26:32.309077Z","end":"2026-09-10T02:26:32.477781Z","steps":["trace[497424763] 'process raft request' (duration: 168.411172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:26:32.4788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.447276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:930174"} {"level":"info","ts":"2026-09-10T02:26:32.478871Z","caller":"traceutil/trace.go:171","msg":"trace[917570887] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:14236; }","duration":"136.566498ms","start":"2026-09-10T02:26:32.342288Z","end":"2026-09-10T02:26:32.478854Z","steps":["trace[917570887] 'agreement among raft nodes before linearized reading' (duration: 135.497098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:26:33.625645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9160498152497233288,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-10T02:26:33.642257Z","caller":"traceutil/trace.go:171","msg":"trace[333244027] transaction","detail":"{read_only:false; response_revision:14240; number_of_response:1; }","duration":"582.448545ms","start":"2026-09-10T02:26:33.059775Z","end":"2026-09-10T02:26:33.642224Z","steps":["trace[333244027] 'process raft request' (duration: 582.295321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:26:33.642534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:26:33.059755Z","time spent":"582.577408ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:26:33.699986Z","caller":"traceutil/trace.go:171","msg":"trace[971499425] transaction","detail":"{read_only:false; response_revision:14241; number_of_response:1; }","duration":"639.380469ms","start":"2026-09-10T02:26:33.060558Z","end":"2026-09-10T02:26:33.699938Z","steps":["trace[971499425] 'process raft request' (duration: 599.075502ms)","trace[971499425] 'compare' (duration: 40.026629ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:26:33.700116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:26:33.06053Z","time spent":"639.513092ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:26:33.700205Z","caller":"traceutil/trace.go:171","msg":"trace[1351222620] linearizableReadLoop","detail":"{readStateIndex:14732; appliedIndex:14729; }","duration":"575.454524ms","start":"2026-09-10T02:26:33.124669Z","end":"2026-09-10T02:26:33.700124Z","steps":["trace[1351222620] 'read index received' (duration: 517.471072ms)","trace[1351222620] 'applied index is now lower than readState.Index' (duration: 57.981682ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:26:33.700413Z","caller":"traceutil/trace.go:171","msg":"trace[1173486002] transaction","detail":"{read_only:false; response_revision:14242; number_of_response:1; }","duration":"389.118699ms","start":"2026-09-10T02:26:33.311274Z","end":"2026-09-10T02:26:33.700393Z","steps":["trace[1173486002] 'process raft request' (duration: 388.585755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:26:33.700511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:26:33.311233Z","time spent":"389.210302ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:26:33.701603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.718704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-10T02:26:33.701722Z","caller":"traceutil/trace.go:171","msg":"trace[1236245977] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14244; }","duration":"550.876628ms","start":"2026-09-10T02:26:33.150811Z","end":"2026-09-10T02:26:33.701688Z","steps":["trace[1236245977] 'agreement among raft nodes before linearized reading' (duration: 550.532719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:26:33.701775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:26:33.150795Z","time spent":"550.96724ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-09-10T02:26:33.702161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.478439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:930174"} {"level":"info","ts":"2026-09-10T02:26:33.702227Z","caller":"traceutil/trace.go:171","msg":"trace[521948606] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:14244; }","duration":"577.563392ms","start":"2026-09-10T02:26:33.124647Z","end":"2026-09-10T02:26:33.70221Z","steps":["trace[521948606] 'agreement among raft nodes before linearized reading' (duration: 575.848525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:26:33.70227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:26:33.12463Z","time spent":"577.629244ms","remote":"127.0.0.1:48556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":86,"response size":930198,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-09-10T02:27:58.766501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12787} {"level":"info","ts":"2026-09-10T02:27:58.857607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12787,"took":"87.638665ms","hash":3721102054,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":30900224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-10T02:27:58.857652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3721102054,"revision":12787,"compact-revision":10504} {"level":"warn","ts":"2026-09-10T02:28:17.864999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.269498ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-10T02:28:17.86517Z","caller":"traceutil/trace.go:171","msg":"trace[724743767] linearizableReadLoop","detail":"{readStateIndex:15990; appliedIndex:15989; }","duration":"302.768169ms","start":"2026-09-10T02:28:17.562375Z","end":"2026-09-10T02:28:17.865143Z","steps":["trace[724743767] 'read index received' (duration: 95.259494ms)","trace[724743767] 'applied index is now lower than readState.Index' (duration: 207.507085ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:28:17.865769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.359345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-09-10T02:28:17.865895Z","caller":"traceutil/trace.go:171","msg":"trace[308573127] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15460; }","duration":"303.570481ms","start":"2026-09-10T02:28:17.562296Z","end":"2026-09-10T02:28:17.865867Z","steps":["trace[308573127] 'agreement among raft nodes before linearized reading' (duration: 303.209581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:28:17.865927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.709407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-10T02:28:17.865949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:28:17.56227Z","time spent":"303.669734ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-09-10T02:28:17.865965Z","caller":"traceutil/trace.go:171","msg":"trace[491155916] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15460; }","duration":"216.764059ms","start":"2026-09-10T02:28:17.649186Z","end":"2026-09-10T02:28:17.86595Z","steps":["trace[491155916] 'agreement among raft nodes before linearized reading' (duration: 216.669577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:28:17.865951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.34197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rpcvk\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-10T02:28:17.866075Z","caller":"traceutil/trace.go:171","msg":"trace[159785109] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rpcvk; range_end:; response_count:1; response_revision:15460; }","duration":"203.529335ms","start":"2026-09-10T02:28:17.662515Z","end":"2026-09-10T02:28:17.866044Z","steps":["trace[159785109] 'agreement among raft nodes before linearized reading' (duration: 203.204106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:28:17.866215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.135237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T02:28:17.865825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.438114ms","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-09-10T02:28:17.866261Z","caller":"traceutil/trace.go:171","msg":"trace[1720642979] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:15460; }","duration":"218.180928ms","start":"2026-09-10T02:28:17.648068Z","end":"2026-09-10T02:28:17.866249Z","steps":["trace[1720642979] 'agreement among raft nodes before linearized reading' (duration: 218.112526ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:28:17.86629Z","caller":"traceutil/trace.go:171","msg":"trace[1215534403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15460; }","duration":"247.00473ms","start":"2026-09-10T02:28:17.619271Z","end":"2026-09-10T02:28:17.866276Z","steps":["trace[1215534403] 'agreement among raft nodes before linearized reading' (duration: 246.330121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:28:17.866517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.885077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-09-10T02:28:17.866552Z","caller":"traceutil/trace.go:171","msg":"trace[735151857] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15460; }","duration":"219.013401ms","start":"2026-09-10T02:28:17.64753Z","end":"2026-09-10T02:28:17.866543Z","steps":["trace[735151857] 'agreement among raft nodes before linearized reading' (duration: 218.875898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:28:17.866624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.770013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-10T02:28:17.866688Z","caller":"traceutil/trace.go:171","msg":"trace[12459432] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:15460; }","duration":"187.901977ms","start":"2026-09-10T02:28:17.678771Z","end":"2026-09-10T02:28:17.866673Z","steps":["trace[12459432] 'agreement among raft nodes before linearized reading' (duration: 187.606919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:28:17.866745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.152503ms","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-09-10T02:28:17.866782Z","caller":"traceutil/trace.go:171","msg":"trace[928761783] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15460; }","duration":"220.231054ms","start":"2026-09-10T02:28:17.646542Z","end":"2026-09-10T02:28:17.866773Z","steps":["trace[928761783] 'agreement among raft nodes before linearized reading' (duration: 220.174703ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:28:18.030357Z","caller":"traceutil/trace.go:171","msg":"trace[1292324426] linearizableReadLoop","detail":"{readStateIndex:15991; appliedIndex:15990; }","duration":"157.731739ms","start":"2026-09-10T02:28:17.872593Z","end":"2026-09-10T02:28:18.030325Z","steps":["trace[1292324426] 'read index received' (duration: 157.438961ms)","trace[1292324426] 'applied index is now lower than readState.Index' (duration: 291.568µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:28:18.031486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.83374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769631"} {"level":"info","ts":"2026-09-10T02:28:18.031535Z","caller":"traceutil/trace.go:171","msg":"trace[515098020] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15461; }","duration":"158.936292ms","start":"2026-09-10T02:28:17.872587Z","end":"2026-09-10T02:28:18.031523Z","steps":["trace[515098020] 'agreement among raft nodes before linearized reading' (duration: 157.910083ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:28:18.032267Z","caller":"traceutil/trace.go:171","msg":"trace[1024662886] transaction","detail":"{read_only:false; response_revision:15461; number_of_response:1; }","duration":"161.434471ms","start":"2026-09-10T02:28:17.870817Z","end":"2026-09-10T02:28:18.032251Z","steps":["trace[1024662886] 'process raft request' (duration: 159.303032ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:31:10.928614Z","caller":"traceutil/trace.go:171","msg":"trace[1950074464] transaction","detail":"{read_only:false; response_revision:16829; number_of_response:1; }","duration":"155.975814ms","start":"2026-09-10T02:31:10.772618Z","end":"2026-09-10T02:31:10.928594Z","steps":["trace[1950074464] 'process raft request' (duration: 117.969018ms)","trace[1950074464] 'compare' (duration: 37.882113ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:31:10.928772Z","caller":"traceutil/trace.go:171","msg":"trace[659973389] transaction","detail":"{read_only:false; response_revision:16830; number_of_response:1; }","duration":"126.0209ms","start":"2026-09-10T02:31:10.802743Z","end":"2026-09-10T02:31:10.928764Z","steps":["trace[659973389] 'process raft request' (duration: 125.823684ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:32:58.773972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15363} {"level":"info","ts":"2026-09-10T02:32:58.878631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15363,"took":"101.583106ms","hash":516324978,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":32215040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-10T02:32:58.878701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":516324978,"revision":15363,"compact-revision":12787} {"level":"info","ts":"2026-09-10T02:33:55.638013Z","caller":"traceutil/trace.go:171","msg":"trace[1618925173] transaction","detail":"{read_only:false; response_revision:17999; number_of_response:1; }","duration":"186.392131ms","start":"2026-09-10T02:33:55.451599Z","end":"2026-09-10T02:33:55.637991Z","steps":["trace[1618925173] 'process raft request' (duration: 186.261197ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:33:55.644015Z","caller":"traceutil/trace.go:171","msg":"trace[935133270] transaction","detail":"{read_only:false; response_revision:18000; number_of_response:1; }","duration":"110.625365ms","start":"2026-09-10T02:33:55.533373Z","end":"2026-09-10T02:33:55.643999Z","steps":["trace[935133270] 'process raft request' (duration: 110.401979ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:34:01.714509Z","caller":"traceutil/trace.go:171","msg":"trace[1361582365] transaction","detail":"{read_only:false; response_revision:18058; number_of_response:1; }","duration":"113.776372ms","start":"2026-09-10T02:34:01.600716Z","end":"2026-09-10T02:34:01.714492Z","steps":["trace[1361582365] 'process raft request' (duration: 113.614548ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:34:02.075828Z","caller":"traceutil/trace.go:171","msg":"trace[1577761328] linearizableReadLoop","detail":"{readStateIndex:18677; appliedIndex:18674; }","duration":"356.620428ms","start":"2026-09-10T02:34:01.719186Z","end":"2026-09-10T02:34:02.075806Z","steps":["trace[1577761328] 'read index received' (duration: 356.369941ms)","trace[1577761328] 'applied index is now lower than readState.Index' (duration: 249.827µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:34:02.076063Z","caller":"traceutil/trace.go:171","msg":"trace[275846866] transaction","detail":"{read_only:false; response_revision:18059; number_of_response:1; }","duration":"420.448785ms","start":"2026-09-10T02:34:01.655604Z","end":"2026-09-10T02:34:02.076053Z","steps":["trace[275846866] 'process raft request' (duration: 420.040404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:34:02.076159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:34:01.655588Z","time spent":"420.500466ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:34:02.076291Z","caller":"traceutil/trace.go:171","msg":"trace[96521240] transaction","detail":"{read_only:false; response_revision:18060; number_of_response:1; }","duration":"400.882796ms","start":"2026-09-10T02:34:01.6754Z","end":"2026-09-10T02:34:02.076283Z","steps":["trace[96521240] 'process raft request' (duration: 400.357562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:34:02.076331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:34:01.675383Z","time spent":"400.926707ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:34:02.076442Z","caller":"traceutil/trace.go:171","msg":"trace[348225668] transaction","detail":"{read_only:false; response_revision:18061; number_of_response:1; }","duration":"400.968908ms","start":"2026-09-10T02:34:01.675465Z","end":"2026-09-10T02:34:02.076434Z","steps":["trace[348225668] 'process raft request' (duration: 400.3186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:34:02.076477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:34:01.675444Z","time spent":"401.01573ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:34:02.076629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.450541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-266d7df3-c07b-4e89-8f71-ae90acdb3ac0\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-09-10T02:34:02.077449Z","caller":"traceutil/trace.go:171","msg":"trace[1979011253] range","detail":"{range_begin:/registry/persistentvolumes/pvc-266d7df3-c07b-4e89-8f71-ae90acdb3ac0; range_end:; response_count:1; response_revision:18061; }","duration":"358.285143ms","start":"2026-09-10T02:34:01.719145Z","end":"2026-09-10T02:34:02.07743Z","steps":["trace[1979011253] 'agreement among raft nodes before linearized reading' (duration: 357.412079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:34:02.076792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.221058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-10T02:34:02.07753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:34:01.719129Z","time spent":"358.389136ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1342,"request content":"key:\"/registry/persistentvolumes/pvc-266d7df3-c07b-4e89-8f71-ae90acdb3ac0\" "} {"level":"info","ts":"2026-09-10T02:34:02.077611Z","caller":"traceutil/trace.go:171","msg":"trace[902036418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18061; }","duration":"251.04365ms","start":"2026-09-10T02:34:01.826534Z","end":"2026-09-10T02:34:02.077578Z","steps":["trace[902036418] 'agreement among raft nodes before linearized reading' (duration: 250.204217ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:36:30.529487Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab61124f07c17f20","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-10T02:36:30.536514Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-10T02:36:30.536651Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-10T02:37:58.783036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17554} {"level":"info","ts":"2026-09-10T02:37:58.888107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17554,"took":"101.775799ms","hash":3413109331,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":34766848,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-10T02:37:58.888172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3413109331,"revision":17554,"compact-revision":15363} {"level":"warn","ts":"2026-09-10T02:38:59.716028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.046825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T02:38:59.716133Z","caller":"traceutil/trace.go:171","msg":"trace[982080757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20401; }","duration":"239.249761ms","start":"2026-09-10T02:38:59.476859Z","end":"2026-09-10T02:38:59.716109Z","steps":["trace[982080757] 'range keys from in-memory index tree' (duration: 238.941083ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:20.718407Z","caller":"traceutil/trace.go:171","msg":"trace[550578737] transaction","detail":"{read_only:false; response_revision:20509; number_of_response:1; }","duration":"135.126729ms","start":"2026-09-10T02:39:20.583257Z","end":"2026-09-10T02:39:20.718384Z","steps":["trace[550578737] 'process raft request' (duration: 134.970075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:20.900536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.089282ms","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:8"} {"level":"info","ts":"2026-09-10T02:39:20.900614Z","caller":"traceutil/trace.go:171","msg":"trace[1148902407] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20509; }","duration":"104.201425ms","start":"2026-09-10T02:39:20.796396Z","end":"2026-09-10T02:39:20.900597Z","steps":["trace[1148902407] 'count revisions from in-memory index tree' (duration: 103.990929ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:21.073044Z","caller":"traceutil/trace.go:171","msg":"trace[852271370] transaction","detail":"{read_only:false; response_revision:20510; number_of_response:1; }","duration":"168.175071ms","start":"2026-09-10T02:39:20.904839Z","end":"2026-09-10T02:39:21.073014Z","steps":["trace[852271370] 'process raft request' (duration: 167.858412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:21.271244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.555048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-10T02:39:21.271363Z","caller":"traceutil/trace.go:171","msg":"trace[1265960183] transaction","detail":"{read_only:false; response_revision:20511; number_of_response:1; }","duration":"366.187334ms","start":"2026-09-10T02:39:20.905157Z","end":"2026-09-10T02:39:21.271344Z","steps":["trace[1265960183] 'process raft request' (duration: 212.418801ms)","trace[1265960183] 'compare' (duration: 153.416534ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:39:21.271425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:39:20.905133Z","time spent":"366.264367ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:39:21.573235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.23415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-10T02:39:21.573422Z","caller":"traceutil/trace.go:171","msg":"trace[555155185] linearizableReadLoop","detail":"{readStateIndex:21212; appliedIndex:21211; }","duration":"219.371594ms","start":"2026-09-10T02:39:21.354031Z","end":"2026-09-10T02:39:21.573402Z","steps":["trace[555155185] 'read index received' (duration: 74.859716ms)","trace[555155185] 'applied index is now lower than readState.Index' (duration: 144.510258ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:39:21.573533Z","caller":"traceutil/trace.go:171","msg":"trace[1185341628] transaction","detail":"{read_only:false; response_revision:20512; number_of_response:1; }","duration":"273.894298ms","start":"2026-09-10T02:39:21.299624Z","end":"2026-09-10T02:39:21.573518Z","steps":["trace[1185341628] 'process raft request' (duration: 129.260686ms)","trace[1185341628] 'compare' (duration: 143.86654ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:39:21.573823Z","caller":"traceutil/trace.go:171","msg":"trace[195304712] transaction","detail":"{read_only:false; response_revision:20514; number_of_response:1; }","duration":"156.034906ms","start":"2026-09-10T02:39:21.417756Z","end":"2026-09-10T02:39:21.573791Z","steps":["trace[195304712] 'process raft request' (duration: 155.80422ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:21.57417Z","caller":"traceutil/trace.go:171","msg":"trace[2114398735] transaction","detail":"{read_only:false; response_revision:20513; number_of_response:1; }","duration":"199.70431ms","start":"2026-09-10T02:39:21.374445Z","end":"2026-09-10T02:39:21.574149Z","steps":["trace[2114398735] 'process raft request' (duration: 198.904758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:21.574896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.846825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T02:39:21.574956Z","caller":"traceutil/trace.go:171","msg":"trace[1254236709] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:20514; }","duration":"220.951948ms","start":"2026-09-10T02:39:21.353988Z","end":"2026-09-10T02:39:21.57494Z","steps":["trace[1254236709] 'agreement among raft nodes before linearized reading' (duration: 219.771255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:21.954915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.835851ms","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-09-10T02:39:21.954992Z","caller":"traceutil/trace.go:171","msg":"trace[369755236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20514; }","duration":"126.969414ms","start":"2026-09-10T02:39:21.828005Z","end":"2026-09-10T02:39:21.954975Z","steps":["trace[369755236] 'range keys from in-memory index tree' (duration: 126.651685ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:22.098478Z","caller":"traceutil/trace.go:171","msg":"trace[1653680577] transaction","detail":"{read_only:false; response_revision:20515; number_of_response:1; }","duration":"140.266141ms","start":"2026-09-10T02:39:21.958157Z","end":"2026-09-10T02:39:22.09844Z","steps":["trace[1653680577] 'process raft request' (duration: 140.031604ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:22.150619Z","caller":"traceutil/trace.go:171","msg":"trace[717345959] linearizableReadLoop","detail":"{readStateIndex:21216; appliedIndex:21214; }","duration":"165.232749ms","start":"2026-09-10T02:39:21.985354Z","end":"2026-09-10T02:39:22.150587Z","steps":["trace[717345959] 'read index received' (duration: 112.809592ms)","trace[717345959] 'applied index is now lower than readState.Index' (duration: 52.421707ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:39:22.150741Z","caller":"traceutil/trace.go:171","msg":"trace[1932831455] transaction","detail":"{read_only:false; response_revision:20516; number_of_response:1; }","duration":"191.285219ms","start":"2026-09-10T02:39:21.959419Z","end":"2026-09-10T02:39:22.150704Z","steps":["trace[1932831455] 'process raft request' (duration: 190.977171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:22.150864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.503037ms","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-09-10T02:39:22.150912Z","caller":"traceutil/trace.go:171","msg":"trace[245479143] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20516; }","duration":"165.570719ms","start":"2026-09-10T02:39:21.985327Z","end":"2026-09-10T02:39:22.150898Z","steps":["trace[245479143] 'agreement among raft nodes before linearized reading' (duration: 165.370913ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:50.670119Z","caller":"traceutil/trace.go:171","msg":"trace[314106048] linearizableReadLoop","detail":"{readStateIndex:21427; appliedIndex:21424; }","duration":"146.685547ms","start":"2026-09-10T02:39:50.523407Z","end":"2026-09-10T02:39:50.670093Z","steps":["trace[314106048] 'read index received' (duration: 40.62428ms)","trace[314106048] 'applied index is now lower than readState.Index' (duration: 106.060227ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:39:50.670383Z","caller":"traceutil/trace.go:171","msg":"trace[1770663123] transaction","detail":"{read_only:false; response_revision:20714; number_of_response:1; }","duration":"147.092729ms","start":"2026-09-10T02:39:50.523279Z","end":"2026-09-10T02:39:50.670372Z","steps":["trace[1770663123] 'process raft request' (duration: 146.475892ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:50.670578Z","caller":"traceutil/trace.go:171","msg":"trace[154483349] transaction","detail":"{read_only:false; response_revision:20715; number_of_response:1; }","duration":"147.215612ms","start":"2026-09-10T02:39:50.523351Z","end":"2026-09-10T02:39:50.670567Z","steps":["trace[154483349] 'process raft request' (duration: 146.528583ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:50.670694Z","caller":"traceutil/trace.go:171","msg":"trace[2035089100] transaction","detail":"{read_only:false; response_revision:20716; number_of_response:1; }","duration":"146.317547ms","start":"2026-09-10T02:39:50.524368Z","end":"2026-09-10T02:39:50.670686Z","steps":["trace[2035089100] 'process raft request' (duration: 145.546006ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:50.670805Z","caller":"traceutil/trace.go:171","msg":"trace[1545220239] transaction","detail":"{read_only:false; response_revision:20717; number_of_response:1; }","duration":"146.403129ms","start":"2026-09-10T02:39:50.524394Z","end":"2026-09-10T02:39:50.670797Z","steps":["trace[1545220239] 'process raft request' (duration: 145.554206ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:50.670916Z","caller":"traceutil/trace.go:171","msg":"trace[1110414732] transaction","detail":"{read_only:false; response_revision:20718; number_of_response:1; }","duration":"146.508562ms","start":"2026-09-10T02:39:50.524397Z","end":"2026-09-10T02:39:50.670906Z","steps":["trace[1110414732] 'process raft request' (duration: 145.579547ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:50.671039Z","caller":"traceutil/trace.go:171","msg":"trace[16623020] transaction","detail":"{read_only:false; response_revision:20719; number_of_response:1; }","duration":"146.273206ms","start":"2026-09-10T02:39:50.524755Z","end":"2026-09-10T02:39:50.671028Z","steps":["trace[16623020] 'process raft request' (duration: 145.244868ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:50.671607Z","caller":"traceutil/trace.go:171","msg":"trace[1723893252] transaction","detail":"{read_only:false; response_revision:20720; number_of_response:1; }","duration":"137.427902ms","start":"2026-09-10T02:39:50.534133Z","end":"2026-09-10T02:39:50.671561Z","steps":["trace[1723893252] 'process raft request' (duration: 135.900949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:50.671867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.434686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-09-10T02:39:50.671941Z","caller":"traceutil/trace.go:171","msg":"trace[1327074778] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:20720; }","duration":"148.56763ms","start":"2026-09-10T02:39:50.52336Z","end":"2026-09-10T02:39:50.671928Z","steps":["trace[1327074778] 'agreement among raft nodes before linearized reading' (duration: 148.345614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:50.671938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.098019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-09-10T02:39:50.67204Z","caller":"traceutil/trace.go:171","msg":"trace[1030516728] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:20720; }","duration":"147.217263ms","start":"2026-09-10T02:39:50.524802Z","end":"2026-09-10T02:39:50.672019Z","steps":["trace[1030516728] 'agreement among raft nodes before linearized reading' (duration: 146.954955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:50.672307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.616835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T02:39:50.672332Z","caller":"traceutil/trace.go:171","msg":"trace[1816855277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20720; }","duration":"134.704047ms","start":"2026-09-10T02:39:50.537622Z","end":"2026-09-10T02:39:50.672326Z","steps":["trace[1816855277] 'agreement among raft nodes before linearized reading' (duration: 134.654215ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:51.277274Z","caller":"traceutil/trace.go:171","msg":"trace[2048035406] linearizableReadLoop","detail":"{readStateIndex:21446; appliedIndex:21445; }","duration":"112.574276ms","start":"2026-09-10T02:39:51.164681Z","end":"2026-09-10T02:39:51.277256Z","steps":["trace[2048035406] 'read index received' (duration: 60.461717ms)","trace[2048035406] 'applied index is now lower than readState.Index' (duration: 52.111759ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:39:51.277386Z","caller":"traceutil/trace.go:171","msg":"trace[1832702646] transaction","detail":"{read_only:false; number_of_response:1; response_revision:20732; }","duration":"118.410727ms","start":"2026-09-10T02:39:51.15895Z","end":"2026-09-10T02:39:51.277361Z","steps":["trace[1832702646] 'process raft request' (duration: 118.096418ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:51.277431Z","caller":"traceutil/trace.go:171","msg":"trace[158524841] transaction","detail":"{read_only:false; response_revision:20733; number_of_response:1; }","duration":"103.113196ms","start":"2026-09-10T02:39:51.174282Z","end":"2026-09-10T02:39:51.277395Z","steps":["trace[158524841] 'process raft request' (duration: 102.865149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:51.277467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.769721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-09-10T02:39:51.277512Z","caller":"traceutil/trace.go:171","msg":"trace[1415031604] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:20733; }","duration":"112.857094ms","start":"2026-09-10T02:39:51.164644Z","end":"2026-09-10T02:39:51.277501Z","steps":["trace[1415031604] 'agreement among raft nodes before linearized reading' (duration: 112.71984ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:51.39959Z","caller":"traceutil/trace.go:171","msg":"trace[250157297] transaction","detail":"{read_only:false; response_revision:20737; number_of_response:1; }","duration":"101.518111ms","start":"2026-09-10T02:39:51.298037Z","end":"2026-09-10T02:39:51.399555Z","steps":["trace[250157297] 'process raft request' (duration: 82.032644ms)","trace[250157297] 'compare' (duration: 18.036388ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:39:51.399871Z","caller":"traceutil/trace.go:171","msg":"trace[1483820882] transaction","detail":"{read_only:false; response_revision:20738; number_of_response:1; }","duration":"101.692827ms","start":"2026-09-10T02:39:51.298153Z","end":"2026-09-10T02:39:51.399846Z","steps":["trace[1483820882] 'process raft request' (duration: 100.226146ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:51.668909Z","caller":"traceutil/trace.go:171","msg":"trace[685380538] transaction","detail":"{read_only:false; response_revision:20749; number_of_response:1; }","duration":"111.099316ms","start":"2026-09-10T02:39:51.557785Z","end":"2026-09-10T02:39:51.668884Z","steps":["trace[685380538] 'process raft request' (duration: 76.295515ms)","trace[685380538] 'compare' (duration: 34.652796ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:39:55.107598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.523821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"} {"level":"info","ts":"2026-09-10T02:39:55.107688Z","caller":"traceutil/trace.go:171","msg":"trace[1323691258] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:20782; }","duration":"101.701456ms","start":"2026-09-10T02:39:55.005967Z","end":"2026-09-10T02:39:55.107668Z","steps":["trace[1323691258] 'range keys from in-memory index tree' (duration: 100.483702ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.779688Z","caller":"traceutil/trace.go:171","msg":"trace[1089000910] transaction","detail":"{read_only:false; response_revision:20864; number_of_response:1; }","duration":"132.096855ms","start":"2026-09-10T02:39:56.647557Z","end":"2026-09-10T02:39:56.779653Z","steps":["trace[1089000910] 'process raft request' (duration: 96.666507ms)","trace[1089000910] 'compare' (duration: 35.219682ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:39:56.779735Z","caller":"traceutil/trace.go:171","msg":"trace[763728132] transaction","detail":"{read_only:false; response_revision:20865; number_of_response:1; }","duration":"128.235229ms","start":"2026-09-10T02:39:56.651475Z","end":"2026-09-10T02:39:56.77971Z","steps":["trace[763728132] 'process raft request' (duration: 128.109785ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.779879Z","caller":"traceutil/trace.go:171","msg":"trace[114249160] transaction","detail":"{read_only:false; response_revision:20867; number_of_response:1; }","duration":"126.545191ms","start":"2026-09-10T02:39:56.653304Z","end":"2026-09-10T02:39:56.779849Z","steps":["trace[114249160] 'process raft request' (duration: 126.390067ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.77993Z","caller":"traceutil/trace.go:171","msg":"trace[1604341818] transaction","detail":"{read_only:false; response_revision:20868; number_of_response:1; }","duration":"125.861633ms","start":"2026-09-10T02:39:56.654054Z","end":"2026-09-10T02:39:56.779915Z","steps":["trace[1604341818] 'process raft request' (duration: 125.677118ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.779879Z","caller":"traceutil/trace.go:171","msg":"trace[1979113129] transaction","detail":"{read_only:false; response_revision:20866; number_of_response:1; }","duration":"126.877421ms","start":"2026-09-10T02:39:56.652962Z","end":"2026-09-10T02:39:56.77984Z","steps":["trace[1979113129] 'process raft request' (duration: 126.671245ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.780087Z","caller":"traceutil/trace.go:171","msg":"trace[84222935] transaction","detail":"{read_only:false; response_revision:20869; number_of_response:1; }","duration":"125.701638ms","start":"2026-09-10T02:39:56.654369Z","end":"2026-09-10T02:39:56.780071Z","steps":["trace[84222935] 'process raft request' (duration: 125.41309ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.780297Z","caller":"traceutil/trace.go:171","msg":"trace[930450495] transaction","detail":"{read_only:false; response_revision:20870; number_of_response:1; }","duration":"125.793241ms","start":"2026-09-10T02:39:56.654485Z","end":"2026-09-10T02:39:56.780278Z","steps":["trace[930450495] 'process raft request' (duration: 125.423511ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.780656Z","caller":"traceutil/trace.go:171","msg":"trace[1886832793] transaction","detail":"{read_only:false; response_revision:20871; number_of_response:1; }","duration":"126.089069ms","start":"2026-09-10T02:39:56.654526Z","end":"2026-09-10T02:39:56.780615Z","steps":["trace[1886832793] 'process raft request' (duration: 125.434341ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.78079Z","caller":"traceutil/trace.go:171","msg":"trace[788959865] transaction","detail":"{read_only:false; response_revision:20875; number_of_response:1; }","duration":"121.592575ms","start":"2026-09-10T02:39:56.659173Z","end":"2026-09-10T02:39:56.780766Z","steps":["trace[788959865] 'process raft request' (duration: 121.499873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:56.780998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.006591ms","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-09-10T02:39:56.781019Z","caller":"traceutil/trace.go:171","msg":"trace[15537078] transaction","detail":"{read_only:false; response_revision:20872; number_of_response:1; }","duration":"126.451849ms","start":"2026-09-10T02:39:56.654552Z","end":"2026-09-10T02:39:56.781003Z","steps":["trace[15537078] 'process raft request' (duration: 125.959085ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.781047Z","caller":"traceutil/trace.go:171","msg":"trace[395310249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20875; }","duration":"116.087963ms","start":"2026-09-10T02:39:56.664945Z","end":"2026-09-10T02:39:56.781033Z","steps":["trace[395310249] 'agreement among raft nodes before linearized reading' (duration: 115.945889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:56.781177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.881759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/octavia-housekeeping\" ","response":"range_response_count:1 size:7403"} {"level":"info","ts":"2026-09-10T02:39:56.781203Z","caller":"traceutil/trace.go:171","msg":"trace[1960089682] transaction","detail":"{read_only:false; response_revision:20873; number_of_response:1; }","duration":"126.043508ms","start":"2026-09-10T02:39:56.655145Z","end":"2026-09-10T02:39:56.781188Z","steps":["trace[1960089682] 'process raft request' (duration: 125.4451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:56.781321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.84842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/octavia-api\" ","response":"range_response_count:1 size:7774"} {"level":"warn","ts":"2026-09-10T02:39:56.781335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.535769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-09-10T02:39:56.78135Z","caller":"traceutil/trace.go:171","msg":"trace[920568399] range","detail":"{range_begin:/registry/deployments/openstack/octavia-api; range_end:; response_count:1; response_revision:20875; }","duration":"122.877401ms","start":"2026-09-10T02:39:56.658465Z","end":"2026-09-10T02:39:56.781342Z","steps":["trace[920568399] 'agreement among raft nodes before linearized reading' (duration: 122.794109ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.781383Z","caller":"traceutil/trace.go:171","msg":"trace[995834133] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:20875; }","duration":"131.581111ms","start":"2026-09-10T02:39:56.649786Z","end":"2026-09-10T02:39:56.781367Z","steps":["trace[995834133] 'agreement among raft nodes before linearized reading' (duration: 131.461687ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.781472Z","caller":"traceutil/trace.go:171","msg":"trace[830070821] transaction","detail":"{read_only:false; response_revision:20874; number_of_response:1; }","duration":"123.009605ms","start":"2026-09-10T02:39:56.658443Z","end":"2026-09-10T02:39:56.781452Z","steps":["trace[830070821] 'process raft request' (duration: 122.189792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:56.781572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.841105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/octavia-worker\" ","response":"range_response_count:1 size:7643"} {"level":"info","ts":"2026-09-10T02:39:56.781601Z","caller":"traceutil/trace.go:171","msg":"trace[1804630704] range","detail":"{range_begin:/registry/deployments/openstack/octavia-worker; range_end:; response_count:1; response_revision:20875; }","duration":"124.870726ms","start":"2026-09-10T02:39:56.656721Z","end":"2026-09-10T02:39:56.781592Z","steps":["trace[1804630704] 'agreement among raft nodes before linearized reading' (duration: 124.783853ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.781239Z","caller":"traceutil/trace.go:171","msg":"trace[901739299] range","detail":"{range_begin:/registry/deployments/openstack/octavia-housekeeping; range_end:; response_count:1; response_revision:20875; }","duration":"127.980292ms","start":"2026-09-10T02:39:56.653246Z","end":"2026-09-10T02:39:56.781226Z","steps":["trace[901739299] 'agreement among raft nodes before linearized reading' (duration: 127.774975ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.981117Z","caller":"traceutil/trace.go:171","msg":"trace[1476512252] transaction","detail":"{read_only:false; response_revision:20890; number_of_response:1; }","duration":"103.66214ms","start":"2026-09-10T02:39:56.87742Z","end":"2026-09-10T02:39:56.981082Z","steps":["trace[1476512252] 'process raft request' (duration: 103.393053ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.981309Z","caller":"traceutil/trace.go:171","msg":"trace[1606827401] transaction","detail":"{read_only:false; response_revision:20891; number_of_response:1; }","duration":"103.561737ms","start":"2026-09-10T02:39:56.877737Z","end":"2026-09-10T02:39:56.981299Z","steps":["trace[1606827401] 'process raft request' (duration: 103.203957ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.981613Z","caller":"traceutil/trace.go:171","msg":"trace[1317780610] transaction","detail":"{read_only:false; response_revision:20892; number_of_response:1; }","duration":"103.322331ms","start":"2026-09-10T02:39:56.878274Z","end":"2026-09-10T02:39:56.981596Z","steps":["trace[1317780610] 'process raft request' (duration: 102.959831ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:39:56.982253Z","caller":"traceutil/trace.go:171","msg":"trace[629125082] transaction","detail":"{read_only:false; response_revision:20893; number_of_response:1; }","duration":"101.868921ms","start":"2026-09-10T02:39:56.88037Z","end":"2026-09-10T02:39:56.982239Z","steps":["trace[629125082] 'process raft request' (duration: 101.11312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:39:56.983716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.031633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1347"} {"level":"info","ts":"2026-09-10T02:39:56.983774Z","caller":"traceutil/trace.go:171","msg":"trace[1913804836] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:20897; }","duration":"107.127916ms","start":"2026-09-10T02:39:56.876629Z","end":"2026-09-10T02:39:56.983757Z","steps":["trace[1913804836] 'agreement among raft nodes before linearized reading' (duration: 106.958592ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:42:58.789664Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20061} {"level":"info","ts":"2026-09-10T02:42:58.932279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20061,"took":"138.328827ms","hash":3723687682,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":32133120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-10T02:42:58.932392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3723687682,"revision":20061,"compact-revision":17554} {"level":"info","ts":"2026-09-10T02:43:15.757094Z","caller":"traceutil/trace.go:171","msg":"trace[1320961972] transaction","detail":"{read_only:false; response_revision:22962; number_of_response:1; }","duration":"239.029266ms","start":"2026-09-10T02:43:15.518041Z","end":"2026-09-10T02:43:15.75707Z","steps":["trace[1320961972] 'process raft request' (duration: 238.855791ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:43:15.765698Z","caller":"traceutil/trace.go:171","msg":"trace[971870787] linearizableReadLoop","detail":"{readStateIndex:23738; appliedIndex:23737; }","duration":"127.879778ms","start":"2026-09-10T02:43:15.637788Z","end":"2026-09-10T02:43:15.765668Z","steps":["trace[971870787] 'read index received' (duration: 119.2567ms)","trace[971870787] 'applied index is now lower than readState.Index' (duration: 8.621748ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:43:15.765765Z","caller":"traceutil/trace.go:171","msg":"trace[1686162270] transaction","detail":"{read_only:false; response_revision:22963; number_of_response:1; }","duration":"142.189883ms","start":"2026-09-10T02:43:15.623557Z","end":"2026-09-10T02:43:15.765747Z","steps":["trace[1686162270] 'process raft request' (duration: 141.926676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:43:15.765919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.118915ms","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-09-10T02:43:15.765967Z","caller":"traceutil/trace.go:171","msg":"trace[717777134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22963; }","duration":"128.208328ms","start":"2026-09-10T02:43:15.637742Z","end":"2026-09-10T02:43:15.765951Z","steps":["trace[717777134] 'agreement among raft nodes before linearized reading' (duration: 128.034873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:44:29.060997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.950763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T02:44:29.061097Z","caller":"traceutil/trace.go:171","msg":"trace[1150946084] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:23798; }","duration":"102.122568ms","start":"2026-09-10T02:44:28.958954Z","end":"2026-09-10T02:44:29.061077Z","steps":["trace[1150946084] 'count revisions from in-memory index tree' (duration: 101.82066ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:47:58.79687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22364} {"level":"info","ts":"2026-09-10T02:47:58.91224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22364,"took":"112.34988ms","hash":1370420111,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":41934848,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-10T02:47:58.912319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1370420111,"revision":22364,"compact-revision":20061} {"level":"info","ts":"2026-09-10T02:52:58.804482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25393} {"level":"info","ts":"2026-09-10T02:52:58.946453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25393,"took":"137.923942ms","hash":1804905646,"current-db-size-bytes":56664064,"current-db-size":"57 MB","current-db-size-in-use-bytes":29548544,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-10T02:52:58.946511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1804905646,"revision":25393,"compact-revision":22364}