{"level":"warn","ts":"2026-10-07T22:04:39.416959Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-07T22:04:39.417309Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.203: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.203:2380","--initial-cluster=instance=https://162.253.55.203:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.203:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.203:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-07T22:04:39.417984Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-07T22:04:39.418035Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.203:2380"]} {"level":"info","ts":"2026-10-07T22:04:39.419085Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-07T22:04:39.420749Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.203:2379"]} {"level":"info","ts":"2026-10-07T22:04:39.420892Z","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.203:2380"],"listen-peer-urls":["https://162.253.55.203:2380"],"advertise-client-urls":["https://162.253.55.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.203:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-07T22:04:39.425937Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.487504ms"} {"level":"info","ts":"2026-10-07T22:04:39.435242Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a656331365382b34","cluster-id":"84e77d9b2eaf556a"} {"level":"info","ts":"2026-10-07T22:04:39.435318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 switched to configuration voters=()"} {"level":"info","ts":"2026-10-07T22:04:39.435338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became follower at term 0"} {"level":"info","ts":"2026-10-07T22:04:39.435351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a656331365382b34 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-07T22:04:39.435359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became follower at term 1"} {"level":"info","ts":"2026-10-07T22:04:39.435397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 switched to configuration voters=(11985823616688728884)"} {"level":"warn","ts":"2026-10-07T22:04:39.444205Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-07T22:04:39.445879Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-07T22:04:39.447209Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-07T22:04:39.450623Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a656331365382b34","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-07T22:04:39.450844Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a656331365382b34","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-07T22:04:39.451267Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-07T22:04:39.451386Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-07T22:04:39.451426Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-07T22:04:39.455499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 switched to configuration voters=(11985823616688728884)"} {"level":"info","ts":"2026-10-07T22:04:39.455661Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"84e77d9b2eaf556a","local-member-id":"a656331365382b34","added-peer-id":"a656331365382b34","added-peer-peer-urls":["https://162.253.55.203:2380"]} {"level":"info","ts":"2026-10-07T22:04:39.45669Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-07T22:04:39.456851Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.203:2380"} {"level":"info","ts":"2026-10-07T22:04:39.456891Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.203:2380"} {"level":"info","ts":"2026-10-07T22:04:39.457067Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a656331365382b34","initial-advertise-peer-urls":["https://162.253.55.203:2380"],"listen-peer-urls":["https://162.253.55.203:2380"],"advertise-client-urls":["https://162.253.55.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-07T22:04:39.45714Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-07T22:04:40.336065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 is starting a new election at term 1"} {"level":"info","ts":"2026-10-07T22:04:40.336114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-07T22:04:40.33616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 received MsgPreVoteResp from a656331365382b34 at term 1"} {"level":"info","ts":"2026-10-07T22:04:40.33618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became candidate at term 2"} {"level":"info","ts":"2026-10-07T22:04:40.33619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 received MsgVoteResp from a656331365382b34 at term 2"} {"level":"info","ts":"2026-10-07T22:04:40.336205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a656331365382b34 became leader at term 2"} {"level":"info","ts":"2026-10-07T22:04:40.336217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a656331365382b34 elected leader a656331365382b34 at term 2"} {"level":"info","ts":"2026-10-07T22:04:40.33747Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T22:04:40.338678Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T22:04:40.338684Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a656331365382b34","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.203:2379]}","request-path":"/0/members/a656331365382b34/attributes","cluster-id":"84e77d9b2eaf556a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-07T22:04:40.338757Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T22:04:40.339085Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-07T22:04:40.339129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-07T22:04:40.340523Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"84e77d9b2eaf556a","local-member-id":"a656331365382b34","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T22:04:40.340634Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T22:04:40.340661Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T22:04:40.340691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-07T22:04:40.341835Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.203:2379"} {"level":"info","ts":"2026-10-07T22:04:52.38309Z","caller":"traceutil/trace.go:171","msg":"trace[897415317] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"160.408922ms","start":"2026-10-07T22:04:52.222652Z","end":"2026-10-07T22:04:52.383061Z","steps":["trace[897415317] 'read index received' (duration: 160.236198ms)","trace[897415317] 'applied index is now lower than readState.Index' (duration: 171.804µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:04:52.383136Z","caller":"traceutil/trace.go:171","msg":"trace[1034387794] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"215.262741ms","start":"2026-10-07T22:04:52.167843Z","end":"2026-10-07T22:04:52.383106Z","steps":["trace[1034387794] 'process raft request' (duration: 215.052295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:04:52.383685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.70548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T22:04:52.383821Z","caller":"traceutil/trace.go:171","msg":"trace[155406081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:360; }","duration":"161.191124ms","start":"2026-10-07T22:04:52.222611Z","end":"2026-10-07T22:04:52.383802Z","steps":["trace[155406081] 'agreement among raft nodes before linearized reading' (duration: 160.654549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:04:52.732921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.168702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-07T22:04:52.733028Z","caller":"traceutil/trace.go:171","msg":"trace[1999307969] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"167.860708ms","start":"2026-10-07T22:04:52.56515Z","end":"2026-10-07T22:04:52.733011Z","steps":["trace[1999307969] 'read index received' (duration: 58.562µs)","trace[1999307969] 'applied index is now lower than readState.Index' (duration: 167.800996ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:04:52.733101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.95476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-10-07T22:04:52.733124Z","caller":"traceutil/trace.go:171","msg":"trace[728415221] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:361; }","duration":"168.018382ms","start":"2026-10-07T22:04:52.565097Z","end":"2026-10-07T22:04:52.733115Z","steps":["trace[728415221] 'agreement among raft nodes before linearized reading' (duration: 167.95583ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:04:52.733097Z","caller":"traceutil/trace.go:171","msg":"trace[1720271096] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"345.418674ms","start":"2026-10-07T22:04:52.387637Z","end":"2026-10-07T22:04:52.733056Z","steps":["trace[1720271096] 'process raft request' (duration: 40.45693ms)","trace[1720271096] 'compare' (duration: 304.048719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:04:52.733464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:04:52.387615Z","time spent":"345.735753ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:05:24.002735Z","caller":"traceutil/trace.go:171","msg":"trace[432730350] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"113.152244ms","start":"2026-10-07T22:05:23.889554Z","end":"2026-10-07T22:05:24.002706Z","steps":["trace[432730350] 'process raft request' (duration: 112.928307ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:05:24.023369Z","caller":"traceutil/trace.go:171","msg":"trace[1914413589] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"117.965686ms","start":"2026-10-07T22:05:23.905381Z","end":"2026-10-07T22:05:24.023347Z","steps":["trace[1914413589] 'process raft request' (duration: 117.883254ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:05:24.023431Z","caller":"traceutil/trace.go:171","msg":"trace[1245453737] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"132.775717ms","start":"2026-10-07T22:05:23.890587Z","end":"2026-10-07T22:05:24.023363Z","steps":["trace[1245453737] 'process raft request' (duration: 132.395476ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:05:24.023366Z","caller":"traceutil/trace.go:171","msg":"trace[919857549] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"129.361212ms","start":"2026-10-07T22:05:23.893971Z","end":"2026-10-07T22:05:24.023332Z","steps":["trace[919857549] 'read index received' (duration: 108.392371ms)","trace[919857549] 'applied index is now lower than readState.Index' (duration: 20.968111ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:05:24.023498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.515937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-07T22:05:24.023541Z","caller":"traceutil/trace.go:171","msg":"trace[1816953412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"129.611069ms","start":"2026-10-07T22:05:23.893918Z","end":"2026-10-07T22:05:24.023529Z","steps":["trace[1816953412] 'agreement among raft nodes before linearized reading' (duration: 129.501606ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:05:30.808829Z","caller":"traceutil/trace.go:171","msg":"trace[1143448548] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"123.057905ms","start":"2026-10-07T22:05:30.685709Z","end":"2026-10-07T22:05:30.808767Z","steps":["trace[1143448548] 'process raft request' (duration: 62.037496ms)","trace[1143448548] 'compare' (duration: 60.860414ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:05:30.808889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.483093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nk2xs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-07T22:05:30.809022Z","caller":"traceutil/trace.go:171","msg":"trace[353660627] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nk2xs; range_end:; response_count:1; response_revision:613; }","duration":"155.683378ms","start":"2026-10-07T22:05:30.653311Z","end":"2026-10-07T22:05:30.808994Z","steps":["trace[353660627] 'range keys from in-memory index tree' (duration: 155.219645ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:05:31.313261Z","caller":"traceutil/trace.go:171","msg":"trace[56757161] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"485.965418ms","start":"2026-10-07T22:05:30.827252Z","end":"2026-10-07T22:05:31.313218Z","steps":["trace[56757161] 'process raft request' (duration: 441.328702ms)","trace[56757161] 'compare' (duration: 44.43463ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:05:31.313344Z","caller":"traceutil/trace.go:171","msg":"trace[1029710511] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"404.333238ms","start":"2026-10-07T22:05:30.90898Z","end":"2026-10-07T22:05:31.313313Z","steps":["trace[1029710511] 'read index received' (duration: 359.5996ms)","trace[1029710511] 'applied index is now lower than readState.Index' (duration: 44.730748ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:05:31.313451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:05:30.827193Z","time spent":"486.159892ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:05:31.313556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.562385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-07T22:05:31.313636Z","caller":"traceutil/trace.go:171","msg":"trace[299739227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"404.684978ms","start":"2026-10-07T22:05:30.908929Z","end":"2026-10-07T22:05:31.313614Z","steps":["trace[299739227] 'agreement among raft nodes before linearized reading' (duration: 404.466712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:05:31.313688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:05:30.908911Z","time spent":"404.76436ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-07T22:05:47.170717Z","caller":"traceutil/trace.go:171","msg":"trace[249308011] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"123.97272ms","start":"2026-10-07T22:05:47.046715Z","end":"2026-10-07T22:05:47.170688Z","steps":["trace[249308011] 'process raft request' (duration: 123.810916ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:06:37.18073Z","caller":"traceutil/trace.go:171","msg":"trace[1572170231] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"106.727453ms","start":"2026-10-07T22:06:37.073966Z","end":"2026-10-07T22:06:37.180693Z","steps":["trace[1572170231] 'process raft request' (duration: 106.60874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:07:03.019181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.540492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-07T22:07:03.019265Z","caller":"traceutil/trace.go:171","msg":"trace[518940096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"201.655394ms","start":"2026-10-07T22:07:02.817594Z","end":"2026-10-07T22:07:03.019249Z","steps":["trace[518940096] 'range keys from in-memory index tree' (duration: 201.408228ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:07:03.319289Z","caller":"traceutil/trace.go:171","msg":"trace[894103891] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"187.924333ms","start":"2026-10-07T22:07:03.131339Z","end":"2026-10-07T22:07:03.319263Z","steps":["trace[894103891] 'process raft request' (duration: 187.737578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:07:03.319437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.935696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T22:07:03.319492Z","caller":"traceutil/trace.go:171","msg":"trace[1195939580] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"138.7511ms","start":"2026-10-07T22:07:03.180474Z","end":"2026-10-07T22:07:03.319226Z","steps":["trace[1195939580] 'read index received' (duration: 138.555095ms)","trace[1195939580] 'applied index is now lower than readState.Index' (duration: 194.875µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:07:03.319518Z","caller":"traceutil/trace.go:171","msg":"trace[146261967] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1854; }","duration":"139.053189ms","start":"2026-10-07T22:07:03.180447Z","end":"2026-10-07T22:07:03.319501Z","steps":["trace[146261967] 'agreement among raft nodes before linearized reading' (duration: 138.922575ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:07:03.434427Z","caller":"traceutil/trace.go:171","msg":"trace[468361166] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1926; }","duration":"114.881733ms","start":"2026-10-07T22:07:03.31952Z","end":"2026-10-07T22:07:03.434402Z","steps":["trace[468361166] 'read index received' (duration: 114.732369ms)","trace[468361166] 'applied index is now lower than readState.Index' (duration: 148.414µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:07:03.434483Z","caller":"traceutil/trace.go:171","msg":"trace[1961555127] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"132.249003ms","start":"2026-10-07T22:07:03.30219Z","end":"2026-10-07T22:07:03.434439Z","steps":["trace[1961555127] 'process raft request' (duration: 132.074629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:07:03.434644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.267194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T22:07:03.434715Z","caller":"traceutil/trace.go:171","msg":"trace[1309382086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1855; }","duration":"211.377517ms","start":"2026-10-07T22:07:03.223314Z","end":"2026-10-07T22:07:03.434691Z","steps":["trace[1309382086] 'agreement among raft nodes before linearized reading' (duration: 211.264424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:07:03.43468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.172145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T22:07:03.435003Z","caller":"traceutil/trace.go:171","msg":"trace[1112758076] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1855; }","duration":"172.556326ms","start":"2026-10-07T22:07:03.262428Z","end":"2026-10-07T22:07:03.434984Z","steps":["trace[1112758076] 'agreement among raft nodes before linearized reading' (duration: 172.118344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:07:03.436398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.378145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-79093f30-531e-4ed8-80bb-c600b74858c7\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-10-07T22:07:03.436461Z","caller":"traceutil/trace.go:171","msg":"trace[1574559947] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-79093f30-531e-4ed8-80bb-c600b74858c7; range_end:; response_count:1; response_revision:1856; }","duration":"112.462578ms","start":"2026-10-07T22:07:03.32398Z","end":"2026-10-07T22:07:03.436443Z","steps":["trace[1574559947] 'agreement among raft nodes before linearized reading' (duration: 112.18924ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:07:58.17757Z","caller":"traceutil/trace.go:171","msg":"trace[1088078406] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"102.407106ms","start":"2026-10-07T22:07:58.075133Z","end":"2026-10-07T22:07:58.177541Z","steps":["trace[1088078406] 'process raft request' (duration: 102.267423ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:07:58.483265Z","caller":"traceutil/trace.go:171","msg":"trace[617497805] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"124.1803ms","start":"2026-10-07T22:07:58.35905Z","end":"2026-10-07T22:07:58.48323Z","steps":["trace[617497805] 'process raft request' (duration: 123.903062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:07:58.779032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.815483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-07T22:07:58.779194Z","caller":"traceutil/trace.go:171","msg":"trace[117795854] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"267.679185ms","start":"2026-10-07T22:07:58.511468Z","end":"2026-10-07T22:07:58.779148Z","steps":["trace[117795854] 'process raft request' (duration: 53.646536ms)","trace[117795854] 'compare' (duration: 213.591067ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:09:16.230322Z","caller":"traceutil/trace.go:171","msg":"trace[1175572817] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"114.420555ms","start":"2026-10-07T22:09:16.115872Z","end":"2026-10-07T22:09:16.230292Z","steps":["trace[1175572817] 'process raft request' (duration: 76.873473ms)","trace[1175572817] 'compare' (duration: 37.030807ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:12:24.00522Z","caller":"traceutil/trace.go:171","msg":"trace[848869672] linearizableReadLoop","detail":"{readStateIndex:4166; appliedIndex:4164; }","duration":"106.814699ms","start":"2026-10-07T22:12:23.898366Z","end":"2026-10-07T22:12:24.005181Z","steps":["trace[848869672] 'read index received' (duration: 99.066914ms)","trace[848869672] 'applied index is now lower than readState.Index' (duration: 7.746515ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:12:24.005469Z","caller":"traceutil/trace.go:171","msg":"trace[866724972] transaction","detail":"{read_only:false; response_revision:4012; number_of_response:1; }","duration":"252.496721ms","start":"2026-10-07T22:12:23.752952Z","end":"2026-10-07T22:12:24.005449Z","steps":["trace[866724972] 'process raft request' (duration: 244.520559ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:12:24.005646Z","caller":"traceutil/trace.go:171","msg":"trace[344543218] transaction","detail":"{read_only:false; response_revision:4013; number_of_response:1; }","duration":"229.941763ms","start":"2026-10-07T22:12:23.775659Z","end":"2026-10-07T22:12:24.005601Z","steps":["trace[344543218] 'process raft request' (duration: 229.458409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:12:24.006353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.90818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-07T22:12:24.006413Z","caller":"traceutil/trace.go:171","msg":"trace[1385028534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4013; }","duration":"108.083525ms","start":"2026-10-07T22:12:23.898314Z","end":"2026-10-07T22:12:24.006397Z","steps":["trace[1385028534] 'agreement among raft nodes before linearized reading' (duration: 107.004765ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:13:44.470768Z","caller":"traceutil/trace.go:171","msg":"trace[528223925] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"111.967288ms","start":"2026-10-07T22:13:44.358772Z","end":"2026-10-07T22:13:44.47074Z","steps":["trace[528223925] 'process raft request' (duration: 111.520525ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:13:52.353653Z","caller":"traceutil/trace.go:171","msg":"trace[1010778856] linearizableReadLoop","detail":"{readStateIndex:5411; appliedIndex:5410; }","duration":"161.729643ms","start":"2026-10-07T22:13:52.191898Z","end":"2026-10-07T22:13:52.353627Z","steps":["trace[1010778856] 'read index received' (duration: 161.534768ms)","trace[1010778856] 'applied index is now lower than readState.Index' (duration: 193.795µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:13:52.353798Z","caller":"traceutil/trace.go:171","msg":"trace[1225334129] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"196.04195ms","start":"2026-10-07T22:13:52.157742Z","end":"2026-10-07T22:13:52.353784Z","steps":["trace[1225334129] 'process raft request' (duration: 195.712851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:13:52.353962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.114788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T22:13:52.354039Z","caller":"traceutil/trace.go:171","msg":"trace[413974754] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:5213; }","duration":"151.226961ms","start":"2026-10-07T22:13:52.202793Z","end":"2026-10-07T22:13:52.35402Z","steps":["trace[413974754] 'agreement among raft nodes before linearized reading' (duration: 151.106358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:13:52.354264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.118815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T22:13:52.354309Z","caller":"traceutil/trace.go:171","msg":"trace[954221223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5213; }","duration":"115.221509ms","start":"2026-10-07T22:13:52.239076Z","end":"2026-10-07T22:13:52.354298Z","steps":["trace[954221223] 'agreement among raft nodes before linearized reading' (duration: 115.131997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:13:52.354808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.913267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786924"} {"level":"info","ts":"2026-10-07T22:13:52.354849Z","caller":"traceutil/trace.go:171","msg":"trace[1895864607] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5213; }","duration":"162.991089ms","start":"2026-10-07T22:13:52.191845Z","end":"2026-10-07T22:13:52.354836Z","steps":["trace[1895864607] 'agreement among raft nodes before linearized reading' (duration: 162.498495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:13:54.38721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.523818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-10-07T22:13:54.387379Z","caller":"traceutil/trace.go:171","msg":"trace[1425378119] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5237; }","duration":"156.912999ms","start":"2026-10-07T22:13:54.230396Z","end":"2026-10-07T22:13:54.387309Z","steps":["trace[1425378119] 'process raft request' (duration: 28.214136ms)","trace[1425378119] 'compare' (duration: 128.485927ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:14:06.706254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.188854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-07T22:14:06.706394Z","caller":"traceutil/trace.go:171","msg":"trace[141731190] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"357.474055ms","start":"2026-10-07T22:14:06.348896Z","end":"2026-10-07T22:14:06.70637Z","steps":["trace[141731190] 'process raft request' (duration: 101.060914ms)","trace[141731190] 'compare' (duration: 256.02191ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:14:06.706496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:14:06.348865Z","time spent":"357.564837ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4986,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:14:06.710632Z","caller":"traceutil/trace.go:171","msg":"trace[375656931] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"266.563243ms","start":"2026-10-07T22:14:06.444043Z","end":"2026-10-07T22:14:06.710606Z","steps":["trace[375656931] 'process raft request' (duration: 266.375328ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:14:06.733929Z","caller":"traceutil/trace.go:171","msg":"trace[223171444] linearizableReadLoop","detail":"{readStateIndex:5680; appliedIndex:5679; }","duration":"110.497547ms","start":"2026-10-07T22:14:06.623397Z","end":"2026-10-07T22:14:06.733895Z","steps":["trace[223171444] 'read index received' (duration: 87.794145ms)","trace[223171444] 'applied index is now lower than readState.Index' (duration: 22.702002ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:14:06.733989Z","caller":"traceutil/trace.go:171","msg":"trace[1370403785] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"247.606215ms","start":"2026-10-07T22:14:06.486357Z","end":"2026-10-07T22:14:06.733964Z","steps":["trace[1370403785] 'process raft request' (duration: 247.385019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:14:06.734558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.130744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-07T22:14:06.734652Z","caller":"traceutil/trace.go:171","msg":"trace[1446832749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5473; }","duration":"111.29456ms","start":"2026-10-07T22:14:06.623337Z","end":"2026-10-07T22:14:06.734631Z","steps":["trace[1446832749] 'agreement among raft nodes before linearized reading' (duration: 110.986831ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:14:06.840585Z","caller":"traceutil/trace.go:171","msg":"trace[1443659411] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"128.56789ms","start":"2026-10-07T22:14:06.711906Z","end":"2026-10-07T22:14:06.840474Z","steps":["trace[1443659411] 'process raft request' (duration: 128.334104ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:14:06.840717Z","caller":"traceutil/trace.go:171","msg":"trace[1246710564] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"128.369754ms","start":"2026-10-07T22:14:06.712327Z","end":"2026-10-07T22:14:06.840696Z","steps":["trace[1246710564] 'process raft request' (duration: 128.081706ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:14:06.840748Z","caller":"traceutil/trace.go:171","msg":"trace[1304261028] linearizableReadLoop","detail":"{readStateIndex:5683; appliedIndex:5680; }","duration":"106.696951ms","start":"2026-10-07T22:14:06.734029Z","end":"2026-10-07T22:14:06.840725Z","steps":["trace[1304261028] 'read index received' (duration: 106.261519ms)","trace[1304261028] 'applied index is now lower than readState.Index' (duration: 434.432µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:14:06.840942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.530502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T22:14:06.840986Z","caller":"traceutil/trace.go:171","msg":"trace[1910077930] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5476; }","duration":"201.609354ms","start":"2026-10-07T22:14:06.639366Z","end":"2026-10-07T22:14:06.840975Z","steps":["trace[1910077930] 'agreement among raft nodes before linearized reading' (duration: 201.442789ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:14:06.841017Z","caller":"traceutil/trace.go:171","msg":"trace[609995234] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"123.030046ms","start":"2026-10-07T22:14:06.717976Z","end":"2026-10-07T22:14:06.841006Z","steps":["trace[609995234] 'process raft request' (duration: 122.648855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:14:06.841308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.087525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-07T22:14:06.841382Z","caller":"traceutil/trace.go:171","msg":"trace[1126399481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5476; }","duration":"129.158527ms","start":"2026-10-07T22:14:06.712203Z","end":"2026-10-07T22:14:06.841361Z","steps":["trace[1126399481] 'agreement among raft nodes before linearized reading' (duration: 128.964862ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:14:06.846722Z","caller":"traceutil/trace.go:171","msg":"trace[508897099] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"106.931058ms","start":"2026-10-07T22:14:06.739772Z","end":"2026-10-07T22:14:06.846703Z","steps":["trace[508897099] 'process raft request' (duration: 106.819545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:14:14.154391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.520992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-07T22:14:14.154576Z","caller":"traceutil/trace.go:171","msg":"trace[1709756431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5653; }","duration":"122.753389ms","start":"2026-10-07T22:14:14.031804Z","end":"2026-10-07T22:14:14.154558Z","steps":["trace[1709756431] 'range keys from in-memory index tree' (duration: 122.316407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:14:14.154477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.403947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c3bcbbd-f194-4580-a014-88a8f84d9955\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-07T22:14:14.154674Z","caller":"traceutil/trace.go:171","msg":"trace[1257760739] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c3bcbbd-f194-4580-a014-88a8f84d9955; range_end:; response_count:1; response_revision:5653; }","duration":"109.637613ms","start":"2026-10-07T22:14:14.045017Z","end":"2026-10-07T22:14:14.154655Z","steps":["trace[1257760739] 'range keys from in-memory index tree' (duration: 109.190921ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:14:26.427695Z","caller":"traceutil/trace.go:171","msg":"trace[115967920] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"102.077052ms","start":"2026-10-07T22:14:26.32558Z","end":"2026-10-07T22:14:26.427657Z","steps":["trace[115967920] 'compare' (duration: 94.44917ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:14:40.371436Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2956} {"level":"info","ts":"2026-10-07T22:14:40.444929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2956,"took":"72.268862ms","hash":3216005584,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T22:14:40.444997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3216005584,"revision":2956,"compact-revision":-1} {"level":"info","ts":"2026-10-07T22:15:07.505344Z","caller":"traceutil/trace.go:171","msg":"trace[692903133] transaction","detail":"{read_only:false; response_revision:6138; number_of_response:1; }","duration":"119.219811ms","start":"2026-10-07T22:15:07.386092Z","end":"2026-10-07T22:15:07.505312Z","steps":["trace[692903133] 'process raft request' (duration: 119.011615ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:19:40.378328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5885} {"level":"info","ts":"2026-10-07T22:19:40.471864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5885,"took":"91.034388ms","hash":1835868275,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":26324992,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-07T22:19:40.471941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1835868275,"revision":5885,"compact-revision":2956} {"level":"warn","ts":"2026-10-07T22:20:23.312767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.150994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-07T22:20:23.312967Z","caller":"traceutil/trace.go:171","msg":"trace[1870356100] linearizableReadLoop","detail":"{readStateIndex:8772; appliedIndex:8770; }","duration":"210.955102ms","start":"2026-10-07T22:20:23.101994Z","end":"2026-10-07T22:20:23.312949Z","steps":["trace[1870356100] 'read index received' (duration: 26.54131ms)","trace[1870356100] 'applied index is now lower than readState.Index' (duration: 184.412702ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:20:23.313189Z","caller":"traceutil/trace.go:171","msg":"trace[1571127947] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"443.807585ms","start":"2026-10-07T22:20:22.869369Z","end":"2026-10-07T22:20:23.313177Z","steps":["trace[1571127947] 'process raft request' (duration: 259.178418ms)","trace[1571127947] 'compare' (duration: 183.961499ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:20:23.31326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:20:22.869353Z","time spent":"443.870587ms","remote":"127.0.0.1:42548","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-10-07T22:20:23.31345Z","caller":"traceutil/trace.go:171","msg":"trace[544849640] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"442.571911ms","start":"2026-10-07T22:20:22.870868Z","end":"2026-10-07T22:20:23.31344Z","steps":["trace[544849640] 'process raft request' (duration: 442.032006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:20:23.313505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:20:22.870849Z","time spent":"442.629283ms","remote":"127.0.0.1:42548","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-10-07T22:20:23.313666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.587466ms","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":"warn","ts":"2026-10-07T22:20:23.313719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.697753ms","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-10-07T22:20:23.313757Z","caller":"traceutil/trace.go:171","msg":"trace[1071763753] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8474; }","duration":"104.73559ms","start":"2026-10-07T22:20:23.208998Z","end":"2026-10-07T22:20:23.313733Z","steps":["trace[1071763753] 'agreement among raft nodes before linearized reading' (duration: 104.551845ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:20:23.313811Z","caller":"traceutil/trace.go:171","msg":"trace[110101354] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8474; }","duration":"211.825676ms","start":"2026-10-07T22:20:23.101961Z","end":"2026-10-07T22:20:23.313786Z","steps":["trace[110101354] 'agreement among raft nodes before linearized reading' (duration: 211.570569ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:20:34.72871Z","caller":"traceutil/trace.go:171","msg":"trace[236923420] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"120.102369ms","start":"2026-10-07T22:20:34.608583Z","end":"2026-10-07T22:20:34.728686Z","steps":["trace[236923420] 'process raft request' (duration: 119.969526ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:20:34.847366Z","caller":"traceutil/trace.go:171","msg":"trace[813288489] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"236.254988ms","start":"2026-10-07T22:20:34.611048Z","end":"2026-10-07T22:20:34.847303Z","steps":["trace[813288489] 'process raft request' (duration: 146.057103ms)","trace[813288489] 'compare' (duration: 90.042791ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:20:35.614936Z","caller":"traceutil/trace.go:171","msg":"trace[1141658794] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"241.240187ms","start":"2026-10-07T22:20:35.37367Z","end":"2026-10-07T22:20:35.614911Z","steps":["trace[1141658794] 'process raft request' (duration: 241.094563ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:20:35.721661Z","caller":"traceutil/trace.go:171","msg":"trace[1953984501] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"347.723006ms","start":"2026-10-07T22:20:35.373908Z","end":"2026-10-07T22:20:35.721631Z","steps":["trace[1953984501] 'process raft request' (duration: 271.371957ms)","trace[1953984501] 'compare' (duration: 76.225006ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:20:35.721806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:20:35.373856Z","time spent":"347.874791ms","remote":"127.0.0.1:42548","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-10-07T22:20:36.354946Z","caller":"traceutil/trace.go:171","msg":"trace[2015496041] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"152.565374ms","start":"2026-10-07T22:20:36.20235Z","end":"2026-10-07T22:20:36.354915Z","steps":["trace[2015496041] 'process raft request' (duration: 117.524617ms)","trace[2015496041] 'compare' (duration: 34.825271ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:21:02.933044Z","caller":"traceutil/trace.go:171","msg":"trace[212900961] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"108.084825ms","start":"2026-10-07T22:21:02.824931Z","end":"2026-10-07T22:21:02.933016Z","steps":["trace[212900961] 'process raft request' (duration: 107.879779ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:04.570731Z","caller":"traceutil/trace.go:171","msg":"trace[502753974] linearizableReadLoop","detail":"{readStateIndex:9056; appliedIndex:9055; }","duration":"247.666327ms","start":"2026-10-07T22:21:04.323042Z","end":"2026-10-07T22:21:04.570708Z","steps":["trace[502753974] 'read index received' (duration: 247.590644ms)","trace[502753974] 'applied index is now lower than readState.Index' (duration: 74.373µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:21:04.570904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.842411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-07T22:21:04.570929Z","caller":"traceutil/trace.go:171","msg":"trace[939485454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8746; }","duration":"247.906684ms","start":"2026-10-07T22:21:04.323015Z","end":"2026-10-07T22:21:04.570922Z","steps":["trace[939485454] 'agreement among raft nodes before linearized reading' (duration: 247.7859ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:04.570888Z","caller":"traceutil/trace.go:171","msg":"trace[856865614] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"250.163117ms","start":"2026-10-07T22:21:04.320693Z","end":"2026-10-07T22:21:04.570856Z","steps":["trace[856865614] 'process raft request' (duration: 249.891359ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:04.767378Z","caller":"traceutil/trace.go:171","msg":"trace[765304452] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"335.017803ms","start":"2026-10-07T22:21:04.432312Z","end":"2026-10-07T22:21:04.76733Z","steps":["trace[765304452] 'process raft request' (duration: 260.951627ms)","trace[765304452] 'compare' (duration: 73.86358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:21:04.767513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:21:04.432288Z","time spent":"335.156987ms","remote":"127.0.0.1:42548","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-10-07T22:21:04.767655Z","caller":"traceutil/trace.go:171","msg":"trace[2079054455] linearizableReadLoop","detail":"{readStateIndex:9058; appliedIndex:9056; }","duration":"196.844349ms","start":"2026-10-07T22:21:04.570785Z","end":"2026-10-07T22:21:04.767629Z","steps":["trace[2079054455] 'read index received' (duration: 116.760116ms)","trace[2079054455] 'applied index is now lower than readState.Index' (duration: 80.083423ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:21:04.767818Z","caller":"traceutil/trace.go:171","msg":"trace[2003672067] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"323.860831ms","start":"2026-10-07T22:21:04.443944Z","end":"2026-10-07T22:21:04.767805Z","steps":["trace[2003672067] 'process raft request' (duration: 323.336806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:21:04.767918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:21:04.443891Z","time spent":"323.952574ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:21:04.768004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.764291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-07T22:21:04.768045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.01494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T22:21:04.76807Z","caller":"traceutil/trace.go:171","msg":"trace[170099204] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"190.157203ms","start":"2026-10-07T22:21:04.577902Z","end":"2026-10-07T22:21:04.768059Z","steps":["trace[170099204] 'process raft request' (duration: 189.653579ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:04.768079Z","caller":"traceutil/trace.go:171","msg":"trace[1362718081] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8749; }","duration":"284.080122ms","start":"2026-10-07T22:21:04.483988Z","end":"2026-10-07T22:21:04.768068Z","steps":["trace[1362718081] 'agreement among raft nodes before linearized reading' (duration: 283.882217ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:04.76808Z","caller":"traceutil/trace.go:171","msg":"trace[990202136] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8749; }","duration":"388.903005ms","start":"2026-10-07T22:21:04.379159Z","end":"2026-10-07T22:21:04.768062Z","steps":["trace[990202136] 'agreement among raft nodes before linearized reading' (duration: 388.72585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:21:04.768126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:21:04.379138Z","time spent":"388.977798ms","remote":"127.0.0.1:50266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-07T22:21:04.768029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.12276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lfrw2\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-07T22:21:04.768372Z","caller":"traceutil/trace.go:171","msg":"trace[1549075053] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lfrw2; range_end:; response_count:1; response_revision:8749; }","duration":"186.448529ms","start":"2026-10-07T22:21:04.581869Z","end":"2026-10-07T22:21:04.768318Z","steps":["trace[1549075053] 'agreement among raft nodes before linearized reading' (duration: 186.054468ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:15.159225Z","caller":"traceutil/trace.go:171","msg":"trace[350813302] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"175.108013ms","start":"2026-10-07T22:21:14.984029Z","end":"2026-10-07T22:21:15.159137Z","steps":["trace[350813302] 'process raft request' (duration: 154.755145ms)","trace[350813302] 'compare' (duration: 19.526285ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:21:15.16147Z","caller":"traceutil/trace.go:171","msg":"trace[1396497795] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"160.359932ms","start":"2026-10-07T22:21:15.001082Z","end":"2026-10-07T22:21:15.161442Z","steps":["trace[1396497795] 'process raft request' (duration: 160.115116ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:17.641265Z","caller":"traceutil/trace.go:171","msg":"trace[1451539653] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8827; }","duration":"150.423755ms","start":"2026-10-07T22:21:17.490813Z","end":"2026-10-07T22:21:17.641237Z","steps":["trace[1451539653] 'process raft request' (duration: 150.27098ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:17.692574Z","caller":"traceutil/trace.go:171","msg":"trace[308705622] transaction","detail":"{read_only:false; response_revision:8828; number_of_response:1; }","duration":"197.802166ms","start":"2026-10-07T22:21:17.494754Z","end":"2026-10-07T22:21:17.692556Z","steps":["trace[308705622] 'process raft request' (duration: 197.650192ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:21:33.87596Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002463.wal"} {"level":"info","ts":"2026-10-07T22:22:43.864939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a656331365382b34","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T22:22:43.871397Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-07T22:22:43.871614Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-07T22:22:44.377707Z","caller":"traceutil/trace.go:171","msg":"trace[1747130851] transaction","detail":"{read_only:false; response_revision:9663; number_of_response:1; }","duration":"107.965309ms","start":"2026-10-07T22:22:44.26971Z","end":"2026-10-07T22:22:44.377675Z","steps":["trace[1747130851] 'process raft request' (duration: 107.66029ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:24:40.38576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8067} {"level":"info","ts":"2026-10-07T22:24:40.458554Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8067,"took":"70.636089ms","hash":3822904294,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":28504064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-07T22:24:40.458628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3822904294,"revision":8067,"compact-revision":5885} {"level":"info","ts":"2026-10-07T22:29:40.395829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10480} {"level":"info","ts":"2026-10-07T22:29:40.493057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10480,"took":"93.639479ms","hash":1049965432,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":25763840,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-07T22:29:40.493141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1049965432,"revision":10480,"compact-revision":8067} {"level":"info","ts":"2026-10-07T22:34:27.169584Z","caller":"traceutil/trace.go:171","msg":"trace[1049369156] transaction","detail":"{read_only:false; response_revision:14558; number_of_response:1; }","duration":"116.784733ms","start":"2026-10-07T22:34:27.052772Z","end":"2026-10-07T22:34:27.169557Z","steps":["trace[1049369156] 'process raft request' (duration: 116.617408ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:34:40.409231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12607} {"level":"info","ts":"2026-10-07T22:34:40.579782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12607,"took":"167.710291ms","hash":2891317103,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":26816512,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-07T22:34:40.579903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2891317103,"revision":12607,"compact-revision":10480} {"level":"info","ts":"2026-10-07T22:34:48.177103Z","caller":"traceutil/trace.go:171","msg":"trace[416284585] transaction","detail":"{read_only:false; response_revision:14671; number_of_response:1; }","duration":"154.703909ms","start":"2026-10-07T22:34:48.022367Z","end":"2026-10-07T22:34:48.177071Z","steps":["trace[416284585] 'process raft request' (duration: 154.490403ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:34:48.257516Z","caller":"traceutil/trace.go:171","msg":"trace[12872349] transaction","detail":"{read_only:false; response_revision:14672; number_of_response:1; }","duration":"221.740077ms","start":"2026-10-07T22:34:48.035733Z","end":"2026-10-07T22:34:48.257473Z","steps":["trace[12872349] 'process raft request' (duration: 219.303719ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:34:48.257512Z","caller":"traceutil/trace.go:171","msg":"trace[927600858] linearizableReadLoop","detail":"{readStateIndex:15180; appliedIndex:15178; }","duration":"209.6534ms","start":"2026-10-07T22:34:48.047784Z","end":"2026-10-07T22:34:48.257437Z","steps":["trace[927600858] 'read index received' (duration: 129.147967ms)","trace[927600858] 'applied index is now lower than readState.Index' (duration: 80.503113ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:34:48.25763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.808304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-07T22:34:48.257723Z","caller":"traceutil/trace.go:171","msg":"trace[1806513858] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:14672; }","duration":"209.905217ms","start":"2026-10-07T22:34:48.047777Z","end":"2026-10-07T22:34:48.257682Z","steps":["trace[1806513858] 'agreement among raft nodes before linearized reading' (duration: 209.761063ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:35:06.227165Z","caller":"traceutil/trace.go:171","msg":"trace[414484443] transaction","detail":"{read_only:false; response_revision:14779; number_of_response:1; }","duration":"268.925551ms","start":"2026-10-07T22:35:05.958212Z","end":"2026-10-07T22:35:06.227138Z","steps":["trace[414484443] 'process raft request' (duration: 268.659853ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:35:06.380751Z","caller":"traceutil/trace.go:171","msg":"trace[40073880] transaction","detail":"{read_only:false; response_revision:14780; number_of_response:1; }","duration":"135.298929ms","start":"2026-10-07T22:35:06.245415Z","end":"2026-10-07T22:35:06.380714Z","steps":["trace[40073880] 'process raft request' (duration: 121.649668ms)","trace[40073880] 'compare' (duration: 13.200008ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:35:30.264403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.025774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-07T22:35:30.264597Z","caller":"traceutil/trace.go:171","msg":"trace[464691799] transaction","detail":"{read_only:false; response_revision:15097; number_of_response:1; }","duration":"375.753096ms","start":"2026-10-07T22:35:29.888791Z","end":"2026-10-07T22:35:30.264544Z","steps":["trace[464691799] 'process raft request' (duration: 255.499096ms)","trace[464691799] 'compare' (duration: 119.848089ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:35:30.264777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:35:29.888702Z","time spent":"375.969913ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:36:55.281595Z","caller":"traceutil/trace.go:171","msg":"trace[1527563883] transaction","detail":"{read_only:false; response_revision:15907; number_of_response:1; }","duration":"114.075327ms","start":"2026-10-07T22:36:55.167489Z","end":"2026-10-07T22:36:55.281564Z","steps":["trace[1527563883] 'process raft request' (duration: 113.883641ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:36:55.655314Z","caller":"traceutil/trace.go:171","msg":"trace[546621639] linearizableReadLoop","detail":"{readStateIndex:16458; appliedIndex:16457; }","duration":"124.231239ms","start":"2026-10-07T22:36:55.531065Z","end":"2026-10-07T22:36:55.655296Z","steps":["trace[546621639] 'read index received' (duration: 124.048004ms)","trace[546621639] 'applied index is now lower than readState.Index' (duration: 182.485µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:36:55.65548Z","caller":"traceutil/trace.go:171","msg":"trace[265840474] transaction","detail":"{read_only:false; response_revision:15909; number_of_response:1; }","duration":"126.842612ms","start":"2026-10-07T22:36:55.528519Z","end":"2026-10-07T22:36:55.655362Z","steps":["trace[265840474] 'process raft request' (duration: 126.655167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:36:55.65614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.535737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737543"} {"level":"info","ts":"2026-10-07T22:36:55.656203Z","caller":"traceutil/trace.go:171","msg":"trace[1281727540] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15909; }","duration":"124.651511ms","start":"2026-10-07T22:36:55.53154Z","end":"2026-10-07T22:36:55.656192Z","steps":["trace[1281727540] 'agreement among raft nodes before linearized reading' (duration: 123.87803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:36:55.656276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.158945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737543"} {"level":"info","ts":"2026-10-07T22:36:55.656388Z","caller":"traceutil/trace.go:171","msg":"trace[698597624] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15909; }","duration":"125.308089ms","start":"2026-10-07T22:36:55.531059Z","end":"2026-10-07T22:36:55.656367Z","steps":["trace[698597624] 'agreement among raft nodes before linearized reading' (duration: 124.323752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:36:55.841979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.239095ms","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-10-07T22:36:55.842086Z","caller":"traceutil/trace.go:171","msg":"trace[1589886777] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15909; }","duration":"103.391779ms","start":"2026-10-07T22:36:55.738676Z","end":"2026-10-07T22:36:55.842068Z","steps":["trace[1589886777] 'range keys from in-memory index tree' (duration: 103.119481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:36:56.188125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.016959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-07T22:36:56.188216Z","caller":"traceutil/trace.go:171","msg":"trace[75398492] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15910; }","duration":"196.187043ms","start":"2026-10-07T22:36:55.992009Z","end":"2026-10-07T22:36:56.188196Z","steps":["trace[75398492] 'range keys from in-memory index tree' (duration: 195.813533ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:39:40.416916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14635} {"level":"info","ts":"2026-10-07T22:39:40.508417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14635,"took":"88.901196ms","hash":615499798,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T22:39:40.508488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":615499798,"revision":14635,"compact-revision":12607} {"level":"info","ts":"2026-10-07T22:42:40.413355Z","caller":"traceutil/trace.go:171","msg":"trace[456919849] transaction","detail":"{read_only:false; response_revision:18454; number_of_response:1; }","duration":"171.500684ms","start":"2026-10-07T22:42:40.241824Z","end":"2026-10-07T22:42:40.413324Z","steps":["trace[456919849] 'process raft request' (duration: 122.892311ms)","trace[456919849] 'compare' (duration: 48.458229ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:42:47.527138Z","caller":"traceutil/trace.go:171","msg":"trace[968554062] transaction","detail":"{read_only:false; response_revision:18507; number_of_response:1; }","duration":"318.20945ms","start":"2026-10-07T22:42:47.208899Z","end":"2026-10-07T22:42:47.527108Z","steps":["trace[968554062] 'process raft request' (duration: 305.415654ms)","trace[968554062] 'compare' (duration: 12.406175ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:42:47.527302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:42:47.208869Z","time spent":"318.332272ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:42:47.527414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.413299ms","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:6"} {"level":"info","ts":"2026-10-07T22:42:47.527448Z","caller":"traceutil/trace.go:171","msg":"trace[1161835117] linearizableReadLoop","detail":"{readStateIndex:19141; appliedIndex:19140; }","duration":"301.991188ms","start":"2026-10-07T22:42:47.224981Z","end":"2026-10-07T22:42:47.526972Z","steps":["trace[1161835117] 'read index received' (duration: 289.343046ms)","trace[1161835117] 'applied index is now lower than readState.Index' (duration: 12.646792ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:42:47.527503Z","caller":"traceutil/trace.go:171","msg":"trace[368543572] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:18507; }","duration":"302.551753ms","start":"2026-10-07T22:42:47.224929Z","end":"2026-10-07T22:42:47.527481Z","steps":["trace[368543572] 'agreement among raft nodes before linearized reading' (duration: 302.390099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:42:47.527543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:42:47.224909Z","time spent":"302.624036ms","remote":"127.0.0.1:48228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-10-07T22:42:47.877219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.278353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-07T22:42:47.877322Z","caller":"traceutil/trace.go:171","msg":"trace[1212379455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18507; }","duration":"183.426417ms","start":"2026-10-07T22:42:47.693876Z","end":"2026-10-07T22:42:47.877303Z","steps":["trace[1212379455] 'range keys from in-memory index tree' (duration: 183.097638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:42:47.877892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.363504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:42:47.877949Z","caller":"traceutil/trace.go:171","msg":"trace[874179574] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:18507; }","duration":"151.487737ms","start":"2026-10-07T22:42:47.726446Z","end":"2026-10-07T22:42:47.877934Z","steps":["trace[874179574] 'count revisions from in-memory index tree' (duration: 151.195139ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:42:47.993295Z","caller":"traceutil/trace.go:171","msg":"trace[13121535] transaction","detail":"{read_only:false; response_revision:18508; number_of_response:1; }","duration":"110.596979ms","start":"2026-10-07T22:42:47.882681Z","end":"2026-10-07T22:42:47.993278Z","steps":["trace[13121535] 'process raft request' (duration: 110.436354ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:42:55.661701Z","caller":"traceutil/trace.go:171","msg":"trace[1087969893] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18633; }","duration":"151.325933ms","start":"2026-10-07T22:42:55.510342Z","end":"2026-10-07T22:42:55.661667Z","steps":["trace[1087969893] 'process raft request' (duration: 151.111017ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:42:55.661883Z","caller":"traceutil/trace.go:171","msg":"trace[1113176484] transaction","detail":"{read_only:false; response_revision:18634; number_of_response:1; }","duration":"146.483349ms","start":"2026-10-07T22:42:55.515346Z","end":"2026-10-07T22:42:55.661829Z","steps":["trace[1113176484] 'process raft request' (duration: 146.232932ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:42:55.661951Z","caller":"traceutil/trace.go:171","msg":"trace[1186334709] linearizableReadLoop","detail":"{readStateIndex:19272; appliedIndex:19269; }","duration":"129.985289ms","start":"2026-10-07T22:42:55.531937Z","end":"2026-10-07T22:42:55.661922Z","steps":["trace[1186334709] 'read index received' (duration: 129.445134ms)","trace[1186334709] 'applied index is now lower than readState.Index' (duration: 538.265µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:42:55.662138Z","caller":"traceutil/trace.go:171","msg":"trace[1722732955] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18634; }","duration":"144.689749ms","start":"2026-10-07T22:42:55.517282Z","end":"2026-10-07T22:42:55.661972Z","steps":["trace[1722732955] 'process raft request' (duration: 144.509994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:42:55.662211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.248887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cbfeab6-8473-467b-b3dd-2e8961b88a18\" ","response":"range_response_count:1 size:3933"} {"level":"info","ts":"2026-10-07T22:42:55.662277Z","caller":"traceutil/trace.go:171","msg":"trace[1156149930] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cbfeab6-8473-467b-b3dd-2e8961b88a18; range_end:; response_count:1; response_revision:18634; }","duration":"130.336629ms","start":"2026-10-07T22:42:55.531901Z","end":"2026-10-07T22:42:55.662238Z","steps":["trace[1156149930] 'agreement among raft nodes before linearized reading' (duration: 130.128933ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:44:01.583028Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a656331365382b34","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T22:44:01.591105Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-07T22:44:01.591205Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-07T22:44:40.425469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17095} {"level":"info","ts":"2026-10-07T22:44:40.536033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17095,"took":"107.353259ms","hash":1666924248,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":33816576,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-07T22:44:40.536118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1666924248,"revision":17095,"compact-revision":14635} {"level":"info","ts":"2026-10-07T22:48:34.066001Z","caller":"traceutil/trace.go:171","msg":"trace[257936037] transaction","detail":"{read_only:false; response_revision:21197; number_of_response:1; }","duration":"137.412776ms","start":"2026-10-07T22:48:33.92856Z","end":"2026-10-07T22:48:34.065972Z","steps":["trace[257936037] 'process raft request' (duration: 137.263891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:48:39.630013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.266983ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-07T22:48:39.630633Z","caller":"traceutil/trace.go:171","msg":"trace[1780190445] transaction","detail":"{read_only:false; response_revision:21225; number_of_response:1; }","duration":"236.010901ms","start":"2026-10-07T22:48:39.394593Z","end":"2026-10-07T22:48:39.630604Z","steps":["trace[1780190445] 'process raft request' (duration: 235.523367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:48:39.845012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.005581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:48:39.845211Z","caller":"traceutil/trace.go:171","msg":"trace[49646219] linearizableReadLoop","detail":"{readStateIndex:21952; appliedIndex:21949; }","duration":"281.389744ms","start":"2026-10-07T22:48:39.563806Z","end":"2026-10-07T22:48:39.845196Z","steps":["trace[49646219] 'read index received' (duration: 110.049314ms)","trace[49646219] 'applied index is now lower than readState.Index' (duration: 171.33953ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:48:39.845305Z","caller":"traceutil/trace.go:171","msg":"trace[1284494291] transaction","detail":"{read_only:false; response_revision:21226; number_of_response:1; }","duration":"335.875181ms","start":"2026-10-07T22:48:39.509419Z","end":"2026-10-07T22:48:39.845294Z","steps":["trace[1284494291] 'process raft request' (duration: 164.50994ms)","trace[1284494291] 'compare' (duration: 170.865117ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:48:39.845375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:48:39.509384Z","time spent":"335.954133ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:48:39.84539Z","caller":"traceutil/trace.go:171","msg":"trace[688055234] transaction","detail":"{read_only:false; response_revision:21228; number_of_response:1; }","duration":"332.869368ms","start":"2026-10-07T22:48:39.512483Z","end":"2026-10-07T22:48:39.845352Z","steps":["trace[688055234] 'process raft request' (duration: 332.674452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:48:39.84554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:48:39.512467Z","time spent":"332.991801ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:48:39.84555Z","caller":"traceutil/trace.go:171","msg":"trace[1007534833] transaction","detail":"{read_only:false; response_revision:21227; number_of_response:1; }","duration":"335.512341ms","start":"2026-10-07T22:48:39.509955Z","end":"2026-10-07T22:48:39.845467Z","steps":["trace[1007534833] 'process raft request' (duration: 335.149251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:48:39.845627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.360526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-07T22:48:39.845731Z","caller":"traceutil/trace.go:171","msg":"trace[1132524071] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:21228; }","duration":"280.49941ms","start":"2026-10-07T22:48:39.565209Z","end":"2026-10-07T22:48:39.845708Z","steps":["trace[1132524071] 'agreement among raft nodes before linearized reading' (duration: 280.285974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:48:39.845738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:48:39.50992Z","time spent":"335.700796ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:48:39.845769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.96962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:48:39.84581Z","caller":"traceutil/trace.go:171","msg":"trace[1634903010] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:21228; }","duration":"282.052053ms","start":"2026-10-07T22:48:39.563746Z","end":"2026-10-07T22:48:39.845798Z","steps":["trace[1634903010] 'agreement among raft nodes before linearized reading' (duration: 281.983301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:48:39.845861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.283146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-07T22:48:39.845938Z","caller":"traceutil/trace.go:171","msg":"trace[242104144] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:21228; }","duration":"251.412679ms","start":"2026-10-07T22:48:39.594503Z","end":"2026-10-07T22:48:39.845916Z","steps":["trace[242104144] 'agreement among raft nodes before linearized reading' (duration: 251.174933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:48:40.097228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.168966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:48:40.097405Z","caller":"traceutil/trace.go:171","msg":"trace[177870751] transaction","detail":"{read_only:false; response_revision:21229; number_of_response:1; }","duration":"247.333185ms","start":"2026-10-07T22:48:39.850052Z","end":"2026-10-07T22:48:40.097385Z","steps":["trace[177870751] 'process raft request' (duration: 103.926142ms)","trace[177870751] 'compare' (duration: 142.980511ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:48:40.0985Z","caller":"traceutil/trace.go:171","msg":"trace[1906225139] transaction","detail":"{read_only:false; response_revision:21230; number_of_response:1; }","duration":"248.107637ms","start":"2026-10-07T22:48:39.850324Z","end":"2026-10-07T22:48:40.098432Z","steps":["trace[1906225139] 'process raft request' (duration: 247.927962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:48:40.41242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.148846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:48:40.412521Z","caller":"traceutil/trace.go:171","msg":"trace[1342493442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21231; }","duration":"189.30037ms","start":"2026-10-07T22:48:40.223198Z","end":"2026-10-07T22:48:40.412499Z","steps":["trace[1342493442] 'range keys from in-memory index tree' (duration: 188.884449ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:49:40.433403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19589} {"level":"info","ts":"2026-10-07T22:49:40.55194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19589,"took":"115.136525ms","hash":1364207733,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":29634560,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T22:49:40.552007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1364207733,"revision":19589,"compact-revision":17095} {"level":"info","ts":"2026-10-07T22:49:49.657736Z","caller":"traceutil/trace.go:171","msg":"trace[1140529501] transaction","detail":"{read_only:false; response_revision:21861; number_of_response:1; }","duration":"105.018605ms","start":"2026-10-07T22:49:49.552684Z","end":"2026-10-07T22:49:49.657703Z","steps":["trace[1140529501] 'process raft request' (duration: 95.560921ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:49:49.659216Z","caller":"traceutil/trace.go:171","msg":"trace[2027587493] transaction","detail":"{read_only:false; response_revision:21862; number_of_response:1; }","duration":"106.115356ms","start":"2026-10-07T22:49:49.553076Z","end":"2026-10-07T22:49:49.659191Z","steps":["trace[2027587493] 'process raft request' (duration: 106.013803ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:52:43.394095Z","caller":"traceutil/trace.go:171","msg":"trace[1971847044] linearizableReadLoop","detail":"{readStateIndex:24547; appliedIndex:24546; }","duration":"170.339203ms","start":"2026-10-07T22:52:43.223706Z","end":"2026-10-07T22:52:43.394046Z","steps":["trace[1971847044] 'read index received' (duration: 170.153378ms)","trace[1971847044] 'applied index is now lower than readState.Index' (duration: 184.935µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:52:43.394377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.640532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:52:43.394596Z","caller":"traceutil/trace.go:171","msg":"trace[270909422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23752; }","duration":"170.93173ms","start":"2026-10-07T22:52:43.223636Z","end":"2026-10-07T22:52:43.394567Z","steps":["trace[270909422] 'agreement among raft nodes before linearized reading' (duration: 170.605051ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:52:43.394838Z","caller":"traceutil/trace.go:171","msg":"trace[192515223] transaction","detail":"{read_only:false; response_revision:23752; number_of_response:1; }","duration":"229.152881ms","start":"2026-10-07T22:52:43.165618Z","end":"2026-10-07T22:52:43.39477Z","steps":["trace[192515223] 'process raft request' (duration: 228.246436ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:54:40.440264Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21817} {"level":"info","ts":"2026-10-07T22:54:40.555574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21817,"took":"111.65634ms","hash":2244811870,"current-db-size-bytes":55607296,"current-db-size":"56 MB","current-db-size-in-use-bytes":43851776,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-07T22:54:40.55565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2244811870,"revision":21817,"compact-revision":19589} {"level":"info","ts":"2026-10-07T22:55:17.356322Z","caller":"traceutil/trace.go:171","msg":"trace[1893414031] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"112.596216ms","start":"2026-10-07T22:55:17.243695Z","end":"2026-10-07T22:55:17.356291Z","steps":["trace[1893414031] 'process raft request' (duration: 112.39427ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:15.68Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000683e.wal"} {"level":"info","ts":"2026-10-07T22:56:31.907657Z","caller":"traceutil/trace.go:171","msg":"trace[316075746] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"141.050559ms","start":"2026-10-07T22:56:31.766582Z","end":"2026-10-07T22:56:31.907632Z","steps":["trace[316075746] 'process raft request' (duration: 140.898955ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:32.209721Z","caller":"traceutil/trace.go:171","msg":"trace[91365050] transaction","detail":"{read_only:false; response_revision:26010; number_of_response:1; }","duration":"442.880706ms","start":"2026-10-07T22:56:31.766793Z","end":"2026-10-07T22:56:32.209674Z","steps":["trace[91365050] 'process raft request' (duration: 442.67993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:32.209878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:31.766783Z","time spent":"443.014469ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:32.210051Z","caller":"traceutil/trace.go:171","msg":"trace[357201336] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"397.367888ms","start":"2026-10-07T22:56:31.812643Z","end":"2026-10-07T22:56:32.210011Z","steps":["trace[357201336] 'process raft request' (duration: 396.989748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:32.210222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:31.811961Z","time spent":"398.177831ms","remote":"127.0.0.1:42548","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-10-07T22:56:32.210212Z","caller":"traceutil/trace.go:171","msg":"trace[1995437780] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"394.185979ms","start":"2026-10-07T22:56:31.815994Z","end":"2026-10-07T22:56:32.210179Z","steps":["trace[1995437780] 'process raft request' (duration: 393.890041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:32.210384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:31.815968Z","time spent":"394.337674ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:32.210381Z","caller":"traceutil/trace.go:171","msg":"trace[1703403026] linearizableReadLoop","detail":"{readStateIndex:26869; appliedIndex:26865; }","duration":"213.907138ms","start":"2026-10-07T22:56:31.996461Z","end":"2026-10-07T22:56:32.210368Z","steps":["trace[1703403026] 'read index received' (duration: 212.985823ms)","trace[1703403026] 'applied index is now lower than readState.Index' (duration: 920.355µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:32.210555Z","caller":"traceutil/trace.go:171","msg":"trace[1735121270] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"367.2951ms","start":"2026-10-07T22:56:31.843246Z","end":"2026-10-07T22:56:32.210541Z","steps":["trace[1735121270] 'process raft request' (duration: 366.849648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:32.210607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:31.843223Z","time spent":"367.355672ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:32.210687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.232237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-07T22:56:32.210747Z","caller":"traceutil/trace.go:171","msg":"trace[1550179416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26013; }","duration":"214.32999ms","start":"2026-10-07T22:56:31.996407Z","end":"2026-10-07T22:56:32.210737Z","steps":["trace[1550179416] 'agreement among raft nodes before linearized reading' (duration: 214.152735ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:32.285509Z","caller":"traceutil/trace.go:171","msg":"trace[1145017373] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"123.474819ms","start":"2026-10-07T22:56:32.162Z","end":"2026-10-07T22:56:32.285475Z","steps":["trace[1145017373] 'process raft request' (duration: 121.807843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:32.285946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.304787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-07T22:56:32.286105Z","caller":"traceutil/trace.go:171","msg":"trace[1615216646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26015; }","duration":"257.498302ms","start":"2026-10-07T22:56:32.02855Z","end":"2026-10-07T22:56:32.286049Z","steps":["trace[1615216646] 'agreement among raft nodes before linearized reading' (duration: 257.105261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:32.286193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.100619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-07T22:56:32.286339Z","caller":"traceutil/trace.go:171","msg":"trace[323049952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26015; }","duration":"239.282234ms","start":"2026-10-07T22:56:32.047029Z","end":"2026-10-07T22:56:32.286312Z","steps":["trace[323049952] 'agreement among raft nodes before linearized reading' (duration: 238.567045ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:32.444755Z","caller":"traceutil/trace.go:171","msg":"trace[1108617869] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"154.287776ms","start":"2026-10-07T22:56:32.29044Z","end":"2026-10-07T22:56:32.444728Z","steps":["trace[1108617869] 'process raft request' (duration: 154.115041ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:32.458178Z","caller":"traceutil/trace.go:171","msg":"trace[954001723] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"167.493684ms","start":"2026-10-07T22:56:32.290609Z","end":"2026-10-07T22:56:32.458103Z","steps":["trace[954001723] 'process raft request' (duration: 167.050282ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:32.777386Z","caller":"traceutil/trace.go:171","msg":"trace[911363285] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26018; }","duration":"100.003216ms","start":"2026-10-07T22:56:32.677348Z","end":"2026-10-07T22:56:32.777352Z","steps":["trace[911363285] 'count revisions from in-memory index tree' (duration: 99.870232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:32.777996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.802341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-10-07T22:56:32.778032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.106362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-07T22:56:32.778101Z","caller":"traceutil/trace.go:171","msg":"trace[1664127455] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26018; }","duration":"122.940175ms","start":"2026-10-07T22:56:32.655143Z","end":"2026-10-07T22:56:32.778083Z","steps":["trace[1664127455] 'range keys from in-memory index tree' (duration: 122.658557ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:32.778115Z","caller":"traceutil/trace.go:171","msg":"trace[290192383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26018; }","duration":"159.224725ms","start":"2026-10-07T22:56:32.618868Z","end":"2026-10-07T22:56:32.778093Z","steps":["trace[290192383] 'range keys from in-memory index tree' (duration: 158.953098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:33.126712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.726261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:56:33.126873Z","caller":"traceutil/trace.go:171","msg":"trace[1642675831] linearizableReadLoop","detail":"{readStateIndex:26877; appliedIndex:26875; }","duration":"257.80174ms","start":"2026-10-07T22:56:32.869055Z","end":"2026-10-07T22:56:33.126857Z","steps":["trace[1642675831] 'read index received' (duration: 103.733959ms)","trace[1642675831] 'applied index is now lower than readState.Index' (duration: 154.066911ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:56:33.126957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.907873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:33.126947Z","caller":"traceutil/trace.go:171","msg":"trace[1181890658] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"277.392596ms","start":"2026-10-07T22:56:32.849519Z","end":"2026-10-07T22:56:33.126912Z","steps":["trace[1181890658] 'process raft request' (duration: 123.350096ms)","trace[1181890658] 'compare' (duration: 153.569927ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:33.126982Z","caller":"traceutil/trace.go:171","msg":"trace[1856476915] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26021; }","duration":"257.975365ms","start":"2026-10-07T22:56:32.868996Z","end":"2026-10-07T22:56:33.126972Z","steps":["trace[1856476915] 'agreement among raft nodes before linearized reading' (duration: 257.903033ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:33.127013Z","caller":"traceutil/trace.go:171","msg":"trace[85550637] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"275.655328ms","start":"2026-10-07T22:56:32.85135Z","end":"2026-10-07T22:56:33.127005Z","steps":["trace[85550637] 'process raft request' (duration: 275.458183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:33.494049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.577423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:33.494377Z","caller":"traceutil/trace.go:171","msg":"trace[1729862760] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:26021; }","duration":"132.984464ms","start":"2026-10-07T22:56:33.361365Z","end":"2026-10-07T22:56:33.494349Z","steps":["trace[1729862760] 'count revisions from in-memory index tree' (duration: 132.402418ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:34.684005Z","caller":"traceutil/trace.go:171","msg":"trace[1315205236] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"466.512234ms","start":"2026-10-07T22:56:34.217469Z","end":"2026-10-07T22:56:34.683982Z","steps":["trace[1315205236] 'process raft request' (duration: 466.422081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.684214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:34.217456Z","time spent":"466.685889ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:34.684386Z","caller":"traceutil/trace.go:171","msg":"trace[474496780] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"465.974179ms","start":"2026-10-07T22:56:34.218375Z","end":"2026-10-07T22:56:34.684349Z","steps":["trace[474496780] 'process raft request' (duration: 465.566997ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:34.684517Z","caller":"traceutil/trace.go:171","msg":"trace[911189987] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"468.655873ms","start":"2026-10-07T22:56:34.215791Z","end":"2026-10-07T22:56:34.684447Z","steps":["trace[911189987] 'process raft request' (duration: 396.519754ms)","trace[911189987] 'compare' (duration: 71.324387ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:56:34.68458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:34.218363Z","time spent":"466.115212ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:34.684615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:34.215763Z","time spent":"468.811388ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":532,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:34.684938Z","caller":"traceutil/trace.go:171","msg":"trace[1152845741] linearizableReadLoop","detail":"{readStateIndex:26884; appliedIndex:26880; }","duration":"462.80647ms","start":"2026-10-07T22:56:34.22212Z","end":"2026-10-07T22:56:34.684926Z","steps":["trace[1152845741] 'read index received' (duration: 76.897631ms)","trace[1152845741] 'applied index is now lower than readState.Index' (duration: 385.907559ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:34.68518Z","caller":"traceutil/trace.go:171","msg":"trace[326092362] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"393.592852ms","start":"2026-10-07T22:56:34.29157Z","end":"2026-10-07T22:56:34.685163Z","steps":["trace[326092362] 'process raft request' (duration: 392.673097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.685329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:34.291557Z","time spent":"393.717916ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:34.685617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.48962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:56:34.686144Z","caller":"traceutil/trace.go:171","msg":"trace[1604113505] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:26028; }","duration":"464.038564ms","start":"2026-10-07T22:56:34.222087Z","end":"2026-10-07T22:56:34.686125Z","steps":["trace[1604113505] 'agreement among raft nodes before linearized reading' (duration: 463.478269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.686266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:34.222076Z","time spent":"464.167708ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":14,"response size":32,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-10-07T22:56:34.709513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.102357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:34.709631Z","caller":"traceutil/trace.go:171","msg":"trace[74294093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26029; }","duration":"487.21988ms","start":"2026-10-07T22:56:34.222394Z","end":"2026-10-07T22:56:34.709614Z","steps":["trace[74294093] 'agreement among raft nodes before linearized reading' (duration: 487.060146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.709716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:34.222362Z","time spent":"487.318653ms","remote":"127.0.0.1:42298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-07T22:56:34.710047Z","caller":"traceutil/trace.go:171","msg":"trace[264046845] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"240.879549ms","start":"2026-10-07T22:56:34.469154Z","end":"2026-10-07T22:56:34.710034Z","steps":["trace[264046845] 'process raft request' (duration: 240.17522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.710341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.850586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-07T22:56:34.710494Z","caller":"traceutil/trace.go:171","msg":"trace[1089425612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26029; }","duration":"242.010291ms","start":"2026-10-07T22:56:34.468438Z","end":"2026-10-07T22:56:34.710449Z","steps":["trace[1089425612] 'agreement among raft nodes before linearized reading' (duration: 241.779674ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.710714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.149994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-07T22:56:34.710779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.439464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:34.710828Z","caller":"traceutil/trace.go:171","msg":"trace[1800573744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26029; }","duration":"419.345089ms","start":"2026-10-07T22:56:34.291456Z","end":"2026-10-07T22:56:34.710801Z","steps":["trace[1800573744] 'agreement among raft nodes before linearized reading' (duration: 418.984039ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:34.710859Z","caller":"traceutil/trace.go:171","msg":"trace[1524931078] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26029; }","duration":"316.576667ms","start":"2026-10-07T22:56:34.394261Z","end":"2026-10-07T22:56:34.710838Z","steps":["trace[1524931078] 'agreement among raft nodes before linearized reading' (duration: 316.397092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.710893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:34.291434Z","time spent":"419.446423ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-07T22:56:34.710906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:34.39424Z","time spent":"316.656109ms","remote":"127.0.0.1:36636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-10-07T22:56:34.710936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.965656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-07T22:56:34.711016Z","caller":"traceutil/trace.go:171","msg":"trace[1783154649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26029; }","duration":"262.167262ms","start":"2026-10-07T22:56:34.448832Z","end":"2026-10-07T22:56:34.710999Z","steps":["trace[1783154649] 'agreement among raft nodes before linearized reading' (duration: 261.770171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.989552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.452447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:56:34.989805Z","caller":"traceutil/trace.go:171","msg":"trace[489626543] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"266.846773ms","start":"2026-10-07T22:56:34.722921Z","end":"2026-10-07T22:56:34.989768Z","steps":["trace[489626543] 'process raft request' (duration: 119.099317ms)","trace[489626543] 'compare' (duration: 147.262162ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:34.98985Z","caller":"traceutil/trace.go:171","msg":"trace[1975524836] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"266.76815ms","start":"2026-10-07T22:56:34.723061Z","end":"2026-10-07T22:56:34.989829Z","steps":["trace[1975524836] 'process raft request' (duration: 266.597946ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:34.990011Z","caller":"traceutil/trace.go:171","msg":"trace[905919238] linearizableReadLoop","detail":"{readStateIndex:26889; appliedIndex:26886; }","duration":"185.005843ms","start":"2026-10-07T22:56:34.804982Z","end":"2026-10-07T22:56:34.989988Z","steps":["trace[905919238] 'read index received' (duration: 37.051002ms)","trace[905919238] 'applied index is now lower than readState.Index' (duration: 147.953841ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:34.990191Z","caller":"traceutil/trace.go:171","msg":"trace[961506578] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"265.656139ms","start":"2026-10-07T22:56:34.724524Z","end":"2026-10-07T22:56:34.99018Z","steps":["trace[961506578] 'process raft request' (duration: 265.267398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.990329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.053593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:34.990381Z","caller":"traceutil/trace.go:171","msg":"trace[2142330541] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26032; }","duration":"139.152426ms","start":"2026-10-07T22:56:34.851214Z","end":"2026-10-07T22:56:34.990367Z","steps":["trace[2142330541] 'agreement among raft nodes before linearized reading' (duration: 139.050803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:34.990427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.376103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-07T22:56:34.990528Z","caller":"traceutil/trace.go:171","msg":"trace[1759394327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26032; }","duration":"185.553988ms","start":"2026-10-07T22:56:34.804961Z","end":"2026-10-07T22:56:34.990515Z","steps":["trace[1759394327] 'agreement among raft nodes before linearized reading' (duration: 185.231659ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:35.175433Z","caller":"traceutil/trace.go:171","msg":"trace[769865783] linearizableReadLoop","detail":"{readStateIndex:26890; appliedIndex:26889; }","duration":"179.192771ms","start":"2026-10-07T22:56:34.996218Z","end":"2026-10-07T22:56:35.175411Z","steps":["trace[769865783] 'read index received' (duration: 166.871508ms)","trace[769865783] 'applied index is now lower than readState.Index' (duration: 12.320433ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:35.175518Z","caller":"traceutil/trace.go:171","msg":"trace[594874526] transaction","detail":"{read_only:false; response_revision:26033; number_of_response:1; }","duration":"180.189188ms","start":"2026-10-07T22:56:34.995298Z","end":"2026-10-07T22:56:35.175487Z","steps":["trace[594874526] 'process raft request' (duration: 167.67253ms)","trace[594874526] 'compare' (duration: 12.080076ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:56:35.175622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.389857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:35.175668Z","caller":"traceutil/trace.go:171","msg":"trace[1674214912] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26034; }","duration":"179.476079ms","start":"2026-10-07T22:56:34.996179Z","end":"2026-10-07T22:56:35.175655Z","steps":["trace[1674214912] 'agreement among raft nodes before linearized reading' (duration: 179.356536ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:36.052085Z","caller":"traceutil/trace.go:171","msg":"trace[1377641010] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"111.480915ms","start":"2026-10-07T22:56:35.940576Z","end":"2026-10-07T22:56:36.052056Z","steps":["trace[1377641010] 'process raft request' (duration: 111.27973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:36.528175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.066085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:56:36.528258Z","caller":"traceutil/trace.go:171","msg":"trace[285310991] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:26039; }","duration":"143.195529ms","start":"2026-10-07T22:56:36.385044Z","end":"2026-10-07T22:56:36.52824Z","steps":["trace[285310991] 'count revisions from in-memory index tree' (duration: 142.915481ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:36.801618Z","caller":"traceutil/trace.go:171","msg":"trace[1844331865] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"110.716154ms","start":"2026-10-07T22:56:36.690876Z","end":"2026-10-07T22:56:36.801592Z","steps":["trace[1844331865] 'process raft request' (duration: 110.55974ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:37.082901Z","caller":"traceutil/trace.go:171","msg":"trace[2115098176] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"391.975648ms","start":"2026-10-07T22:56:36.690901Z","end":"2026-10-07T22:56:37.082877Z","steps":["trace[2115098176] 'process raft request' (duration: 391.626238ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:37.082935Z","caller":"traceutil/trace.go:171","msg":"trace[896743046] linearizableReadLoop","detail":"{readStateIndex:26901; appliedIndex:26896; }","duration":"326.254257ms","start":"2026-10-07T22:56:36.75666Z","end":"2026-10-07T22:56:37.082914Z","steps":["trace[896743046] 'read index received' (duration: 44.777387ms)","trace[896743046] 'applied index is now lower than readState.Index' (duration: 281.47586ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:37.083059Z","caller":"traceutil/trace.go:171","msg":"trace[207162582] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"366.972262ms","start":"2026-10-07T22:56:36.716077Z","end":"2026-10-07T22:56:37.08305Z","steps":["trace[207162582] 'process raft request' (duration: 366.797307ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:37.083091Z","caller":"traceutil/trace.go:171","msg":"trace[34687668] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"391.263848ms","start":"2026-10-07T22:56:36.691788Z","end":"2026-10-07T22:56:37.083052Z","steps":["trace[34687668] 'process raft request' (duration: 391.038101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:37.083162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:36.716059Z","time spent":"367.031393ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:37.08323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:36.691752Z","time spent":"391.399731ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:37.083255Z","caller":"traceutil/trace.go:171","msg":"trace[1933409957] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"391.568526ms","start":"2026-10-07T22:56:36.691669Z","end":"2026-10-07T22:56:37.083238Z","steps":["trace[1933409957] 'process raft request' (duration: 391.100793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:37.083339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:36.691648Z","time spent":"391.644858ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:37.083383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.71808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:37.083415Z","caller":"traceutil/trace.go:171","msg":"trace[290815354] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:26044; }","duration":"326.788323ms","start":"2026-10-07T22:56:36.756617Z","end":"2026-10-07T22:56:37.083405Z","steps":["trace[290815354] 'agreement among raft nodes before linearized reading' (duration: 326.722901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:37.083445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:36.690874Z","time spent":"392.085061ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:37.083452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:36.756599Z","time spent":"326.841113ms","remote":"127.0.0.1:48364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-07T22:56:37.118077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.763978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:37.118168Z","caller":"traceutil/trace.go:171","msg":"trace[1386399961] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:26046; }","duration":"295.896622ms","start":"2026-10-07T22:56:36.82225Z","end":"2026-10-07T22:56:37.118146Z","steps":["trace[1386399961] 'agreement among raft nodes before linearized reading' (duration: 295.734238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:37.118375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.564962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-07T22:56:37.118376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.461579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-07T22:56:37.11842Z","caller":"traceutil/trace.go:171","msg":"trace[1394979391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26046; }","duration":"123.645585ms","start":"2026-10-07T22:56:36.994762Z","end":"2026-10-07T22:56:37.118407Z","steps":["trace[1394979391] 'agreement among raft nodes before linearized reading' (duration: 123.47434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:37.118511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.020673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:56:37.118535Z","caller":"traceutil/trace.go:171","msg":"trace[1502862333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26046; }","duration":"123.597402ms","start":"2026-10-07T22:56:36.994844Z","end":"2026-10-07T22:56:37.118441Z","steps":["trace[1502862333] 'agreement among raft nodes before linearized reading' (duration: 123.374356ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:37.118565Z","caller":"traceutil/trace.go:171","msg":"trace[1944571568] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:26046; }","duration":"269.116136ms","start":"2026-10-07T22:56:36.849435Z","end":"2026-10-07T22:56:37.118551Z","steps":["trace[1944571568] 'agreement among raft nodes before linearized reading' (duration: 268.999872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:37.118759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.85324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-07T22:56:37.118795Z","caller":"traceutil/trace.go:171","msg":"trace[675634319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26046; }","duration":"123.934382ms","start":"2026-10-07T22:56:36.994851Z","end":"2026-10-07T22:56:37.118786Z","steps":["trace[675634319] 'agreement among raft nodes before linearized reading' (duration: 123.807159ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:37.118954Z","caller":"traceutil/trace.go:171","msg":"trace[251693414] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"230.700256ms","start":"2026-10-07T22:56:36.888237Z","end":"2026-10-07T22:56:37.118937Z","steps":["trace[251693414] 'process raft request' (duration: 229.553264ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:37.225942Z","caller":"traceutil/trace.go:171","msg":"trace[743492477] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"102.892206ms","start":"2026-10-07T22:56:37.123025Z","end":"2026-10-07T22:56:37.225917Z","steps":["trace[743492477] 'process raft request' (duration: 33.443352ms)","trace[743492477] 'compare' (duration: 69.342981ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:37.230617Z","caller":"traceutil/trace.go:171","msg":"trace[1233925548] transaction","detail":"{read_only:false; response_revision:26049; number_of_response:1; }","duration":"106.958809ms","start":"2026-10-07T22:56:37.123632Z","end":"2026-10-07T22:56:37.230591Z","steps":["trace[1233925548] 'process raft request' (duration: 106.808375ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:37.232245Z","caller":"traceutil/trace.go:171","msg":"trace[742955683] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"107.272138ms","start":"2026-10-07T22:56:37.123294Z","end":"2026-10-07T22:56:37.230566Z","steps":["trace[742955683] 'process raft request' (duration: 106.60612ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:46.466893Z","caller":"traceutil/trace.go:171","msg":"trace[1826509070] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"127.311226ms","start":"2026-10-07T22:56:46.339506Z","end":"2026-10-07T22:56:46.466817Z","steps":["trace[1826509070] 'process raft request' (duration: 127.12314ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:48.379383Z","caller":"traceutil/trace.go:171","msg":"trace[1557133304] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"268.842948ms","start":"2026-10-07T22:56:48.110512Z","end":"2026-10-07T22:56:48.379355Z","steps":["trace[1557133304] 'process raft request' (duration: 268.682074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:48.723068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.287865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:48.723162Z","caller":"traceutil/trace.go:171","msg":"trace[1980863945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26130; }","duration":"313.434659ms","start":"2026-10-07T22:56:48.409702Z","end":"2026-10-07T22:56:48.723137Z","steps":["trace[1980863945] 'range keys from in-memory index tree' (duration: 313.203453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:48.723206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:48.409657Z","time spent":"313.540563ms","remote":"127.0.0.1:42298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-07T22:56:48.723302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.304354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-07T22:56:48.723396Z","caller":"traceutil/trace.go:171","msg":"trace[662048354] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26130; }","duration":"314.448778ms","start":"2026-10-07T22:56:48.408922Z","end":"2026-10-07T22:56:48.723371Z","steps":["trace[662048354] 'range keys from in-memory index tree' (duration: 314.075417ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:48.723456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:48.408904Z","time spent":"314.538621ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-07T22:56:48.723436Z","caller":"traceutil/trace.go:171","msg":"trace[334528357] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"251.571027ms","start":"2026-10-07T22:56:48.471837Z","end":"2026-10-07T22:56:48.723408Z","steps":["trace[334528357] 'process raft request' (duration: 208.464746ms)","trace[334528357] 'compare' (duration: 42.673739ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:48.976304Z","caller":"traceutil/trace.go:171","msg":"trace[124834597] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"127.661066ms","start":"2026-10-07T22:56:48.848611Z","end":"2026-10-07T22:56:48.976272Z","steps":["trace[124834597] 'process raft request' (duration: 126.800662ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:49.323101Z","caller":"traceutil/trace.go:171","msg":"trace[1869581363] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"191.941077ms","start":"2026-10-07T22:56:49.131134Z","end":"2026-10-07T22:56:49.323076Z","steps":["trace[1869581363] 'process raft request' (duration: 189.578101ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:49.871744Z","caller":"traceutil/trace.go:171","msg":"trace[229592332] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"731.864745ms","start":"2026-10-07T22:56:49.139855Z","end":"2026-10-07T22:56:49.87172Z","steps":["trace[229592332] 'process raft request' (duration: 731.755662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.871899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.139846Z","time spent":"731.973408ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:49.87202Z","caller":"traceutil/trace.go:171","msg":"trace[1061253745] linearizableReadLoop","detail":"{readStateIndex:26995; appliedIndex:26991; }","duration":"683.072346ms","start":"2026-10-07T22:56:49.188934Z","end":"2026-10-07T22:56:49.872007Z","steps":["trace[1061253745] 'read index received' (duration: 131.712519ms)","trace[1061253745] 'applied index is now lower than readState.Index' (duration: 551.358777ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:49.872098Z","caller":"traceutil/trace.go:171","msg":"trace[791932380] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"581.811186ms","start":"2026-10-07T22:56:49.29025Z","end":"2026-10-07T22:56:49.872062Z","steps":["trace[791932380] 'process raft request' (duration: 581.649641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.872165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.21458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:56:49.872103Z","caller":"traceutil/trace.go:171","msg":"trace[1895290054] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"730.830506ms","start":"2026-10-07T22:56:49.141209Z","end":"2026-10-07T22:56:49.87204Z","steps":["trace[1895290054] 'process raft request' (duration: 730.451015ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:49.872203Z","caller":"traceutil/trace.go:171","msg":"trace[699705842] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26137; }","duration":"683.312492ms","start":"2026-10-07T22:56:49.188878Z","end":"2026-10-07T22:56:49.872191Z","steps":["trace[699705842] 'agreement among raft nodes before linearized reading' (duration: 683.204099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.872235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.188851Z","time spent":"683.374394ms","remote":"127.0.0.1:42534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":32,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-10-07T22:56:49.872264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.29022Z","time spent":"581.96119ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:49.872307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.141193Z","time spent":"731.026361ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:49.871775Z","caller":"traceutil/trace.go:171","msg":"trace[46857202] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"740.148126ms","start":"2026-10-07T22:56:49.131565Z","end":"2026-10-07T22:56:49.871713Z","steps":["trace[46857202] 'process raft request' (duration: 739.882698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.872708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.131538Z","time spent":"740.982048ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:49.907748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.68159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:49.907808Z","caller":"traceutil/trace.go:171","msg":"trace[156865703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26137; }","duration":"684.779033ms","start":"2026-10-07T22:56:49.223015Z","end":"2026-10-07T22:56:49.907794Z","steps":["trace[156865703] 'agreement among raft nodes before linearized reading' (duration: 684.6883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.90784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.222999Z","time spent":"684.831185ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-07T22:56:49.907881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.9818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:56:49.907954Z","caller":"traceutil/trace.go:171","msg":"trace[295853828] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26137; }","duration":"214.062443ms","start":"2026-10-07T22:56:49.693871Z","end":"2026-10-07T22:56:49.907933Z","steps":["trace[295853828] 'agreement among raft nodes before linearized reading' (duration: 213.927839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.907969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.788531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-07T22:56:49.908052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.477849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-07T22:56:49.908117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.279544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-07T22:56:49.908166Z","caller":"traceutil/trace.go:171","msg":"trace[353130401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26137; }","duration":"593.357237ms","start":"2026-10-07T22:56:49.314796Z","end":"2026-10-07T22:56:49.908153Z","steps":["trace[353130401] 'agreement among raft nodes before linearized reading' (duration: 593.183472ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:49.90815Z","caller":"traceutil/trace.go:171","msg":"trace[39951277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26137; }","duration":"593.049238ms","start":"2026-10-07T22:56:49.315074Z","end":"2026-10-07T22:56:49.908124Z","steps":["trace[39951277] 'agreement among raft nodes before linearized reading' (duration: 592.644417ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.908207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.314785Z","time spent":"593.412578ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-07T22:56:49.908207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.666411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-07T22:56:49.908158Z","caller":"traceutil/trace.go:171","msg":"trace[120249624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26137; }","duration":"594.608192ms","start":"2026-10-07T22:56:49.313535Z","end":"2026-10-07T22:56:49.908143Z","steps":["trace[120249624] 'agreement among raft nodes before linearized reading' (duration: 594.316263ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:49.908272Z","caller":"traceutil/trace.go:171","msg":"trace[2114434339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26137; }","duration":"600.767383ms","start":"2026-10-07T22:56:49.307489Z","end":"2026-10-07T22:56:49.908256Z","steps":["trace[2114434339] 'agreement among raft nodes before linearized reading' (duration: 600.575537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.90829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.315054Z","time spent":"593.177132ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-07T22:56:49.908298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.98712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-07T22:56:49.908313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.307471Z","time spent":"600.834345ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-07T22:56:49.908338Z","caller":"traceutil/trace.go:171","msg":"trace[632638474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26137; }","duration":"596.058822ms","start":"2026-10-07T22:56:49.312269Z","end":"2026-10-07T22:56:49.908328Z","steps":["trace[632638474] 'agreement among raft nodes before linearized reading' (duration: 595.927898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:49.908344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.313524Z","time spent":"594.761596ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-07T22:56:49.908374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.312254Z","time spent":"596.113534ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-07T22:56:50.017094Z","caller":"traceutil/trace.go:171","msg":"trace[613452362] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"104.49911ms","start":"2026-10-07T22:56:49.912569Z","end":"2026-10-07T22:56:50.017068Z","steps":["trace[613452362] 'process raft request' (duration: 104.353776ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:50.577079Z","caller":"traceutil/trace.go:171","msg":"trace[120755523] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"663.902611ms","start":"2026-10-07T22:56:49.913164Z","end":"2026-10-07T22:56:50.577066Z","steps":["trace[120755523] 'process raft request' (duration: 663.644234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:50.577252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.913148Z","time spent":"663.986353ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:50.577477Z","caller":"traceutil/trace.go:171","msg":"trace[1939446457] linearizableReadLoop","detail":"{readStateIndex:27002; appliedIndex:26998; }","duration":"354.788922ms","start":"2026-10-07T22:56:50.222663Z","end":"2026-10-07T22:56:50.577452Z","steps":["trace[1939446457] 'read index received' (duration: 353.99393ms)","trace[1939446457] 'applied index is now lower than readState.Index' (duration: 793.742µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:50.57753Z","caller":"traceutil/trace.go:171","msg":"trace[645455461] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"664.257911ms","start":"2026-10-07T22:56:49.913231Z","end":"2026-10-07T22:56:50.577489Z","steps":["trace[645455461] 'process raft request' (duration: 663.795818ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:50.576939Z","caller":"traceutil/trace.go:171","msg":"trace[1838686442] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"664.268621ms","start":"2026-10-07T22:56:49.912613Z","end":"2026-10-07T22:56:50.576882Z","steps":["trace[1838686442] 'process raft request' (duration: 663.976723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:50.577599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.924115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:50.577646Z","caller":"traceutil/trace.go:171","msg":"trace[685734745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26142; }","duration":"355.018178ms","start":"2026-10-07T22:56:50.222614Z","end":"2026-10-07T22:56:50.577632Z","steps":["trace[685734745] 'agreement among raft nodes before linearized reading' (duration: 354.932325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:50.577713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:50.222594Z","time spent":"355.108861ms","remote":"127.0.0.1:42298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-07T22:56:50.577666Z","caller":"traceutil/trace.go:171","msg":"trace[704264679] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"663.903291ms","start":"2026-10-07T22:56:49.913745Z","end":"2026-10-07T22:56:50.577648Z","steps":["trace[704264679] 'process raft request' (duration: 663.607283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:50.577817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.913724Z","time spent":"664.024974ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:50.578803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.912585Z","time spent":"665.035762ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:50.579094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:49.913211Z","time spent":"664.346563ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:50.829515Z","caller":"traceutil/trace.go:171","msg":"trace[1166555357] linearizableReadLoop","detail":"{readStateIndex:27004; appliedIndex:27002; }","duration":"249.375145ms","start":"2026-10-07T22:56:50.580106Z","end":"2026-10-07T22:56:50.829481Z","steps":["trace[1166555357] 'read index received' (duration: 236.463676ms)","trace[1166555357] 'applied index is now lower than readState.Index' (duration: 12.909849ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:56:50.829938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.813098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:50.829924Z","caller":"traceutil/trace.go:171","msg":"trace[1270130917] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"273.595761ms","start":"2026-10-07T22:56:50.556286Z","end":"2026-10-07T22:56:50.829882Z","steps":["trace[1270130917] 'process raft request' (duration: 273.131828ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:50.829985Z","caller":"traceutil/trace.go:171","msg":"trace[11804309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26144; }","duration":"249.87316ms","start":"2026-10-07T22:56:50.580098Z","end":"2026-10-07T22:56:50.829971Z","steps":["trace[11804309] 'agreement among raft nodes before linearized reading' (duration: 249.778527ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:50.830126Z","caller":"traceutil/trace.go:171","msg":"trace[27656168] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"443.348029ms","start":"2026-10-07T22:56:50.386749Z","end":"2026-10-07T22:56:50.830097Z","steps":["trace[27656168] 'process raft request' (duration: 429.805421ms)","trace[27656168] 'compare' (duration: 12.721585ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:56:50.830256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:50.386719Z","time spent":"443.451502ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:51.330569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3113290368577152374,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-07T22:56:51.371474Z","caller":"traceutil/trace.go:171","msg":"trace[317323179] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"642.733152ms","start":"2026-10-07T22:56:50.728715Z","end":"2026-10-07T22:56:51.371449Z","steps":["trace[317323179] 'process raft request' (duration: 642.579377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:51.371631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:50.728659Z","time spent":"642.888806ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:51.413839Z","caller":"traceutil/trace.go:171","msg":"trace[583214857] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"418.788054ms","start":"2026-10-07T22:56:50.995001Z","end":"2026-10-07T22:56:51.41379Z","steps":["trace[583214857] 'process raft request' (duration: 415.790101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:51.414006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:50.994979Z","time spent":"418.937589ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:51.41576Z","caller":"traceutil/trace.go:171","msg":"trace[1554161679] linearizableReadLoop","detail":"{readStateIndex:27007; appliedIndex:27004; }","duration":"586.113245ms","start":"2026-10-07T22:56:50.829615Z","end":"2026-10-07T22:56:51.415728Z","steps":["trace[1554161679] 'read index received' (duration: 541.660587ms)","trace[1554161679] 'applied index is now lower than readState.Index' (duration: 44.450678ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:56:51.415969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.242832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:56:51.416008Z","caller":"traceutil/trace.go:171","msg":"trace[1501538953] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26147; }","duration":"510.367475ms","start":"2026-10-07T22:56:50.905631Z","end":"2026-10-07T22:56:51.415998Z","steps":["trace[1501538953] 'agreement among raft nodes before linearized reading' (duration: 510.259442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:51.416008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.787972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-07T22:56:51.416043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:50.905612Z","time spent":"510.420846ms","remote":"127.0.0.1:42458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":32,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-10-07T22:56:51.416084Z","caller":"traceutil/trace.go:171","msg":"trace[1293996434] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:26147; }","duration":"424.916005ms","start":"2026-10-07T22:56:50.991148Z","end":"2026-10-07T22:56:51.416064Z","steps":["trace[1293996434] 'agreement among raft nodes before linearized reading' (duration: 424.795932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:51.416126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:50.99113Z","time spent":"424.986967ms","remote":"127.0.0.1:36684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-10-07T22:56:51.416548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.604176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-10-07T22:56:51.416627Z","caller":"traceutil/trace.go:171","msg":"trace[1947163985] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:26147; }","duration":"820.732959ms","start":"2026-10-07T22:56:50.595872Z","end":"2026-10-07T22:56:51.416605Z","steps":["trace[1947163985] 'agreement among raft nodes before linearized reading' (duration: 819.984239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:51.416691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:50.595855Z","time spent":"820.806182ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":930,"request content":"key:\"/registry/serviceaccounts/auth-system/keycloak\" "} {"level":"warn","ts":"2026-10-07T22:56:56.874224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.399049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:56:56.874332Z","caller":"traceutil/trace.go:171","msg":"trace[1884516245] linearizableReadLoop","detail":"{readStateIndex:27104; appliedIndex:27103; }","duration":"268.973901ms","start":"2026-10-07T22:56:56.605328Z","end":"2026-10-07T22:56:56.874302Z","steps":["trace[1884516245] 'read index received' (duration: 20.305965ms)","trace[1884516245] 'applied index is now lower than readState.Index' (duration: 248.666776ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:56.874395Z","caller":"traceutil/trace.go:171","msg":"trace[745683175] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"270.596226ms","start":"2026-10-07T22:56:56.603788Z","end":"2026-10-07T22:56:56.874384Z","steps":["trace[745683175] 'process raft request' (duration: 21.957241ms)","trace[745683175] 'compare' (duration: 248.297006ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:56:56.87463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.279279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-07T22:56:56.874734Z","caller":"traceutil/trace.go:171","msg":"trace[2010104363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26243; }","duration":"269.393284ms","start":"2026-10-07T22:56:56.605322Z","end":"2026-10-07T22:56:56.874716Z","steps":["trace[2010104363] 'agreement among raft nodes before linearized reading' (duration: 269.126446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:56.893634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.710446ms","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-10-07T22:56:56.893689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.876596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:56.893722Z","caller":"traceutil/trace.go:171","msg":"trace[1190036398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26244; }","duration":"281.82656ms","start":"2026-10-07T22:56:56.611877Z","end":"2026-10-07T22:56:56.893703Z","steps":["trace[1190036398] 'agreement among raft nodes before linearized reading' (duration: 281.648695ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:56.893741Z","caller":"traceutil/trace.go:171","msg":"trace[1138356956] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26244; }","duration":"265.982738ms","start":"2026-10-07T22:56:56.627742Z","end":"2026-10-07T22:56:56.893725Z","steps":["trace[1138356956] 'agreement among raft nodes before linearized reading' (duration: 265.867995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:56.8939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.684368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-07T22:56:56.893984Z","caller":"traceutil/trace.go:171","msg":"trace[185908353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26244; }","duration":"280.822082ms","start":"2026-10-07T22:56:56.613141Z","end":"2026-10-07T22:56:56.893963Z","steps":["trace[185908353] 'agreement among raft nodes before linearized reading' (duration: 280.543394ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:57.167387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.236308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:56:57.167483Z","caller":"traceutil/trace.go:171","msg":"trace[148099422] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"287.622411ms","start":"2026-10-07T22:56:56.879844Z","end":"2026-10-07T22:56:57.167466Z","steps":["trace[148099422] 'process raft request' (duration: 148.226488ms)","trace[148099422] 'compare' (duration: 138.818637ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:57.23279Z","caller":"traceutil/trace.go:171","msg":"trace[50191934] linearizableReadLoop","detail":"{readStateIndex:27108; appliedIndex:27106; }","duration":"148.62362ms","start":"2026-10-07T22:56:57.084152Z","end":"2026-10-07T22:56:57.232776Z","steps":["trace[50191934] 'read index received' (duration: 148.501156ms)","trace[50191934] 'applied index is now lower than readState.Index' (duration: 121.664µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:57.232874Z","caller":"traceutil/trace.go:171","msg":"trace[419812653] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"334.775944ms","start":"2026-10-07T22:56:56.89809Z","end":"2026-10-07T22:56:57.232866Z","steps":["trace[419812653] 'process raft request' (duration: 334.659441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:57.232937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:56.898069Z","time spent":"334.817146ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T22:56:57.232893Z","caller":"traceutil/trace.go:171","msg":"trace[2097652815] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"335.112014ms","start":"2026-10-07T22:56:56.897735Z","end":"2026-10-07T22:56:57.232847Z","steps":["trace[2097652815] 'process raft request' (duration: 334.938559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:57.23311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T22:56:56.897715Z","time spent":"335.294939ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T22:56:57.233148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.979409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6164e7de-4075-4147-9ba1-32662670372c\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-07T22:56:57.233207Z","caller":"traceutil/trace.go:171","msg":"trace[1392702827] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6164e7de-4075-4147-9ba1-32662670372c; range_end:; response_count:1; response_revision:26247; }","duration":"149.082942ms","start":"2026-10-07T22:56:57.08411Z","end":"2026-10-07T22:56:57.233193Z","steps":["trace[1392702827] 'agreement among raft nodes before linearized reading' (duration: 148.870246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:59.45986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.284204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:56:59.460085Z","caller":"traceutil/trace.go:171","msg":"trace[191608752] linearizableReadLoop","detail":"{readStateIndex:27125; appliedIndex:27123; }","duration":"214.814503ms","start":"2026-10-07T22:56:59.245251Z","end":"2026-10-07T22:56:59.460066Z","steps":["trace[191608752] 'read index received' (duration: 35.320644ms)","trace[191608752] 'applied index is now lower than readState.Index' (duration: 179.492879ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:59.460125Z","caller":"traceutil/trace.go:171","msg":"trace[1260907466] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"217.356144ms","start":"2026-10-07T22:56:59.24271Z","end":"2026-10-07T22:56:59.460066Z","steps":["trace[1260907466] 'process raft request' (duration: 37.783163ms)","trace[1260907466] 'compare' (duration: 178.849921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:56:59.460177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.924356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:56:59.460207Z","caller":"traceutil/trace.go:171","msg":"trace[1132737873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26263; }","duration":"214.983558ms","start":"2026-10-07T22:56:59.245213Z","end":"2026-10-07T22:56:59.460197Z","steps":["trace[1132737873] 'agreement among raft nodes before linearized reading' (duration: 214.929056ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:59.460132Z","caller":"traceutil/trace.go:171","msg":"trace[379507451] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"216.701265ms","start":"2026-10-07T22:56:59.243412Z","end":"2026-10-07T22:56:59.460113Z","steps":["trace[379507451] 'process raft request' (duration: 216.547001ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:59.576593Z","caller":"traceutil/trace.go:171","msg":"trace[1725265858] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"136.702197ms","start":"2026-10-07T22:56:59.439866Z","end":"2026-10-07T22:56:59.576568Z","steps":["trace[1725265858] 'process raft request' (duration: 136.633575ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:59.576612Z","caller":"traceutil/trace.go:171","msg":"trace[494699099] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"179.683455ms","start":"2026-10-07T22:56:59.396883Z","end":"2026-10-07T22:56:59.576566Z","steps":["trace[494699099] 'process raft request' (duration: 177.678399ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:59.578472Z","caller":"traceutil/trace.go:171","msg":"trace[801432328] linearizableReadLoop","detail":"{readStateIndex:27129; appliedIndex:27125; }","duration":"118.310576ms","start":"2026-10-07T22:56:59.460118Z","end":"2026-10-07T22:56:59.578428Z","steps":["trace[801432328] 'read index received' (duration: 114.377716ms)","trace[801432328] 'applied index is now lower than readState.Index' (duration: 3.9318ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:56:59.578566Z","caller":"traceutil/trace.go:171","msg":"trace[624447862] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"134.519137ms","start":"2026-10-07T22:56:59.44401Z","end":"2026-10-07T22:56:59.57853Z","steps":["trace[624447862] 'process raft request' (duration: 134.194458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:56:59.578715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.815427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6164e7de-4075-4147-9ba1-32662670372c\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-10-07T22:56:59.578772Z","caller":"traceutil/trace.go:171","msg":"trace[2124592696] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6164e7de-4075-4147-9ba1-32662670372c; range_end:; response_count:1; response_revision:26267; }","duration":"254.91841ms","start":"2026-10-07T22:56:59.323834Z","end":"2026-10-07T22:56:59.578752Z","steps":["trace[2124592696] 'agreement among raft nodes before linearized reading' (duration: 254.702964ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:56:59.578779Z","caller":"traceutil/trace.go:171","msg":"trace[1695536262] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"120.732543ms","start":"2026-10-07T22:56:59.458013Z","end":"2026-10-07T22:56:59.578746Z","steps":["trace[1695536262] 'process raft request' (duration: 120.348312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:03.839421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.5572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-07T22:57:03.83952Z","caller":"traceutil/trace.go:171","msg":"trace[1734797022] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26314; }","duration":"282.714504ms","start":"2026-10-07T22:57:03.556784Z","end":"2026-10-07T22:57:03.839499Z","steps":["trace[1734797022] 'range keys from in-memory index tree' (duration: 282.386156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:03.839619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.446854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:57:03.839716Z","caller":"traceutil/trace.go:171","msg":"trace[597924122] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"250.795585ms","start":"2026-10-07T22:57:03.588904Z","end":"2026-10-07T22:57:03.8397Z","steps":["trace[597924122] 'process raft request' (duration: 83.187486ms)","trace[597924122] 'compare' (duration: 167.087954ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:03.882356Z","caller":"traceutil/trace.go:171","msg":"trace[1489812838] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"293.072502ms","start":"2026-10-07T22:57:03.589269Z","end":"2026-10-07T22:57:03.882341Z","steps":["trace[1489812838] 'process raft request' (duration: 292.958139ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:03.882573Z","caller":"traceutil/trace.go:171","msg":"trace[1530325628] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"290.638325ms","start":"2026-10-07T22:57:03.59191Z","end":"2026-10-07T22:57:03.882549Z","steps":["trace[1530325628] 'process raft request' (duration: 290.401429ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:03.882576Z","caller":"traceutil/trace.go:171","msg":"trace[542358309] linearizableReadLoop","detail":"{readStateIndex:27179; appliedIndex:27177; }","duration":"204.368042ms","start":"2026-10-07T22:57:03.678155Z","end":"2026-10-07T22:57:03.882523Z","steps":["trace[542358309] 'read index received' (duration: 204.054604ms)","trace[542358309] 'applied index is now lower than readState.Index' (duration: 311.068µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:57:03.882758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.584358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:57:03.882802Z","caller":"traceutil/trace.go:171","msg":"trace[1530249783] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:26317; }","duration":"204.691711ms","start":"2026-10-07T22:57:03.678098Z","end":"2026-10-07T22:57:03.88279Z","steps":["trace[1530249783] 'agreement among raft nodes before linearized reading' (duration: 204.587628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:03.882935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.409944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.203\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-07T22:57:03.883031Z","caller":"traceutil/trace.go:171","msg":"trace[1667073106] range","detail":"{range_begin:/registry/masterleases/162.253.55.203; range_end:; response_count:1; response_revision:26317; }","duration":"143.555948ms","start":"2026-10-07T22:57:03.739453Z","end":"2026-10-07T22:57:03.883008Z","steps":["trace[1667073106] 'agreement among raft nodes before linearized reading' (duration: 143.317151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:03.883181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.906993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:57:03.88323Z","caller":"traceutil/trace.go:171","msg":"trace[1009698036] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26317; }","duration":"131.988196ms","start":"2026-10-07T22:57:03.75123Z","end":"2026-10-07T22:57:03.883218Z","steps":["trace[1009698036] 'agreement among raft nodes before linearized reading' (duration: 131.915494ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:04.143258Z","caller":"traceutil/trace.go:171","msg":"trace[1414845133] linearizableReadLoop","detail":"{readStateIndex:27180; appliedIndex:27179; }","duration":"235.569542ms","start":"2026-10-07T22:57:03.907651Z","end":"2026-10-07T22:57:04.143221Z","steps":["trace[1414845133] 'read index received' (duration: 137.214712ms)","trace[1414845133] 'applied index is now lower than readState.Index' (duration: 98.35135ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:04.143377Z","caller":"traceutil/trace.go:171","msg":"trace[401682418] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"213.406424ms","start":"2026-10-07T22:57:03.929945Z","end":"2026-10-07T22:57:04.143352Z","steps":["trace[401682418] 'process raft request' (duration: 213.362363ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:04.14361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.935651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:57:04.143694Z","caller":"traceutil/trace.go:171","msg":"trace[1267452594] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:26321; }","duration":"236.116037ms","start":"2026-10-07T22:57:03.907564Z","end":"2026-10-07T22:57:04.14368Z","steps":["trace[1267452594] 'agreement among raft nodes before linearized reading' (duration: 235.826278ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:04.143749Z","caller":"traceutil/trace.go:171","msg":"trace[1735966620] transaction","detail":"{read_only:false; response_revision:26318; number_of_response:1; }","duration":"224.319038ms","start":"2026-10-07T22:57:03.919417Z","end":"2026-10-07T22:57:04.143736Z","steps":["trace[1735966620] 'process raft request' (duration: 223.66761ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:04.143877Z","caller":"traceutil/trace.go:171","msg":"trace[1432243941] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"215.229665ms","start":"2026-10-07T22:57:03.928611Z","end":"2026-10-07T22:57:04.143841Z","steps":["trace[1432243941] 'process raft request' (duration: 214.656059ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:04.143905Z","caller":"traceutil/trace.go:171","msg":"trace[1067153450] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"215.867073ms","start":"2026-10-07T22:57:03.927999Z","end":"2026-10-07T22:57:04.143866Z","steps":["trace[1067153450] 'process raft request' (duration: 215.225595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:04.144064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.559448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-10-07T22:57:04.144103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.561346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:57:04.144153Z","caller":"traceutil/trace.go:171","msg":"trace[1199227733] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:26321; }","duration":"112.625567ms","start":"2026-10-07T22:57:04.031513Z","end":"2026-10-07T22:57:04.144139Z","steps":["trace[1199227733] 'agreement among raft nodes before linearized reading' (duration: 112.535825ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:04.144154Z","caller":"traceutil/trace.go:171","msg":"trace[1842257357] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26321; }","duration":"148.697971ms","start":"2026-10-07T22:57:03.995435Z","end":"2026-10-07T22:57:04.144133Z","steps":["trace[1842257357] 'agreement among raft nodes before linearized reading' (duration: 148.421833ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:04.270888Z","caller":"traceutil/trace.go:171","msg":"trace[1755621423] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"126.121273ms","start":"2026-10-07T22:57:04.144739Z","end":"2026-10-07T22:57:04.27086Z","steps":["trace[1755621423] 'process raft request' (duration: 125.938868ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:04.278306Z","caller":"traceutil/trace.go:171","msg":"trace[799165248] linearizableReadLoop","detail":"{readStateIndex:27186; appliedIndex:27184; }","duration":"130.29645ms","start":"2026-10-07T22:57:04.147991Z","end":"2026-10-07T22:57:04.278287Z","steps":["trace[799165248] 'read index received' (duration: 122.643417ms)","trace[799165248] 'applied index is now lower than readState.Index' (duration: 7.652203ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:04.278548Z","caller":"traceutil/trace.go:171","msg":"trace[553802800] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"130.576267ms","start":"2026-10-07T22:57:04.147959Z","end":"2026-10-07T22:57:04.278536Z","steps":["trace[553802800] 'process raft request' (duration: 130.232687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:04.278815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.819094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-07T22:57:04.278851Z","caller":"traceutil/trace.go:171","msg":"trace[877682619] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26323; }","duration":"130.906256ms","start":"2026-10-07T22:57:04.147934Z","end":"2026-10-07T22:57:04.27884Z","steps":["trace[877682619] 'agreement among raft nodes before linearized reading' (duration: 130.780323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:04.279005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.090965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:57:04.279037Z","caller":"traceutil/trace.go:171","msg":"trace[1686590407] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26323; }","duration":"115.143777ms","start":"2026-10-07T22:57:04.163883Z","end":"2026-10-07T22:57:04.279026Z","steps":["trace[1686590407] 'agreement among raft nodes before linearized reading' (duration: 115.096566ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:08.38017Z","caller":"traceutil/trace.go:171","msg":"trace[1913639676] linearizableReadLoop","detail":"{readStateIndex:27221; appliedIndex:27219; }","duration":"158.146885ms","start":"2026-10-07T22:57:08.222003Z","end":"2026-10-07T22:57:08.38015Z","steps":["trace[1913639676] 'read index received' (duration: 80.684728ms)","trace[1913639676] 'applied index is now lower than readState.Index' (duration: 77.461627ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:08.380216Z","caller":"traceutil/trace.go:171","msg":"trace[1711931154] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"219.451802ms","start":"2026-10-07T22:57:08.160728Z","end":"2026-10-07T22:57:08.38018Z","steps":["trace[1711931154] 'process raft request' (duration: 142.021305ms)","trace[1711931154] 'compare' (duration: 77.239832ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:08.380278Z","caller":"traceutil/trace.go:171","msg":"trace[65776412] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"219.437082ms","start":"2026-10-07T22:57:08.160831Z","end":"2026-10-07T22:57:08.380268Z","steps":["trace[65776412] 'process raft request' (duration: 219.284127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:08.380418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.396912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:57:08.380437Z","caller":"traceutil/trace.go:171","msg":"trace[837772060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26358; }","duration":"158.436203ms","start":"2026-10-07T22:57:08.221995Z","end":"2026-10-07T22:57:08.380431Z","steps":["trace[837772060] 'agreement among raft nodes before linearized reading' (duration: 158.380662ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:08.547073Z","caller":"traceutil/trace.go:171","msg":"trace[1061137292] linearizableReadLoop","detail":"{readStateIndex:27222; appliedIndex:27221; }","duration":"116.955297ms","start":"2026-10-07T22:57:08.430073Z","end":"2026-10-07T22:57:08.547028Z","steps":["trace[1061137292] 'read index received' (duration: 61.558695ms)","trace[1061137292] 'applied index is now lower than readState.Index' (duration: 55.395072ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:08.54722Z","caller":"traceutil/trace.go:171","msg":"trace[960967426] transaction","detail":"{read_only:false; response_revision:26359; number_of_response:1; }","duration":"162.411253ms","start":"2026-10-07T22:57:08.384797Z","end":"2026-10-07T22:57:08.547208Z","steps":["trace[960967426] 'process raft request' (duration: 106.848586ms)","trace[960967426] 'compare' (duration: 55.204777ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:57:08.54741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.318647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:57:08.54745Z","caller":"traceutil/trace.go:171","msg":"trace[249784432] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:26359; }","duration":"117.426491ms","start":"2026-10-07T22:57:08.430009Z","end":"2026-10-07T22:57:08.547436Z","steps":["trace[249784432] 'agreement among raft nodes before linearized reading' (duration: 117.340908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:11.728675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.806284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-07T22:57:11.728767Z","caller":"traceutil/trace.go:171","msg":"trace[606375482] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26382; }","duration":"129.956359ms","start":"2026-10-07T22:57:11.598788Z","end":"2026-10-07T22:57:11.728745Z","steps":["trace[606375482] 'range keys from in-memory index tree' (duration: 129.610719ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:28.187166Z","caller":"traceutil/trace.go:171","msg":"trace[86630568] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"209.347321ms","start":"2026-10-07T22:57:27.977796Z","end":"2026-10-07T22:57:28.187143Z","steps":["trace[86630568] 'process raft request' (duration: 209.211957ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:28.226175Z","caller":"traceutil/trace.go:171","msg":"trace[978563953] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"217.441897ms","start":"2026-10-07T22:57:28.008716Z","end":"2026-10-07T22:57:28.226158Z","steps":["trace[978563953] 'process raft request' (duration: 217.035866ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:28.226279Z","caller":"traceutil/trace.go:171","msg":"trace[739040657] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"217.476897ms","start":"2026-10-07T22:57:28.008797Z","end":"2026-10-07T22:57:28.226274Z","steps":["trace[739040657] 'process raft request' (duration: 217.057935ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:28.382238Z","caller":"traceutil/trace.go:171","msg":"trace[1141381910] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"143.406524ms","start":"2026-10-07T22:57:28.238796Z","end":"2026-10-07T22:57:28.382203Z","steps":["trace[1141381910] 'process raft request' (duration: 56.697309ms)","trace[1141381910] 'compare' (duration: 86.457808ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:28.382488Z","caller":"traceutil/trace.go:171","msg":"trace[758436489] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"122.929093ms","start":"2026-10-07T22:57:28.259506Z","end":"2026-10-07T22:57:28.382435Z","steps":["trace[758436489] 'process raft request' (duration: 122.630105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:28.676989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.627582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:57:28.677198Z","caller":"traceutil/trace.go:171","msg":"trace[1540987709] linearizableReadLoop","detail":"{readStateIndex:27391; appliedIndex:27388; }","duration":"180.179039ms","start":"2026-10-07T22:57:28.496999Z","end":"2026-10-07T22:57:28.677178Z","steps":["trace[1540987709] 'read index received' (duration: 43.016709ms)","trace[1540987709] 'applied index is now lower than readState.Index' (duration: 137.16148ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:28.677286Z","caller":"traceutil/trace.go:171","msg":"trace[515460366] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"213.777495ms","start":"2026-10-07T22:57:28.463498Z","end":"2026-10-07T22:57:28.677276Z","steps":["trace[515460366] 'process raft request' (duration: 213.59ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:28.67735Z","caller":"traceutil/trace.go:171","msg":"trace[662184402] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"284.185176ms","start":"2026-10-07T22:57:28.393125Z","end":"2026-10-07T22:57:28.67731Z","steps":["trace[662184402] 'process raft request' (duration: 99.142432ms)","trace[662184402] 'compare' (duration: 184.523029ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:28.677368Z","caller":"traceutil/trace.go:171","msg":"trace[1349765463] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"209.083644ms","start":"2026-10-07T22:57:28.468258Z","end":"2026-10-07T22:57:28.677342Z","steps":["trace[1349765463] 'process raft request' (duration: 208.880408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:28.677569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.525048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-07T22:57:28.677686Z","caller":"traceutil/trace.go:171","msg":"trace[1076804712] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26523; }","duration":"180.732594ms","start":"2026-10-07T22:57:28.49693Z","end":"2026-10-07T22:57:28.677663Z","steps":["trace[1076804712] 'agreement among raft nodes before linearized reading' (duration: 180.390694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:29.031335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.689924ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-07T22:57:29.885681Z","caller":"traceutil/trace.go:171","msg":"trace[844719451] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"120.758653ms","start":"2026-10-07T22:57:29.764891Z","end":"2026-10-07T22:57:29.885649Z","steps":["trace[844719451] 'process raft request' (duration: 82.950081ms)","trace[844719451] 'compare' (duration: 37.613837ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:57:30.12112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.229731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:57:30.1213Z","caller":"traceutil/trace.go:171","msg":"trace[1305697049] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26530; }","duration":"114.411006ms","start":"2026-10-07T22:57:30.00682Z","end":"2026-10-07T22:57:30.121231Z","steps":["trace[1305697049] 'range keys from in-memory index tree' (duration: 114.133969ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:30.354775Z","caller":"traceutil/trace.go:171","msg":"trace[27511459] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"122.824201ms","start":"2026-10-07T22:57:30.231911Z","end":"2026-10-07T22:57:30.354735Z","steps":["trace[27511459] 'process raft request' (duration: 122.579094ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:30.896611Z","caller":"traceutil/trace.go:171","msg":"trace[1281575086] linearizableReadLoop","detail":"{readStateIndex:27406; appliedIndex:27405; }","duration":"147.259352ms","start":"2026-10-07T22:57:30.749331Z","end":"2026-10-07T22:57:30.89659Z","steps":["trace[1281575086] 'read index received' (duration: 147.233301ms)","trace[1281575086] 'applied index is now lower than readState.Index' (duration: 25.001µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:30.896886Z","caller":"traceutil/trace.go:171","msg":"trace[984522052] transaction","detail":"{read_only:false; response_revision:26537; number_of_response:1; }","duration":"213.751223ms","start":"2026-10-07T22:57:30.683101Z","end":"2026-10-07T22:57:30.896852Z","steps":["trace[984522052] 'process raft request' (duration: 213.27751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:30.897009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.662483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-07T22:57:30.89709Z","caller":"traceutil/trace.go:171","msg":"trace[1756489305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26537; }","duration":"147.780276ms","start":"2026-10-07T22:57:30.749276Z","end":"2026-10-07T22:57:30.897057Z","steps":["trace[1756489305] 'agreement among raft nodes before linearized reading' (duration: 147.422436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:42.01879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.395772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:57:42.019063Z","caller":"traceutil/trace.go:171","msg":"trace[1467970289] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"154.830423ms","start":"2026-10-07T22:57:41.864179Z","end":"2026-10-07T22:57:42.019009Z","steps":["trace[1467970289] 'process raft request' (duration: 43.107231ms)","trace[1467970289] 'compare' (duration: 111.220698ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:42.164336Z","caller":"traceutil/trace.go:171","msg":"trace[752716380] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"140.773001ms","start":"2026-10-07T22:57:42.023521Z","end":"2026-10-07T22:57:42.164294Z","steps":["trace[752716380] 'process raft request' (duration: 103.061291ms)","trace[752716380] 'compare' (duration: 37.586687ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:42.606797Z","caller":"traceutil/trace.go:171","msg":"trace[1378242157] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"113.519802ms","start":"2026-10-07T22:57:42.493244Z","end":"2026-10-07T22:57:42.606763Z","steps":["trace[1378242157] 'process raft request' (duration: 72.718726ms)","trace[1378242157] 'compare' (duration: 40.427346ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:42.607492Z","caller":"traceutil/trace.go:171","msg":"trace[1519638727] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"102.999449ms","start":"2026-10-07T22:57:42.504474Z","end":"2026-10-07T22:57:42.607473Z","steps":["trace[1519638727] 'process raft request' (duration: 102.783923ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:46.765407Z","caller":"traceutil/trace.go:171","msg":"trace[1434182714] transaction","detail":"{read_only:false; response_revision:26650; number_of_response:1; }","duration":"145.569925ms","start":"2026-10-07T22:57:46.619803Z","end":"2026-10-07T22:57:46.765373Z","steps":["trace[1434182714] 'process raft request' (duration: 145.39869ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:46.765995Z","caller":"traceutil/trace.go:171","msg":"trace[344992109] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"146.029387ms","start":"2026-10-07T22:57:46.61994Z","end":"2026-10-07T22:57:46.765969Z","steps":["trace[344992109] 'process raft request' (duration: 145.907534ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:52.640514Z","caller":"traceutil/trace.go:171","msg":"trace[2037477352] transaction","detail":"{read_only:false; response_revision:26691; number_of_response:1; }","duration":"171.452285ms","start":"2026-10-07T22:57:52.469035Z","end":"2026-10-07T22:57:52.640487Z","steps":["trace[2037477352] 'process raft request' (duration: 129.766365ms)","trace[2037477352] 'compare' (duration: 41.31226ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:52.640652Z","caller":"traceutil/trace.go:171","msg":"trace[106668022] linearizableReadLoop","detail":"{readStateIndex:27565; appliedIndex:27563; }","duration":"102.467704ms","start":"2026-10-07T22:57:52.538157Z","end":"2026-10-07T22:57:52.640625Z","steps":["trace[106668022] 'read index received' (duration: 60.679651ms)","trace[106668022] 'applied index is now lower than readState.Index' (duration: 41.785943ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:52.640729Z","caller":"traceutil/trace.go:171","msg":"trace[2138003591] transaction","detail":"{read_only:false; response_revision:26692; number_of_response:1; }","duration":"171.483546ms","start":"2026-10-07T22:57:52.469215Z","end":"2026-10-07T22:57:52.640699Z","steps":["trace[2138003591] 'process raft request' (duration: 171.167018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:52.640915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.729231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-10-07T22:57:52.64102Z","caller":"traceutil/trace.go:171","msg":"trace[1506056039] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26692; }","duration":"102.894696ms","start":"2026-10-07T22:57:52.5381Z","end":"2026-10-07T22:57:52.640995Z","steps":["trace[1506056039] 'agreement among raft nodes before linearized reading' (duration: 102.609608ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:53.34415Z","caller":"traceutil/trace.go:171","msg":"trace[1485243669] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"158.3305ms","start":"2026-10-07T22:57:53.18579Z","end":"2026-10-07T22:57:53.344121Z","steps":["trace[1485243669] 'process raft request' (duration: 157.917128ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:53.369829Z","caller":"traceutil/trace.go:171","msg":"trace[1338100918] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"171.534388ms","start":"2026-10-07T22:57:53.198268Z","end":"2026-10-07T22:57:53.369803Z","steps":["trace[1338100918] 'process raft request' (duration: 170.951192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T22:57:53.547781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.565717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T22:57:53.547859Z","caller":"traceutil/trace.go:171","msg":"trace[1597279126] linearizableReadLoop","detail":"{readStateIndex:27572; appliedIndex:27571; }","duration":"162.681081ms","start":"2026-10-07T22:57:53.385165Z","end":"2026-10-07T22:57:53.547846Z","steps":["trace[1597279126] 'read index received' (duration: 59.846577ms)","trace[1597279126] 'applied index is now lower than readState.Index' (duration: 102.833624ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:57:53.547932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.770804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:57:53.547951Z","caller":"traceutil/trace.go:171","msg":"trace[1011504224] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26699; }","duration":"162.824635ms","start":"2026-10-07T22:57:53.385119Z","end":"2026-10-07T22:57:53.547943Z","steps":["trace[1011504224] 'agreement among raft nodes before linearized reading' (duration: 162.757023ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:53.548092Z","caller":"traceutil/trace.go:171","msg":"trace[42366118] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"173.820642ms","start":"2026-10-07T22:57:53.374264Z","end":"2026-10-07T22:57:53.548084Z","steps":["trace[42366118] 'process raft request' (duration: 70.885695ms)","trace[42366118] 'compare' (duration: 102.476784ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:53.770415Z","caller":"traceutil/trace.go:171","msg":"trace[851316398] linearizableReadLoop","detail":"{readStateIndex:27574; appliedIndex:27573; }","duration":"163.01128ms","start":"2026-10-07T22:57:53.607378Z","end":"2026-10-07T22:57:53.770389Z","steps":["trace[851316398] 'read index received' (duration: 148.793495ms)","trace[851316398] 'applied index is now lower than readState.Index' (duration: 14.216635ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:53.770626Z","caller":"traceutil/trace.go:171","msg":"trace[964973074] transaction","detail":"{read_only:false; response_revision:26701; number_of_response:1; }","duration":"226.584231ms","start":"2026-10-07T22:57:53.543969Z","end":"2026-10-07T22:57:53.770554Z","steps":["trace[964973074] 'process raft request' (duration: 212.389106ms)","trace[964973074] 'compare' (duration: 13.723292ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:57:53.7708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.393721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T22:57:53.770943Z","caller":"traceutil/trace.go:171","msg":"trace[1793937898] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:26701; }","duration":"163.557825ms","start":"2026-10-07T22:57:53.60733Z","end":"2026-10-07T22:57:53.770888Z","steps":["trace[1793937898] 'agreement among raft nodes before linearized reading' (duration: 163.234756ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:57:53.948896Z","caller":"traceutil/trace.go:171","msg":"trace[577948417] linearizableReadLoop","detail":"{readStateIndex:27577; appliedIndex:27575; }","duration":"137.624883ms","start":"2026-10-07T22:57:53.811246Z","end":"2026-10-07T22:57:53.948871Z","steps":["trace[577948417] 'read index received' (duration: 53.925702ms)","trace[577948417] 'applied index is now lower than readState.Index' (duration: 83.698091ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:57:53.948951Z","caller":"traceutil/trace.go:171","msg":"trace[61010270] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"153.177507ms","start":"2026-10-07T22:57:53.795734Z","end":"2026-10-07T22:57:53.948912Z","steps":["trace[61010270] 'process raft request' (duration: 117.222516ms)","trace[61010270] 'compare' (duration: 35.791626ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:57:53.949057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.790248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:57:53.949109Z","caller":"traceutil/trace.go:171","msg":"trace[1080737550] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:26703; }","duration":"137.903881ms","start":"2026-10-07T22:57:53.811191Z","end":"2026-10-07T22:57:53.949094Z","steps":["trace[1080737550] 'agreement among raft nodes before linearized reading' (duration: 137.776818ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:58:20.469987Z","caller":"traceutil/trace.go:171","msg":"trace[1015724506] linearizableReadLoop","detail":"{readStateIndex:27898; appliedIndex:27897; }","duration":"242.893935ms","start":"2026-10-07T22:58:20.227068Z","end":"2026-10-07T22:58:20.469962Z","steps":["trace[1015724506] 'read index received' (duration: 38.018289ms)","trace[1015724506] 'applied index is now lower than readState.Index' (duration: 204.874606ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:58:20.470132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.05094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T22:58:20.470169Z","caller":"traceutil/trace.go:171","msg":"trace[58422551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27017; }","duration":"243.146842ms","start":"2026-10-07T22:58:20.227013Z","end":"2026-10-07T22:58:20.470159Z","steps":["trace[58422551] 'agreement among raft nodes before linearized reading' (duration: 243.04365ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:58:20.470321Z","caller":"traceutil/trace.go:171","msg":"trace[1793252573] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"252.866363ms","start":"2026-10-07T22:58:20.217389Z","end":"2026-10-07T22:58:20.470255Z","steps":["trace[1793252573] 'process raft request' (duration: 252.415841ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:58:20.73071Z","caller":"traceutil/trace.go:171","msg":"trace[603585525] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"142.385486ms","start":"2026-10-07T22:58:20.58829Z","end":"2026-10-07T22:58:20.730675Z","steps":["trace[603585525] 'process raft request' (duration: 107.164785ms)","trace[603585525] 'compare' (duration: 34.544572ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T22:58:20.730742Z","caller":"traceutil/trace.go:171","msg":"trace[1833950678] linearizableReadLoop","detail":"{readStateIndex:27900; appliedIndex:27899; }","duration":"142.116949ms","start":"2026-10-07T22:58:20.588316Z","end":"2026-10-07T22:58:20.730433Z","steps":["trace[1833950678] 'read index received' (duration: 75.483µs)","trace[1833950678] 'applied index is now lower than readState.Index' (duration: 142.039756ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T22:58:20.731811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.493527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-10-07T22:58:20.731966Z","caller":"traceutil/trace.go:171","msg":"trace[565508118] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:27019; }","duration":"143.670672ms","start":"2026-10-07T22:58:20.588225Z","end":"2026-10-07T22:58:20.731895Z","steps":["trace[565508118] 'agreement among raft nodes before linearized reading' (duration: 142.54648ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T22:59:40.448772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24944} {"level":"info","ts":"2026-10-07T22:59:40.608742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24944,"took":"155.826606ms","hash":3198481606,"current-db-size-bytes":56451072,"current-db-size":"56 MB","current-db-size-in-use-bytes":36057088,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-07T22:59:40.608818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3198481606,"revision":24944,"compact-revision":21817} {"level":"info","ts":"2026-10-07T23:01:41.937122Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a656331365382b34","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T23:01:41.944132Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-07T23:01:41.944235Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-07T23:01:51.739262Z","caller":"traceutil/trace.go:171","msg":"trace[601174788] transaction","detail":"{read_only:false; response_revision:29173; number_of_response:1; }","duration":"101.048198ms","start":"2026-10-07T23:01:51.638186Z","end":"2026-10-07T23:01:51.739234Z","steps":["trace[601174788] 'process raft request' (duration: 100.828322ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T23:01:51.765553Z","caller":"traceutil/trace.go:171","msg":"trace[295481191] transaction","detail":"{read_only:false; response_revision:29174; number_of_response:1; }","duration":"112.531318ms","start":"2026-10-07T23:01:51.652996Z","end":"2026-10-07T23:01:51.765527Z","steps":["trace[295481191] 'process raft request' (duration: 111.849849ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T23:04:40.456021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27731} {"level":"info","ts":"2026-10-07T23:04:40.589033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27731,"took":"128.300297ms","hash":3324903376,"current-db-size-bytes":56451072,"current-db-size":"56 MB","current-db-size-in-use-bytes":37027840,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-07T23:04:40.589106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3324903376,"revision":27731,"compact-revision":24944} {"level":"info","ts":"2026-10-07T23:09:40.464101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30436} {"level":"info","ts":"2026-10-07T23:09:40.60931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30436,"took":"141.212037ms","hash":3744501215,"current-db-size-bytes":56451072,"current-db-size":"56 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-07T23:09:40.609378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3744501215,"revision":30436,"compact-revision":27731} {"level":"info","ts":"2026-10-07T23:14:40.47246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32723} {"level":"info","ts":"2026-10-07T23:14:40.656771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32723,"took":"179.743512ms","hash":1168580287,"current-db-size-bytes":56451072,"current-db-size":"56 MB","current-db-size-in-use-bytes":30277632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T23:14:40.656846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1168580287,"revision":32723,"compact-revision":30436} {"level":"info","ts":"2026-10-07T23:19:40.480362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34950} {"level":"info","ts":"2026-10-07T23:19:40.61777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34950,"took":"133.326828ms","hash":724045905,"current-db-size-bytes":56451072,"current-db-size":"56 MB","current-db-size-in-use-bytes":31567872,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T23:19:40.61785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":724045905,"revision":34950,"compact-revision":32723} {"level":"info","ts":"2026-10-07T23:23:00.734569Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a656331365382b34","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T23:23:00.741227Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-07T23:23:00.74143Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-07T23:24:40.488564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37250} {"level":"info","ts":"2026-10-07T23:24:40.625395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37250,"took":"132.779161ms","hash":3694059329,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":32677888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-07T23:24:40.625471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3694059329,"revision":37250,"compact-revision":34950} {"level":"info","ts":"2026-10-07T23:29:40.493896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39528} {"level":"info","ts":"2026-10-07T23:29:40.622394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39528,"took":"124.979892ms","hash":3357452428,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":31195136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-07T23:29:40.62252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3357452428,"revision":39528,"compact-revision":37250}