{"level":"warn","ts":"2026-03-06T21:15:14.322972Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T21:15:14.32307Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-03-06T21:15:14.323153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T21:15:14.32317Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-03-06T21:15:14.3232Z","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-03-06T21:15:14.323895Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-03-06T21:15:14.323971Z","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://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-03-06T21:15:14.328443Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.237011ms"} {"level":"info","ts":"2026-03-06T21:15:14.335183Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-03-06T21:15:14.335266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T21:15:14.335292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-03-06T21:15:14.33531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T21:15:14.335323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-03-06T21:15:14.335364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-03-06T21:15:14.353184Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T21:15:14.355726Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T21:15:14.357052Z","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-03-06T21:15:14.359832Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T21:15:14.360409Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T21:15:14.360435Z","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-03-06T21:15:14.360563Z","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-03-06T21:15:14.360687Z","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-03-06T21:15:14.362165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-03-06T21:15:14.362674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-03-06T21:15:14.365317Z","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-03-06T21:15:14.365545Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-03-06T21:15:14.365604Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-03-06T21:15:14.365677Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T21:15:14.365732Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T21:15:14.836156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T21:15:14.836244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T21:15:14.836279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-03-06T21:15:14.836298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-03-06T21:15:14.836306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-03-06T21:15:14.83632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-03-06T21:15:14.836333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-03-06T21:15:14.83727Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:15:14.838753Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T21:15:14.838762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T21:15:14.838791Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:15:14.838849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T21:15:14.839223Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T21:15:14.839316Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T21:15:14.839473Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:15:14.839699Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:15:14.844894Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-03-06T21:15:14.852053Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T21:15:32.549381Z","caller":"traceutil/trace.go:171","msg":"trace[1195177538] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"113.919773ms","start":"2026-03-06T21:15:32.435422Z","end":"2026-03-06T21:15:32.549341Z","steps":["trace[1195177538] 'process raft request' (duration: 113.598466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:15:53.28912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.405915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-06T21:15:53.28919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.333035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-06T21:15:53.289233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.617241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:15:53.289284Z","caller":"traceutil/trace.go:171","msg":"trace[1501741500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"398.704682ms","start":"2026-03-06T21:15:52.890552Z","end":"2026-03-06T21:15:53.289256Z","steps":["trace[1501741500] 'range keys from in-memory index tree' (duration: 398.251762ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:15:53.289337Z","caller":"traceutil/trace.go:171","msg":"trace[367375210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"321.791254ms","start":"2026-03-06T21:15:52.96752Z","end":"2026-03-06T21:15:53.289312Z","steps":["trace[367375210] 'range keys from in-memory index tree' (duration: 321.391626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:15:53.289345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:15:52.890517Z","time spent":"398.816324ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T21:15:53.289385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:15:52.967483Z","time spent":"321.894517ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T21:15:53.289329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.556952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gqnzp\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-06T21:15:53.289169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.036896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:15:53.289508Z","caller":"traceutil/trace.go:171","msg":"trace[1135355509] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gqnzp; range_end:; response_count:1; response_revision:552; }","duration":"503.767286ms","start":"2026-03-06T21:15:52.785698Z","end":"2026-03-06T21:15:53.289465Z","steps":["trace[1135355509] 'range keys from in-memory index tree' (duration: 503.392458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:15:53.289607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:15:52.785643Z","time spent":"503.94731ms","remote":"127.0.0.1:45322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-gqnzp\" "} {"level":"info","ts":"2026-03-06T21:15:53.289635Z","caller":"traceutil/trace.go:171","msg":"trace[1298231637] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:552; }","duration":"216.565487ms","start":"2026-03-06T21:15:53.073008Z","end":"2026-03-06T21:15:53.289573Z","steps":["trace[1298231637] 'range keys from in-memory index tree' (duration: 215.922323ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:15:53.289395Z","caller":"traceutil/trace.go:171","msg":"trace[1958820896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"153.525699ms","start":"2026-03-06T21:15:53.135777Z","end":"2026-03-06T21:15:53.289303Z","steps":["trace[1958820896] 'range keys from in-memory index tree' (duration: 153.215082ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:15:53.476311Z","caller":"traceutil/trace.go:171","msg":"trace[404163380] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"172.442697ms","start":"2026-03-06T21:15:53.303836Z","end":"2026-03-06T21:15:53.476278Z","steps":["trace[404163380] 'process raft request' (duration: 172.247623ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:15:53.47673Z","caller":"traceutil/trace.go:171","msg":"trace[2101264562] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"179.844946ms","start":"2026-03-06T21:15:53.296514Z","end":"2026-03-06T21:15:53.476358Z","steps":["trace[2101264562] 'process raft request' (duration: 179.360105ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:15:55.038353Z","caller":"traceutil/trace.go:171","msg":"trace[2138620421] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"119.188058ms","start":"2026-03-06T21:15:54.919121Z","end":"2026-03-06T21:15:55.038309Z","steps":["trace[2138620421] 'process raft request' (duration: 106.505615ms)","trace[2138620421] 'compare' (duration: 12.50595ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:15:55.039373Z","caller":"traceutil/trace.go:171","msg":"trace[1700821946] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"115.247734ms","start":"2026-03-06T21:15:54.92408Z","end":"2026-03-06T21:15:55.039327Z","steps":["trace[1700821946] 'process raft request' (duration: 114.650511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:08.79191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.760978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T21:17:08.792084Z","caller":"traceutil/trace.go:171","msg":"trace[301115738] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1620; }","duration":"214.956652ms","start":"2026-03-06T21:17:08.577101Z","end":"2026-03-06T21:17:08.792057Z","steps":["trace[301115738] 'count revisions from in-memory index tree' (duration: 214.554532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:08.792137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.486523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-06T21:17:08.792121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.137663ms","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-03-06T21:17:08.792202Z","caller":"traceutil/trace.go:171","msg":"trace[1761026362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1620; }","duration":"167.569804ms","start":"2026-03-06T21:17:08.624614Z","end":"2026-03-06T21:17:08.792184Z","steps":["trace[1761026362] 'range keys from in-memory index tree' (duration: 167.301209ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:08.792222Z","caller":"traceutil/trace.go:171","msg":"trace[123632921] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1620; }","duration":"255.307047ms","start":"2026-03-06T21:17:08.536892Z","end":"2026-03-06T21:17:08.792199Z","steps":["trace[123632921] 'range keys from in-memory index tree' (duration: 254.900958ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:08.797897Z","caller":"traceutil/trace.go:171","msg":"trace[854178976] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"156.63542ms","start":"2026-03-06T21:17:08.641237Z","end":"2026-03-06T21:17:08.797872Z","steps":["trace[854178976] 'process raft request' (duration: 156.380895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:09.200937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.56504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.189a5d270fd64b5f\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-03-06T21:17:09.201045Z","caller":"traceutil/trace.go:171","msg":"trace[1003321961] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.189a5d270fd64b5f; range_end:; response_count:1; response_revision:1623; }","duration":"143.714022ms","start":"2026-03-06T21:17:09.057306Z","end":"2026-03-06T21:17:09.20102Z","steps":["trace[1003321961] 'range keys from in-memory index tree' (duration: 143.242752ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:09.201777Z","caller":"traceutil/trace.go:171","msg":"trace[539605808] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"114.872164ms","start":"2026-03-06T21:17:09.086883Z","end":"2026-03-06T21:17:09.201755Z","steps":["trace[539605808] 'process raft request' (duration: 102.831046ms)","trace[539605808] 'compare' (duration: 11.633059ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:17:20.995749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.513526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T21:17:20.995914Z","caller":"traceutil/trace.go:171","msg":"trace[756646344] linearizableReadLoop","detail":"{readStateIndex:1786; appliedIndex:1785; }","duration":"114.852693ms","start":"2026-03-06T21:17:20.881036Z","end":"2026-03-06T21:17:20.995889Z","steps":["trace[756646344] 'read index received' (duration: 13.336645ms)","trace[756646344] 'applied index is now lower than readState.Index' (duration: 101.514158ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:17:20.995959Z","caller":"traceutil/trace.go:171","msg":"trace[321048896] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"132.81247ms","start":"2026-03-06T21:17:20.863104Z","end":"2026-03-06T21:17:20.995916Z","steps":["trace[321048896] 'process raft request' (duration: 31.207889ms)","trace[321048896] 'compare' (duration: 100.274412ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:17:20.996297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.200391ms","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-03-06T21:17:20.996388Z","caller":"traceutil/trace.go:171","msg":"trace[1670784693] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1718; }","duration":"115.375054ms","start":"2026-03-06T21:17:20.880988Z","end":"2026-03-06T21:17:20.996363Z","steps":["trace[1670784693] 'agreement among raft nodes before linearized reading' (duration: 115.046047ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:24.564648Z","caller":"traceutil/trace.go:171","msg":"trace[1428689887] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"159.414619ms","start":"2026-03-06T21:17:24.405203Z","end":"2026-03-06T21:17:24.564617Z","steps":["trace[1428689887] 'process raft request' (duration: 159.267005ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:18:03.820078Z","caller":"traceutil/trace.go:171","msg":"trace[935920346] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"135.845935ms","start":"2026-03-06T21:18:03.684187Z","end":"2026-03-06T21:18:03.820033Z","steps":["trace[935920346] 'process raft request' (duration: 135.498798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:18:03.990332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.222279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T21:18:03.990716Z","caller":"traceutil/trace.go:171","msg":"trace[488031613] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"165.203131ms","start":"2026-03-06T21:18:03.825378Z","end":"2026-03-06T21:18:03.990581Z","steps":["trace[488031613] 'process raft request' (duration: 39.599974ms)","trace[488031613] 'compare' (duration: 125.057586ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:18:04.247934Z","caller":"traceutil/trace.go:171","msg":"trace[1289579416] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"184.521243ms","start":"2026-03-06T21:18:04.063351Z","end":"2026-03-06T21:18:04.247872Z","steps":["trace[1289579416] 'process raft request' (duration: 88.456896ms)","trace[1289579416] 'compare' (duration: 95.815982ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:18:37.65846Z","caller":"traceutil/trace.go:171","msg":"trace[178938534] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"118.030856ms","start":"2026-03-06T21:18:37.540408Z","end":"2026-03-06T21:18:37.658439Z","steps":["trace[178938534] 'process raft request' (duration: 117.899593ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:11.539528Z","caller":"traceutil/trace.go:171","msg":"trace[922439096] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"122.50122ms","start":"2026-03-06T21:19:11.416992Z","end":"2026-03-06T21:19:11.539493Z","steps":["trace[922439096] 'process raft request' (duration: 122.291236ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:11.695601Z","caller":"traceutil/trace.go:171","msg":"trace[821057936] transaction","detail":"{read_only:false; response_revision:2474; number_of_response:1; }","duration":"149.625618ms","start":"2026-03-06T21:19:11.545937Z","end":"2026-03-06T21:19:11.695563Z","steps":["trace[821057936] 'process raft request' (duration: 122.299616ms)","trace[821057936] 'compare' (duration: 27.054736ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:19:11.695797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.984397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T21:19:11.695869Z","caller":"traceutil/trace.go:171","msg":"trace[1763485879] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2474; }","duration":"143.114469ms","start":"2026-03-06T21:19:11.552736Z","end":"2026-03-06T21:19:11.69585Z","steps":["trace[1763485879] 'agreement among raft nodes before linearized reading' (duration: 142.948636ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:11.695601Z","caller":"traceutil/trace.go:171","msg":"trace[1552116963] linearizableReadLoop","detail":"{readStateIndex:2574; appliedIndex:2573; }","duration":"142.787343ms","start":"2026-03-06T21:19:11.55276Z","end":"2026-03-06T21:19:11.695547Z","steps":["trace[1552116963] 'read index received' (duration: 115.408369ms)","trace[1552116963] 'applied index is now lower than readState.Index' (duration: 27.377184ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:19:11.696419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.951589ms","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-03-06T21:19:11.696486Z","caller":"traceutil/trace.go:171","msg":"trace[532315930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2474; }","duration":"130.04495ms","start":"2026-03-06T21:19:11.566424Z","end":"2026-03-06T21:19:11.696469Z","steps":["trace[532315930] 'agreement among raft nodes before linearized reading' (duration: 129.807145ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:11.890827Z","caller":"traceutil/trace.go:171","msg":"trace[1013988389] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"189.930916ms","start":"2026-03-06T21:19:11.700846Z","end":"2026-03-06T21:19:11.890777Z","steps":["trace[1013988389] 'process raft request' (duration: 116.251596ms)","trace[1013988389] 'compare' (duration: 73.325002ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:19:11.891089Z","caller":"traceutil/trace.go:171","msg":"trace[1394241307] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"107.597253ms","start":"2026-03-06T21:19:11.783434Z","end":"2026-03-06T21:19:11.891032Z","steps":["trace[1394241307] 'process raft request' (duration: 107.265466ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:21:47.592393Z","caller":"traceutil/trace.go:171","msg":"trace[1648295226] transaction","detail":"{read_only:false; response_revision:3416; number_of_response:1; }","duration":"151.346136ms","start":"2026-03-06T21:21:47.441017Z","end":"2026-03-06T21:21:47.592363Z","steps":["trace[1648295226] 'process raft request' (duration: 133.652741ms)","trace[1648295226] 'compare' (duration: 17.539002ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:21:47.59258Z","caller":"traceutil/trace.go:171","msg":"trace[582620253] transaction","detail":"{read_only:false; response_revision:3417; number_of_response:1; }","duration":"150.045668ms","start":"2026-03-06T21:21:47.442504Z","end":"2026-03-06T21:21:47.59255Z","steps":["trace[582620253] 'process raft request' (duration: 149.823483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:23:17.173585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.138585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413082"} {"level":"info","ts":"2026-03-06T21:23:17.173683Z","caller":"traceutil/trace.go:171","msg":"trace[957659224] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:4580; }","duration":"215.308528ms","start":"2026-03-06T21:23:16.958355Z","end":"2026-03-06T21:23:17.173663Z","steps":["trace[957659224] 'range keys from in-memory index tree' (duration: 214.643095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:23:17.174078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.845552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:23:17.174176Z","caller":"traceutil/trace.go:171","msg":"trace[781114354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4580; }","duration":"208.016215ms","start":"2026-03-06T21:23:16.966138Z","end":"2026-03-06T21:23:17.174154Z","steps":["trace[781114354] 'range keys from in-memory index tree' (duration: 207.678079ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:17.174316Z","caller":"traceutil/trace.go:171","msg":"trace[1546968916] linearizableReadLoop","detail":"{readStateIndex:4766; appliedIndex:4765; }","duration":"109.015793ms","start":"2026-03-06T21:23:17.065271Z","end":"2026-03-06T21:23:17.174287Z","steps":["trace[1546968916] 'read index received' (duration: 104.10943ms)","trace[1546968916] 'applied index is now lower than readState.Index' (duration: 4.902073ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:23:17.174405Z","caller":"traceutil/trace.go:171","msg":"trace[506922237] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"207.400463ms","start":"2026-03-06T21:23:16.966993Z","end":"2026-03-06T21:23:17.174393Z","steps":["trace[506922237] 'process raft request' (duration: 202.324827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:23:17.174811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.520003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9dbdc8c7-1561-4d6d-8761-78be54fd1466\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-06T21:23:17.174862Z","caller":"traceutil/trace.go:171","msg":"trace[1296040008] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9dbdc8c7-1561-4d6d-8761-78be54fd1466; range_end:; response_count:1; response_revision:4581; }","duration":"109.631256ms","start":"2026-03-06T21:23:17.065219Z","end":"2026-03-06T21:23:17.17485Z","steps":["trace[1296040008] 'agreement among raft nodes before linearized reading' (duration: 109.190676ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:27.631798Z","caller":"traceutil/trace.go:171","msg":"trace[1755123664] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"102.049001ms","start":"2026-03-06T21:23:27.529726Z","end":"2026-03-06T21:23:27.631775Z","steps":["trace[1755123664] 'process raft request' (duration: 101.963929ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:27.631927Z","caller":"traceutil/trace.go:171","msg":"trace[1849609408] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"102.188693ms","start":"2026-03-06T21:23:27.529711Z","end":"2026-03-06T21:23:27.6319Z","steps":["trace[1849609408] 'process raft request' (duration: 101.822656ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:27.78833Z","caller":"traceutil/trace.go:171","msg":"trace[1119173096] linearizableReadLoop","detail":"{readStateIndex:4979; appliedIndex:4977; }","duration":"121.853241ms","start":"2026-03-06T21:23:27.666459Z","end":"2026-03-06T21:23:27.788312Z","steps":["trace[1119173096] 'read index received' (duration: 121.626586ms)","trace[1119173096] 'applied index is now lower than readState.Index' (duration: 225.835µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:23:27.788555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.075625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-06T21:23:27.78879Z","caller":"traceutil/trace.go:171","msg":"trace[723820805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4788; }","duration":"122.354301ms","start":"2026-03-06T21:23:27.666426Z","end":"2026-03-06T21:23:27.78878Z","steps":["trace[723820805] 'agreement among raft nodes before linearized reading' (duration: 121.971433ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:27.788655Z","caller":"traceutil/trace.go:171","msg":"trace[1626247530] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"253.868706ms","start":"2026-03-06T21:23:27.534763Z","end":"2026-03-06T21:23:27.788632Z","steps":["trace[1626247530] 'process raft request' (duration: 253.342325ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:27.788711Z","caller":"traceutil/trace.go:171","msg":"trace[691255645] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"252.564818ms","start":"2026-03-06T21:23:27.536137Z","end":"2026-03-06T21:23:27.788702Z","steps":["trace[691255645] 'process raft request' (duration: 252.106028ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:36.049859Z","caller":"traceutil/trace.go:171","msg":"trace[1320387814] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5144; }","duration":"186.784996ms","start":"2026-03-06T21:23:35.863044Z","end":"2026-03-06T21:23:36.049829Z","steps":["trace[1320387814] 'read index received' (duration: 186.592052ms)","trace[1320387814] 'applied index is now lower than readState.Index' (duration: 191.074µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:23:36.049982Z","caller":"traceutil/trace.go:171","msg":"trace[2113338734] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"187.594923ms","start":"2026-03-06T21:23:35.862345Z","end":"2026-03-06T21:23:36.04994Z","steps":["trace[2113338734] 'process raft request' (duration: 187.296247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:23:36.050318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.242895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-06T21:23:36.050661Z","caller":"traceutil/trace.go:171","msg":"trace[863304535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4948; }","duration":"187.616743ms","start":"2026-03-06T21:23:35.863005Z","end":"2026-03-06T21:23:36.050622Z","steps":["trace[863304535] 'agreement among raft nodes before linearized reading' (duration: 187.081022ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:36.31227Z","caller":"traceutil/trace.go:171","msg":"trace[1114201805] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"261.839585ms","start":"2026-03-06T21:23:36.0504Z","end":"2026-03-06T21:23:36.312239Z","steps":["trace[1114201805] 'read index received' (duration: 248.543103ms)","trace[1114201805] 'applied index is now lower than readState.Index' (duration: 13.295572ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:23:36.312381Z","caller":"traceutil/trace.go:171","msg":"trace[1730964049] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"385.230758ms","start":"2026-03-06T21:23:35.92709Z","end":"2026-03-06T21:23:36.312321Z","steps":["trace[1730964049] 'process raft request' (duration: 371.828174ms)","trace[1730964049] 'compare' (duration: 13.19631ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:23:36.31257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.653837ms","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-03-06T21:23:36.312662Z","caller":"traceutil/trace.go:171","msg":"trace[1915979126] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4949; }","duration":"318.843982ms","start":"2026-03-06T21:23:35.993804Z","end":"2026-03-06T21:23:36.312648Z","steps":["trace[1915979126] 'agreement among raft nodes before linearized reading' (duration: 318.594447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:23:36.312776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:23:35.993797Z","time spent":"318.908393ms","remote":"127.0.0.1:45480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-06T21:23:36.312676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:23:35.927031Z","time spent":"385.472583ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T21:23:36.312774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.445536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:23:36.312919Z","caller":"traceutil/trace.go:171","msg":"trace[1922608401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4949; }","duration":"345.66346ms","start":"2026-03-06T21:23:35.967223Z","end":"2026-03-06T21:23:36.312887Z","steps":["trace[1922608401] 'agreement among raft nodes before linearized reading' (duration: 345.336323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:23:36.312997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:23:35.967209Z","time spent":"345.770473ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T21:23:36.31891Z","caller":"traceutil/trace.go:171","msg":"trace[461757122] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"263.378218ms","start":"2026-03-06T21:23:36.055491Z","end":"2026-03-06T21:23:36.31887Z","steps":["trace[461757122] 'process raft request' (duration: 263.185614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:23:36.318971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.294647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T21:23:36.319026Z","caller":"traceutil/trace.go:171","msg":"trace[536095327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4950; }","duration":"256.365968ms","start":"2026-03-06T21:23:36.062647Z","end":"2026-03-06T21:23:36.319013Z","steps":["trace[536095327] 'agreement among raft nodes before linearized reading' (duration: 256.218495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:23:36.31921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.810027ms","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-03-06T21:23:36.319323Z","caller":"traceutil/trace.go:171","msg":"trace[1426952007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4950; }","duration":"154.014152ms","start":"2026-03-06T21:23:36.165284Z","end":"2026-03-06T21:23:36.319298Z","steps":["trace[1426952007] 'agreement among raft nodes before linearized reading' (duration: 153.578723ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:46.768683Z","caller":"traceutil/trace.go:171","msg":"trace[1217692661] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"116.239881ms","start":"2026-03-06T21:23:46.652399Z","end":"2026-03-06T21:23:46.768638Z","steps":["trace[1217692661] 'process raft request' (duration: 76.993ms)","trace[1217692661] 'compare' (duration: 38.934674ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:23:46.769042Z","caller":"traceutil/trace.go:171","msg":"trace[902909037] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"116.485466ms","start":"2026-03-06T21:23:46.652543Z","end":"2026-03-06T21:23:46.769029Z","steps":["trace[902909037] 'process raft request' (duration: 116.016046ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:46.769285Z","caller":"traceutil/trace.go:171","msg":"trace[1279221442] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"116.215501ms","start":"2026-03-06T21:23:46.653026Z","end":"2026-03-06T21:23:46.769241Z","steps":["trace[1279221442] 'process raft request' (duration: 115.911434ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:23:46.769922Z","caller":"traceutil/trace.go:171","msg":"trace[1173528547] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"116.716052ms","start":"2026-03-06T21:23:46.653163Z","end":"2026-03-06T21:23:46.769879Z","steps":["trace[1173528547] 'process raft request' (duration: 116.139199ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:25:14.877915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2784} {"level":"info","ts":"2026-03-06T21:25:14.941648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2784,"took":"61.735727ms","hash":640030515,"current-db-size-bytes":41680896,"current-db-size":"42 MB","current-db-size-in-use-bytes":31338496,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-06T21:25:14.941704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":640030515,"revision":2784,"compact-revision":-1} {"level":"info","ts":"2026-03-06T21:29:41.637016Z","caller":"traceutil/trace.go:171","msg":"trace[64275320] transaction","detail":"{read_only:false; response_revision:7601; number_of_response:1; }","duration":"275.705071ms","start":"2026-03-06T21:29:41.361279Z","end":"2026-03-06T21:29:41.636984Z","steps":["trace[64275320] 'process raft request' (duration: 275.514677ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:29:54.304627Z","caller":"traceutil/trace.go:171","msg":"trace[453330377] linearizableReadLoop","detail":"{readStateIndex:7948; appliedIndex:7947; }","duration":"152.22437ms","start":"2026-03-06T21:29:54.152369Z","end":"2026-03-06T21:29:54.304593Z","steps":["trace[453330377] 'read index received' (duration: 151.978605ms)","trace[453330377] 'applied index is now lower than readState.Index' (duration: 243.885µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:29:54.304737Z","caller":"traceutil/trace.go:171","msg":"trace[1352330843] transaction","detail":"{read_only:false; response_revision:7659; number_of_response:1; }","duration":"159.215429ms","start":"2026-03-06T21:29:54.145478Z","end":"2026-03-06T21:29:54.304693Z","steps":["trace[1352330843] 'process raft request' (duration: 158.899942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:29:54.305005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.628278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-06T21:29:54.305067Z","caller":"traceutil/trace.go:171","msg":"trace[1804165045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7659; }","duration":"152.701279ms","start":"2026-03-06T21:29:54.152349Z","end":"2026-03-06T21:29:54.305051Z","steps":["trace[1804165045] 'agreement among raft nodes before linearized reading' (duration: 152.530656ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:30:14.884871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5794} {"level":"info","ts":"2026-03-06T21:30:14.981024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5794,"took":"93.827328ms","hash":2464386659,"current-db-size-bytes":42397696,"current-db-size":"42 MB","current-db-size-in-use-bytes":23003136,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-06T21:30:14.981093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2464386659,"revision":5794,"compact-revision":2784} {"level":"info","ts":"2026-03-06T21:30:22.562995Z","caller":"traceutil/trace.go:171","msg":"trace[57324431] transaction","detail":"{read_only:false; response_revision:7851; number_of_response:1; }","duration":"143.366361ms","start":"2026-03-06T21:30:22.419602Z","end":"2026-03-06T21:30:22.562968Z","steps":["trace[57324431] 'process raft request' (duration: 142.938952ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:30:22.778255Z","caller":"traceutil/trace.go:171","msg":"trace[462016228] transaction","detail":"{read_only:false; response_revision:7852; number_of_response:1; }","duration":"210.725065ms","start":"2026-03-06T21:30:22.567491Z","end":"2026-03-06T21:30:22.778216Z","steps":["trace[462016228] 'process raft request' (duration: 183.204419ms)","trace[462016228] 'compare' (duration: 27.342232ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:30:22.779018Z","caller":"traceutil/trace.go:171","msg":"trace[641930200] transaction","detail":"{read_only:false; response_revision:7854; number_of_response:1; }","duration":"210.48583ms","start":"2026-03-06T21:30:22.568499Z","end":"2026-03-06T21:30:22.778985Z","steps":["trace[641930200] 'process raft request' (duration: 210.374458ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:30:22.779094Z","caller":"traceutil/trace.go:171","msg":"trace[819617080] transaction","detail":"{read_only:false; response_revision:7853; number_of_response:1; }","duration":"210.706804ms","start":"2026-03-06T21:30:22.568372Z","end":"2026-03-06T21:30:22.779079Z","steps":["trace[819617080] 'process raft request' (duration: 210.366587ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:30:22.779267Z","caller":"traceutil/trace.go:171","msg":"trace[1285721479] linearizableReadLoop","detail":"{readStateIndex:8153; appliedIndex:8149; }","duration":"131.822336ms","start":"2026-03-06T21:30:22.647413Z","end":"2026-03-06T21:30:22.779236Z","steps":["trace[1285721479] 'read index received' (duration: 103.296238ms)","trace[1285721479] 'applied index is now lower than readState.Index' (duration: 28.477016ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:30:22.779334Z","caller":"traceutil/trace.go:171","msg":"trace[1058764843] transaction","detail":"{read_only:false; response_revision:7855; number_of_response:1; }","duration":"209.234104ms","start":"2026-03-06T21:30:22.570087Z","end":"2026-03-06T21:30:22.779321Z","steps":["trace[1058764843] 'process raft request' (duration: 208.851985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:30:22.779422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.98836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T21:30:22.779471Z","caller":"traceutil/trace.go:171","msg":"trace[1470674967] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:7855; }","duration":"132.087382ms","start":"2026-03-06T21:30:22.64737Z","end":"2026-03-06T21:30:22.779458Z","steps":["trace[1470674967] 'agreement among raft nodes before linearized reading' (duration: 131.97935ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:30:35.326434Z","caller":"traceutil/trace.go:171","msg":"trace[1770919866] linearizableReadLoop","detail":"{readStateIndex:8260; appliedIndex:8259; }","duration":"118.665675ms","start":"2026-03-06T21:30:35.20774Z","end":"2026-03-06T21:30:35.326406Z","steps":["trace[1770919866] 'read index received' (duration: 55.181µs)","trace[1770919866] 'applied index is now lower than readState.Index' (duration: 118.609094ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:30:35.326566Z","caller":"traceutil/trace.go:171","msg":"trace[1780902580] transaction","detail":"{read_only:false; response_revision:7957; number_of_response:1; }","duration":"118.825518ms","start":"2026-03-06T21:30:35.207703Z","end":"2026-03-06T21:30:35.326529Z","steps":["trace[1780902580] 'process raft request' (duration: 118.498341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:30:35.326804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.035864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-8sr7p\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-03-06T21:30:35.326853Z","caller":"traceutil/trace.go:171","msg":"trace[1946118735] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-8sr7p; range_end:; response_count:1; response_revision:7957; }","duration":"119.160187ms","start":"2026-03-06T21:30:35.207679Z","end":"2026-03-06T21:30:35.32684Z","steps":["trace[1946118735] 'agreement among raft nodes before linearized reading' (duration: 118.904671ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:30:35.547183Z","caller":"traceutil/trace.go:171","msg":"trace[1046081986] transaction","detail":"{read_only:false; response_revision:7958; number_of_response:1; }","duration":"211.253367ms","start":"2026-03-06T21:30:35.335897Z","end":"2026-03-06T21:30:35.547151Z","steps":["trace[1046081986] 'process raft request' (duration: 211.006541ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:30:36.258761Z","caller":"traceutil/trace.go:171","msg":"trace[1601602134] transaction","detail":"{read_only:false; response_revision:7964; number_of_response:1; }","duration":"124.626792ms","start":"2026-03-06T21:30:36.134102Z","end":"2026-03-06T21:30:36.258729Z","steps":["trace[1601602134] 'process raft request' (duration: 124.330286ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:31:41.140638Z","caller":"traceutil/trace.go:171","msg":"trace[2093914393] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"118.300838ms","start":"2026-03-06T21:31:41.022298Z","end":"2026-03-06T21:31:41.140599Z","steps":["trace[2093914393] 'process raft request' (duration: 118.058533ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:34:09.723249Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"344940fc142d902f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-06T21:34:09.80091Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-06T21:34:09.80106Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-06T21:35:14.890786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7768} {"level":"info","ts":"2026-03-06T21:35:14.967032Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7768,"took":"73.467198ms","hash":3486913884,"current-db-size-bytes":42397696,"current-db-size":"42 MB","current-db-size-in-use-bytes":28012544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-06T21:35:14.967083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3486913884,"revision":7768,"compact-revision":5794} {"level":"info","ts":"2026-03-06T21:35:38.276509Z","caller":"traceutil/trace.go:171","msg":"trace[666201023] transaction","detail":"{read_only:false; response_revision:10423; number_of_response:1; }","duration":"214.118441ms","start":"2026-03-06T21:35:38.062359Z","end":"2026-03-06T21:35:38.276478Z","steps":["trace[666201023] 'process raft request' (duration: 211.891403ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:35:38.27659Z","caller":"traceutil/trace.go:171","msg":"trace[245699766] transaction","detail":"{read_only:false; response_revision:10424; number_of_response:1; }","duration":"178.836492ms","start":"2026-03-06T21:35:38.097713Z","end":"2026-03-06T21:35:38.276549Z","steps":["trace[245699766] 'process raft request' (duration: 178.599417ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:35:38.289217Z","caller":"traceutil/trace.go:171","msg":"trace[854716447] linearizableReadLoop","detail":"{readStateIndex:10805; appliedIndex:10802; }","duration":"116.189854ms","start":"2026-03-06T21:35:38.17301Z","end":"2026-03-06T21:35:38.2892Z","steps":["trace[854716447] 'read index received' (duration: 101.319158ms)","trace[854716447] 'applied index is now lower than readState.Index' (duration: 14.866986ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:35:38.28926Z","caller":"traceutil/trace.go:171","msg":"trace[1957800350] transaction","detail":"{read_only:false; response_revision:10425; number_of_response:1; }","duration":"162.828684ms","start":"2026-03-06T21:35:38.12641Z","end":"2026-03-06T21:35:38.289238Z","steps":["trace[1957800350] 'process raft request' (duration: 162.667101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:35:38.289415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.395919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T21:35:38.289462Z","caller":"traceutil/trace.go:171","msg":"trace[246029485] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:10425; }","duration":"116.506761ms","start":"2026-03-06T21:35:38.172943Z","end":"2026-03-06T21:35:38.289449Z","steps":["trace[246029485] 'agreement among raft nodes before linearized reading' (duration: 116.331888ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:35:38.753923Z","caller":"traceutil/trace.go:171","msg":"trace[3324483] transaction","detail":"{read_only:false; response_revision:10427; number_of_response:1; }","duration":"162.876844ms","start":"2026-03-06T21:35:38.591014Z","end":"2026-03-06T21:35:38.753891Z","steps":["trace[3324483] 'process raft request' (duration: 138.460066ms)","trace[3324483] 'compare' (duration: 24.239344ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:35:38.754203Z","caller":"traceutil/trace.go:171","msg":"trace[348422292] linearizableReadLoop","detail":"{readStateIndex:10808; appliedIndex:10805; }","duration":"148.52084ms","start":"2026-03-06T21:35:38.605623Z","end":"2026-03-06T21:35:38.754144Z","steps":["trace[348422292] 'read index received' (duration: 82.04207ms)","trace[348422292] 'applied index is now lower than readState.Index' (duration: 66.47618ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:35:38.754299Z","caller":"traceutil/trace.go:171","msg":"trace[1197961229] transaction","detail":"{read_only:false; response_revision:10428; number_of_response:1; }","duration":"163.192641ms","start":"2026-03-06T21:35:38.591015Z","end":"2026-03-06T21:35:38.754208Z","steps":["trace[1197961229] 'process raft request' (duration: 162.828043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:35:38.754549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.844107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-06T21:35:38.754607Z","caller":"traceutil/trace.go:171","msg":"trace[1822160729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10428; }","duration":"149.0045ms","start":"2026-03-06T21:35:38.605582Z","end":"2026-03-06T21:35:38.754587Z","steps":["trace[1822160729] 'agreement among raft nodes before linearized reading' (duration: 148.678314ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:40:14.89739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10308} {"level":"info","ts":"2026-03-06T21:40:14.978767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10308,"took":"78.485835ms","hash":4227250538,"current-db-size-bytes":42397696,"current-db-size":"42 MB","current-db-size-in-use-bytes":25030656,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-06T21:40:14.978842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4227250538,"revision":10308,"compact-revision":7768} {"level":"info","ts":"2026-03-06T21:41:17.341832Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000032cd.wal"} {"level":"info","ts":"2026-03-06T21:41:57.833739Z","caller":"traceutil/trace.go:171","msg":"trace[504144811] linearizableReadLoop","detail":"{readStateIndex:13253; appliedIndex:13252; }","duration":"147.275745ms","start":"2026-03-06T21:41:57.686439Z","end":"2026-03-06T21:41:57.833715Z","steps":["trace[504144811] 'read index received' (duration: 147.106532ms)","trace[504144811] 'applied index is now lower than readState.Index' (duration: 167.953µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:41:57.833882Z","caller":"traceutil/trace.go:171","msg":"trace[1223414485] transaction","detail":"{read_only:false; response_revision:12784; number_of_response:1; }","duration":"151.896902ms","start":"2026-03-06T21:41:57.681971Z","end":"2026-03-06T21:41:57.833868Z","steps":["trace[1223414485] 'process raft request' (duration: 151.580446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:41:57.834094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.615372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T21:41:57.834205Z","caller":"traceutil/trace.go:171","msg":"trace[630940049] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:12784; }","duration":"147.770935ms","start":"2026-03-06T21:41:57.686408Z","end":"2026-03-06T21:41:57.834179Z","steps":["trace[630940049] 'agreement among raft nodes before linearized reading' (duration: 147.595641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:41:57.834153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.036476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-06T21:41:57.834587Z","caller":"traceutil/trace.go:171","msg":"trace[484548905] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:12784; }","duration":"128.505987ms","start":"2026-03-06T21:41:57.706058Z","end":"2026-03-06T21:41:57.834564Z","steps":["trace[484548905] 'agreement among raft nodes before linearized reading' (duration: 127.907894ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:42:23.511711Z","caller":"traceutil/trace.go:171","msg":"trace[423196841] linearizableReadLoop","detail":"{readStateIndex:13514; appliedIndex:13513; }","duration":"111.759032ms","start":"2026-03-06T21:42:23.399925Z","end":"2026-03-06T21:42:23.511684Z","steps":["trace[423196841] 'read index received' (duration: 111.603239ms)","trace[423196841] 'applied index is now lower than readState.Index' (duration: 154.653µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:42:23.511756Z","caller":"traceutil/trace.go:171","msg":"trace[160726568] transaction","detail":"{read_only:false; response_revision:13037; number_of_response:1; }","duration":"120.249642ms","start":"2026-03-06T21:42:23.391467Z","end":"2026-03-06T21:42:23.511717Z","steps":["trace[160726568] 'process raft request' (duration: 120.054127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:42:23.512006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.106507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8913256b-aeed-4d69-93fc-1d2a228d2402\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-03-06T21:42:23.512055Z","caller":"traceutil/trace.go:171","msg":"trace[594928373] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8913256b-aeed-4d69-93fc-1d2a228d2402; range_end:; response_count:1; response_revision:13037; }","duration":"111.196998ms","start":"2026-03-06T21:42:23.400844Z","end":"2026-03-06T21:42:23.512041Z","steps":["trace[594928373] 'agreement among raft nodes before linearized reading' (duration: 111.010214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:42:23.512155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.178391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dc2e5f68-0ed9-4cd9-87a8-642f8046f989\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-03-06T21:42:23.512279Z","caller":"traceutil/trace.go:171","msg":"trace[686389904] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dc2e5f68-0ed9-4cd9-87a8-642f8046f989; range_end:; response_count:1; response_revision:13037; }","duration":"112.333414ms","start":"2026-03-06T21:42:23.399918Z","end":"2026-03-06T21:42:23.512252Z","steps":["trace[686389904] 'agreement among raft nodes before linearized reading' (duration: 111.880954ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:42:23.888214Z","caller":"traceutil/trace.go:171","msg":"trace[1754158004] linearizableReadLoop","detail":"{readStateIndex:13516; appliedIndex:13515; }","duration":"173.290927ms","start":"2026-03-06T21:42:23.7149Z","end":"2026-03-06T21:42:23.888191Z","steps":["trace[1754158004] 'read index received' (duration: 146.952397ms)","trace[1754158004] 'applied index is now lower than readState.Index' (duration: 26.33731ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:42:23.888354Z","caller":"traceutil/trace.go:171","msg":"trace[840840977] transaction","detail":"{read_only:false; response_revision:13039; number_of_response:1; }","duration":"254.847748ms","start":"2026-03-06T21:42:23.633485Z","end":"2026-03-06T21:42:23.888333Z","steps":["trace[840840977] 'process raft request' (duration: 228.361235ms)","trace[840840977] 'compare' (duration: 26.147406ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:42:23.888695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.783428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dc2e5f68-0ed9-4cd9-87a8-642f8046f989\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-03-06T21:42:23.888777Z","caller":"traceutil/trace.go:171","msg":"trace[1711062457] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dc2e5f68-0ed9-4cd9-87a8-642f8046f989; range_end:; response_count:1; response_revision:13039; }","duration":"173.937391ms","start":"2026-03-06T21:42:23.714819Z","end":"2026-03-06T21:42:23.888756Z","steps":["trace[1711062457] 'agreement among raft nodes before linearized reading' (duration: 173.605694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:42:23.888871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.709782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wqjhx\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-03-06T21:42:23.888903Z","caller":"traceutil/trace.go:171","msg":"trace[383979217] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wqjhx; range_end:; response_count:1; response_revision:13039; }","duration":"129.803553ms","start":"2026-03-06T21:42:23.759091Z","end":"2026-03-06T21:42:23.888895Z","steps":["trace[383979217] 'agreement among raft nodes before linearized reading' (duration: 129.678101ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:42:24.075603Z","caller":"traceutil/trace.go:171","msg":"trace[222657305] transaction","detail":"{read_only:false; response_revision:13040; number_of_response:1; }","duration":"178.131468ms","start":"2026-03-06T21:42:23.897432Z","end":"2026-03-06T21:42:24.075564Z","steps":["trace[222657305] 'process raft request' (duration: 177.899833ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:42:24.081032Z","caller":"traceutil/trace.go:171","msg":"trace[1716188755] linearizableReadLoop","detail":"{readStateIndex:13518; appliedIndex:13516; }","duration":"112.474557ms","start":"2026-03-06T21:42:23.968529Z","end":"2026-03-06T21:42:24.081004Z","steps":["trace[1716188755] 'read index received' (duration: 106.806006ms)","trace[1716188755] 'applied index is now lower than readState.Index' (duration: 5.666841ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:42:24.081106Z","caller":"traceutil/trace.go:171","msg":"trace[1891285194] transaction","detail":"{read_only:false; response_revision:13042; number_of_response:1; }","duration":"112.103848ms","start":"2026-03-06T21:42:23.968981Z","end":"2026-03-06T21:42:24.081084Z","steps":["trace[1891285194] 'process raft request' (duration: 111.974706ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:42:24.081084Z","caller":"traceutil/trace.go:171","msg":"trace[1719392577] transaction","detail":"{read_only:false; response_revision:13041; number_of_response:1; }","duration":"115.210004ms","start":"2026-03-06T21:42:23.965832Z","end":"2026-03-06T21:42:24.081042Z","steps":["trace[1719392577] 'process raft request' (duration: 114.966469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:42:24.081292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.747523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:42:24.081332Z","caller":"traceutil/trace.go:171","msg":"trace[1136336042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13042; }","duration":"112.851695ms","start":"2026-03-06T21:42:23.968468Z","end":"2026-03-06T21:42:24.08132Z","steps":["trace[1136336042] 'agreement among raft nodes before linearized reading' (duration: 112.66901ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:43:43.450422Z","caller":"traceutil/trace.go:171","msg":"trace[1207388812] transaction","detail":"{read_only:false; response_revision:13986; number_of_response:1; }","duration":"116.914701ms","start":"2026-03-06T21:43:43.333477Z","end":"2026-03-06T21:43:43.450392Z","steps":["trace[1207388812] 'process raft request' (duration: 116.716217ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:43:43.451343Z","caller":"traceutil/trace.go:171","msg":"trace[955910413] transaction","detail":"{read_only:false; response_revision:13987; number_of_response:1; }","duration":"115.446289ms","start":"2026-03-06T21:43:43.335857Z","end":"2026-03-06T21:43:43.451303Z","steps":["trace[955910413] 'process raft request' (duration: 115.267155ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:43:43.480909Z","caller":"traceutil/trace.go:171","msg":"trace[2059804798] linearizableReadLoop","detail":"{readStateIndex:14492; appliedIndex:14490; }","duration":"125.489493ms","start":"2026-03-06T21:43:43.355375Z","end":"2026-03-06T21:43:43.480864Z","steps":["trace[2059804798] 'read index received' (duration: 94.885143ms)","trace[2059804798] 'applied index is now lower than readState.Index' (duration: 30.60254ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:43:43.481328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.896921ms","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-03-06T21:43:43.481378Z","caller":"traceutil/trace.go:171","msg":"trace[1541248303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13987; }","duration":"126.048934ms","start":"2026-03-06T21:43:43.355314Z","end":"2026-03-06T21:43:43.481363Z","steps":["trace[1541248303] 'agreement among raft nodes before linearized reading' (duration: 125.749428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:43:43.481545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.216117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-03-06T21:43:43.48161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.141976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-06T21:43:43.481623Z","caller":"traceutil/trace.go:171","msg":"trace[452883117] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13987; }","duration":"125.351749ms","start":"2026-03-06T21:43:43.356253Z","end":"2026-03-06T21:43:43.481605Z","steps":["trace[452883117] 'agreement among raft nodes before linearized reading' (duration: 125.062773ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:43:43.481701Z","caller":"traceutil/trace.go:171","msg":"trace[1098683193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13987; }","duration":"118.272959ms","start":"2026-03-06T21:43:43.363405Z","end":"2026-03-06T21:43:43.481678Z","steps":["trace[1098683193] 'agreement among raft nodes before linearized reading' (duration: 118.016513ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:43:44.530994Z","caller":"traceutil/trace.go:171","msg":"trace[807586501] linearizableReadLoop","detail":"{readStateIndex:14496; appliedIndex:14495; }","duration":"143.869772ms","start":"2026-03-06T21:43:44.387089Z","end":"2026-03-06T21:43:44.530959Z","steps":["trace[807586501] 'read index received' (duration: 143.639667ms)","trace[807586501] 'applied index is now lower than readState.Index' (duration: 228.305µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:43:44.531105Z","caller":"traceutil/trace.go:171","msg":"trace[556170266] transaction","detail":"{read_only:false; response_revision:13990; number_of_response:1; }","duration":"145.474786ms","start":"2026-03-06T21:43:44.385586Z","end":"2026-03-06T21:43:44.531061Z","steps":["trace[556170266] 'process raft request' (duration: 145.134448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:43:44.531229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.097978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:43:44.531289Z","caller":"traceutil/trace.go:171","msg":"trace[590565210] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:13990; }","duration":"144.2136ms","start":"2026-03-06T21:43:44.387062Z","end":"2026-03-06T21:43:44.531276Z","steps":["trace[590565210] 'agreement among raft nodes before linearized reading' (duration: 144.079367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:43:44.563183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.778309ms","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-03-06T21:43:44.563838Z","caller":"traceutil/trace.go:171","msg":"trace[1633162081] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13991; }","duration":"118.488344ms","start":"2026-03-06T21:43:44.445322Z","end":"2026-03-06T21:43:44.56381Z","steps":["trace[1633162081] 'agreement among raft nodes before linearized reading' (duration: 117.766639ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:43:44.563198Z","caller":"traceutil/trace.go:171","msg":"trace[595285673] transaction","detail":"{read_only:false; response_revision:13991; number_of_response:1; }","duration":"149.489761ms","start":"2026-03-06T21:43:44.413671Z","end":"2026-03-06T21:43:44.563161Z","steps":["trace[595285673] 'process raft request' (duration: 149.139584ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:43:44.563233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.392749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-03-06T21:43:44.564466Z","caller":"traceutil/trace.go:171","msg":"trace[296964552] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13991; }","duration":"174.626515ms","start":"2026-03-06T21:43:44.389805Z","end":"2026-03-06T21:43:44.564431Z","steps":["trace[296964552] 'agreement among raft nodes before linearized reading' (duration: 173.227345ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:45:14.906072Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12191} {"level":"info","ts":"2026-03-06T21:45:14.985802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12191,"took":"76.846331ms","hash":2250374076,"current-db-size-bytes":42397696,"current-db-size":"42 MB","current-db-size-in-use-bytes":27467776,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-06T21:45:14.985862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2250374076,"revision":12191,"compact-revision":10308} {"level":"info","ts":"2026-03-06T21:50:14.914079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14514} {"level":"info","ts":"2026-03-06T21:50:15.013138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14514,"took":"95.328953ms","hash":4265327001,"current-db-size-bytes":42397696,"current-db-size":"42 MB","current-db-size-in-use-bytes":29720576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-06T21:50:15.013296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4265327001,"revision":14514,"compact-revision":12191} {"level":"info","ts":"2026-03-06T21:55:02.387668Z","caller":"traceutil/trace.go:171","msg":"trace[1719307764] transaction","detail":"{read_only:false; response_revision:18797; number_of_response:1; }","duration":"210.357912ms","start":"2026-03-06T21:55:02.177283Z","end":"2026-03-06T21:55:02.387641Z","steps":["trace[1719307764] 'process raft request' (duration: 210.202558ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:55:04.420504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.606955ms","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-03-06T21:55:04.420599Z","caller":"traceutil/trace.go:171","msg":"trace[1826007307] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:18805; }","duration":"167.759808ms","start":"2026-03-06T21:55:04.252816Z","end":"2026-03-06T21:55:04.420576Z","steps":["trace[1826007307] 'count revisions from in-memory index tree' (duration: 167.519803ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:55:04.866877Z","caller":"traceutil/trace.go:171","msg":"trace[294698659] transaction","detail":"{read_only:false; response_revision:18806; number_of_response:1; }","duration":"126.326559ms","start":"2026-03-06T21:55:04.740491Z","end":"2026-03-06T21:55:04.866818Z","steps":["trace[294698659] 'process raft request' (duration: 126.039893ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:55:14.925476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16928} {"level":"info","ts":"2026-03-06T21:55:15.047566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16928,"took":"117.437311ms","hash":1642842610,"current-db-size-bytes":44089344,"current-db-size":"44 MB","current-db-size-in-use-bytes":29941760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-06T21:55:15.047646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1642842610,"revision":16928,"compact-revision":14514} {"level":"info","ts":"2026-03-06T21:55:52.165748Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"344940fc142d902f","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-06T21:55:52.171242Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-03-06T21:55:52.171376Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-03-06T21:59:02.350768Z","caller":"traceutil/trace.go:171","msg":"trace[139398160] linearizableReadLoop","detail":"{readStateIndex:21852; appliedIndex:21851; }","duration":"160.918062ms","start":"2026-03-06T21:59:02.189828Z","end":"2026-03-06T21:59:02.350746Z","steps":["trace[139398160] 'read index received' (duration: 160.851491ms)","trace[139398160] 'applied index is now lower than readState.Index' (duration: 65.431µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:59:02.351094Z","caller":"traceutil/trace.go:171","msg":"trace[1047090836] transaction","detail":"{read_only:false; response_revision:21101; number_of_response:1; }","duration":"298.848438ms","start":"2026-03-06T21:59:02.052232Z","end":"2026-03-06T21:59:02.35108Z","steps":["trace[1047090836] 'process raft request' (duration: 298.319187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:59:02.351331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.481414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T21:59:02.351379Z","caller":"traceutil/trace.go:171","msg":"trace[1050263409] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:21101; }","duration":"161.587856ms","start":"2026-03-06T21:59:02.189779Z","end":"2026-03-06T21:59:02.351367Z","steps":["trace[1050263409] 'agreement among raft nodes before linearized reading' (duration: 161.489754ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:59:02.35194Z","caller":"traceutil/trace.go:171","msg":"trace[1028726859] transaction","detail":"{read_only:false; response_revision:21102; number_of_response:1; }","duration":"159.796499ms","start":"2026-03-06T21:59:02.192107Z","end":"2026-03-06T21:59:02.351903Z","steps":["trace[1028726859] 'process raft request' (duration: 159.503472ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:59:02.352123Z","caller":"traceutil/trace.go:171","msg":"trace[1815560320] transaction","detail":"{read_only:false; response_revision:21103; number_of_response:1; }","duration":"127.612988ms","start":"2026-03-06T21:59:02.224493Z","end":"2026-03-06T21:59:02.352106Z","steps":["trace[1815560320] 'process raft request' (duration: 127.342232ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:14.938538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18873} {"level":"info","ts":"2026-03-06T22:00:15.058268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18873,"took":"113.385475ms","hash":2366705483,"current-db-size-bytes":47661056,"current-db-size":"48 MB","current-db-size-in-use-bytes":37851136,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-03-06T22:00:15.058367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2366705483,"revision":18873,"compact-revision":16928} {"level":"info","ts":"2026-03-06T22:02:44.494373Z","caller":"traceutil/trace.go:171","msg":"trace[185651142] transaction","detail":"{read_only:false; response_revision:23279; number_of_response:1; }","duration":"213.72016ms","start":"2026-03-06T22:02:44.280628Z","end":"2026-03-06T22:02:44.494348Z","steps":["trace[185651142] 'process raft request' (duration: 213.558657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:44.837404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.571344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:02:44.837609Z","caller":"traceutil/trace.go:171","msg":"trace[136057174] transaction","detail":"{read_only:false; response_revision:23281; number_of_response:1; }","duration":"279.24189ms","start":"2026-03-06T22:02:44.558348Z","end":"2026-03-06T22:02:44.83759Z","steps":["trace[136057174] 'process raft request' (duration: 279.176418ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:44.837612Z","caller":"traceutil/trace.go:171","msg":"trace[1398101485] transaction","detail":"{read_only:false; response_revision:23280; number_of_response:1; }","duration":"470.091165ms","start":"2026-03-06T22:02:44.367487Z","end":"2026-03-06T22:02:44.837578Z","steps":["trace[1398101485] 'process raft request' (duration: 276.243267ms)","trace[1398101485] 'compare' (duration: 193.43381ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:44.837959Z","caller":"traceutil/trace.go:171","msg":"trace[1724997801] linearizableReadLoop","detail":"{readStateIndex:24089; appliedIndex:24088; }","duration":"340.042268ms","start":"2026-03-06T22:02:44.497906Z","end":"2026-03-06T22:02:44.837948Z","steps":["trace[1724997801] 'read index received' (duration: 145.839732ms)","trace[1724997801] 'applied index is now lower than readState.Index' (duration: 194.201326ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:44.837992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:44.367463Z","time spent":"470.33177ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:44.838221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.462614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-06T22:02:44.838302Z","caller":"traceutil/trace.go:171","msg":"trace[183730238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23281; }","duration":"212.586647ms","start":"2026-03-06T22:02:44.625692Z","end":"2026-03-06T22:02:44.838278Z","steps":["trace[183730238] 'agreement among raft nodes before linearized reading' (duration: 212.368512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:44.838326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.418486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-554677b4d6-nltgm.189a5fa4b117df16\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-03-06T22:02:44.838363Z","caller":"traceutil/trace.go:171","msg":"trace[1363464322] range","detail":"{range_begin:/registry/events/openstack/magnum-api-554677b4d6-nltgm.189a5fa4b117df16; range_end:; response_count:1; response_revision:23281; }","duration":"340.514708ms","start":"2026-03-06T22:02:44.497836Z","end":"2026-03-06T22:02:44.838351Z","steps":["trace[1363464322] 'agreement among raft nodes before linearized reading' (duration: 340.380895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:44.83835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.551566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:02:44.838486Z","caller":"traceutil/trace.go:171","msg":"trace[118942042] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:23281; }","duration":"162.76791ms","start":"2026-03-06T22:02:44.675666Z","end":"2026-03-06T22:02:44.838434Z","steps":["trace[118942042] 'agreement among raft nodes before linearized reading' (duration: 162.489974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:44.838392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:44.497798Z","time spent":"340.586299ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":805,"request content":"key:\"/registry/events/openstack/magnum-api-554677b4d6-nltgm.189a5fa4b117df16\" "} {"level":"warn","ts":"2026-03-06T22:02:45.131907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.672348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:02:45.132114Z","caller":"traceutil/trace.go:171","msg":"trace[498736643] transaction","detail":"{read_only:false; response_revision:23283; number_of_response:1; }","duration":"287.7124ms","start":"2026-03-06T22:02:44.844383Z","end":"2026-03-06T22:02:45.132096Z","steps":["trace[498736643] 'process raft request' (duration: 287.636428ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:45.132446Z","caller":"traceutil/trace.go:171","msg":"trace[1804350181] transaction","detail":"{read_only:false; response_revision:23282; number_of_response:1; }","duration":"290.531879ms","start":"2026-03-06T22:02:44.841899Z","end":"2026-03-06T22:02:45.132431Z","steps":["trace[1804350181] 'process raft request' (duration: 135.228447ms)","trace[1804350181] 'compare' (duration: 153.977884ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:45.741829Z","caller":"traceutil/trace.go:171","msg":"trace[1628601807] transaction","detail":"{read_only:false; response_revision:23284; number_of_response:1; }","duration":"434.038771ms","start":"2026-03-06T22:02:45.307763Z","end":"2026-03-06T22:02:45.741802Z","steps":["trace[1628601807] 'process raft request' (duration: 433.53646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:45.741992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.307743Z","time spent":"434.157723ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:45.95639Z","caller":"traceutil/trace.go:171","msg":"trace[605484711] transaction","detail":"{read_only:false; response_revision:23285; number_of_response:1; }","duration":"592.451199ms","start":"2026-03-06T22:02:45.363918Z","end":"2026-03-06T22:02:45.95637Z","steps":["trace[605484711] 'process raft request' (duration: 592.277005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:45.95655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.363909Z","time spent":"592.570181ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:45.956735Z","caller":"traceutil/trace.go:171","msg":"trace[641762965] transaction","detail":"{read_only:false; response_revision:23286; number_of_response:1; }","duration":"591.194452ms","start":"2026-03-06T22:02:45.365496Z","end":"2026-03-06T22:02:45.95669Z","steps":["trace[641762965] 'process raft request' (duration: 590.832865ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:45.956906Z","caller":"traceutil/trace.go:171","msg":"trace[1981445965] transaction","detail":"{read_only:false; response_revision:23288; number_of_response:1; }","duration":"503.35192ms","start":"2026-03-06T22:02:45.453545Z","end":"2026-03-06T22:02:45.956897Z","steps":["trace[1981445965] 'process raft request' (duration: 503.221817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:45.956926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.365471Z","time spent":"591.353145ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:45.956953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.453524Z","time spent":"503.40604ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:45.956909Z","caller":"traceutil/trace.go:171","msg":"trace[16890146] linearizableReadLoop","detail":"{readStateIndex:24098; appliedIndex:24093; }","duration":"201.677925ms","start":"2026-03-06T22:02:45.755201Z","end":"2026-03-06T22:02:45.956878Z","steps":["trace[16890146] 'read index received' (duration: 200.986741ms)","trace[16890146] 'applied index is now lower than readState.Index' (duration: 689.874µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:45.957028Z","caller":"traceutil/trace.go:171","msg":"trace[148694170] transaction","detail":"{read_only:false; response_revision:23287; number_of_response:1; }","duration":"506.994457ms","start":"2026-03-06T22:02:45.450026Z","end":"2026-03-06T22:02:45.957021Z","steps":["trace[148694170] 'process raft request' (duration: 506.578148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:45.957066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.450016Z","time spent":"507.030778ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:45.957085Z","caller":"traceutil/trace.go:171","msg":"trace[1708503342] transaction","detail":"{read_only:false; response_revision:23289; number_of_response:1; }","duration":"443.830078ms","start":"2026-03-06T22:02:45.513236Z","end":"2026-03-06T22:02:45.957066Z","steps":["trace[1708503342] 'process raft request' (duration: 443.586052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:45.957195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.884628ms","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-03-06T22:02:45.957201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.085249ms","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-03-06T22:02:45.957218Z","caller":"traceutil/trace.go:171","msg":"trace[467201403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23289; }","duration":"182.923808ms","start":"2026-03-06T22:02:45.774285Z","end":"2026-03-06T22:02:45.957208Z","steps":["trace[467201403] 'agreement among raft nodes before linearized reading' (duration: 182.826486ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:45.957236Z","caller":"traceutil/trace.go:171","msg":"trace[1483618701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23289; }","duration":"197.14418ms","start":"2026-03-06T22:02:45.760081Z","end":"2026-03-06T22:02:45.957225Z","steps":["trace[1483618701] 'agreement among raft nodes before linearized reading' (duration: 197.007027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:45.95722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.513197Z","time spent":"443.91856ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:45.95734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.072727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:02:45.957366Z","caller":"traceutil/trace.go:171","msg":"trace[1375678767] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:23289; }","duration":"196.170038ms","start":"2026-03-06T22:02:45.761188Z","end":"2026-03-06T22:02:45.957358Z","steps":["trace[1375678767] 'agreement among raft nodes before linearized reading' (duration: 196.114197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:45.957419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.76794ms","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-03-06T22:02:45.95747Z","caller":"traceutil/trace.go:171","msg":"trace[101254646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23289; }","duration":"186.83477ms","start":"2026-03-06T22:02:45.770623Z","end":"2026-03-06T22:02:45.957458Z","steps":["trace[101254646] 'agreement among raft nodes before linearized reading' (duration: 186.647887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:45.957647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.419381ms","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-03-06T22:02:45.957785Z","caller":"traceutil/trace.go:171","msg":"trace[1023327239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23289; }","duration":"202.632466ms","start":"2026-03-06T22:02:45.755126Z","end":"2026-03-06T22:02:45.957758Z","steps":["trace[1023327239] 'agreement among raft nodes before linearized reading' (duration: 202.205627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:46.308381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.241445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:02:46.308513Z","caller":"traceutil/trace.go:171","msg":"trace[681510058] transaction","detail":"{read_only:false; response_revision:23290; number_of_response:1; }","duration":"346.6879ms","start":"2026-03-06T22:02:45.961797Z","end":"2026-03-06T22:02:46.308485Z","steps":["trace[681510058] 'process raft request' (duration: 221.22878ms)","trace[681510058] 'compare' (duration: 124.98821ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:46.308584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.961771Z","time spent":"346.779282ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:46.311086Z","caller":"traceutil/trace.go:171","msg":"trace[2062273042] transaction","detail":"{read_only:false; response_revision:23291; number_of_response:1; }","duration":"349.174683ms","start":"2026-03-06T22:02:45.961862Z","end":"2026-03-06T22:02:46.311037Z","steps":["trace[2062273042] 'process raft request' (duration: 348.962068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:46.311263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.961837Z","time spent":"349.338276ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:46.311295Z","caller":"traceutil/trace.go:171","msg":"trace[1542494517] transaction","detail":"{read_only:false; response_revision:23292; number_of_response:1; }","duration":"349.010659ms","start":"2026-03-06T22:02:45.962259Z","end":"2026-03-06T22:02:46.31127Z","steps":["trace[1542494517] 'process raft request' (duration: 348.725563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:46.311386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.962247Z","time spent":"349.092341ms","remote":"127.0.0.1:45400","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-03-06T22:02:46.311443Z","caller":"traceutil/trace.go:171","msg":"trace[1967870772] linearizableReadLoop","detail":"{readStateIndex:24102; appliedIndex:24098; }","duration":"346.385343ms","start":"2026-03-06T22:02:45.965019Z","end":"2026-03-06T22:02:46.311405Z","steps":["trace[1967870772] 'read index received' (duration: 218.069133ms)","trace[1967870772] 'applied index is now lower than readState.Index' (duration: 128.31441ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:46.311523Z","caller":"traceutil/trace.go:171","msg":"trace[1227317679] transaction","detail":"{read_only:false; response_revision:23293; number_of_response:1; }","duration":"348.952718ms","start":"2026-03-06T22:02:45.962539Z","end":"2026-03-06T22:02:46.311492Z","steps":["trace[1227317679] 'process raft request' (duration: 348.692343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:46.311626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.583427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:02:46.311677Z","caller":"traceutil/trace.go:171","msg":"trace[1334630775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23293; }","duration":"346.663889ms","start":"2026-03-06T22:02:45.964999Z","end":"2026-03-06T22:02:46.311663Z","steps":["trace[1334630775] 'agreement among raft nodes before linearized reading' (duration: 346.561677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:46.311668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.962529Z","time spent":"349.06724ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:46.311704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:45.96499Z","time spent":"346.70944ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:02:46.31183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.116495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-03-06T22:02:46.311882Z","caller":"traceutil/trace.go:171","msg":"trace[852632272] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23293; }","duration":"135.227726ms","start":"2026-03-06T22:02:46.176642Z","end":"2026-03-06T22:02:46.311869Z","steps":["trace[852632272] 'agreement among raft nodes before linearized reading' (duration: 135.000191ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:47.139441Z","caller":"traceutil/trace.go:171","msg":"trace[1966388967] transaction","detail":"{read_only:false; response_revision:23295; number_of_response:1; }","duration":"289.739242ms","start":"2026-03-06T22:02:46.849675Z","end":"2026-03-06T22:02:47.139414Z","steps":["trace[1966388967] 'process raft request' (duration: 289.594979ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:47.297418Z","caller":"traceutil/trace.go:171","msg":"trace[1190243188] linearizableReadLoop","detail":"{readStateIndex:24105; appliedIndex:24103; }","duration":"329.103137ms","start":"2026-03-06T22:02:46.968285Z","end":"2026-03-06T22:02:47.297388Z","steps":["trace[1190243188] 'read index received' (duration: 171.096317ms)","trace[1190243188] 'applied index is now lower than readState.Index' (duration: 158.00591ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:47.297534Z","caller":"traceutil/trace.go:171","msg":"trace[1998567711] transaction","detail":"{read_only:false; response_revision:23296; number_of_response:1; }","duration":"411.411291ms","start":"2026-03-06T22:02:46.886085Z","end":"2026-03-06T22:02:47.297497Z","steps":["trace[1998567711] 'process raft request' (duration: 346.184589ms)","trace[1998567711] 'compare' (duration: 64.66531ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:47.297601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.304862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:02:47.297687Z","caller":"traceutil/trace.go:171","msg":"trace[668893600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23296; }","duration":"329.465525ms","start":"2026-03-06T22:02:46.968203Z","end":"2026-03-06T22:02:47.297668Z","steps":["trace[668893600] 'agreement among raft nodes before linearized reading' (duration: 329.307201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:47.297682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.442851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"warn","ts":"2026-03-06T22:02:47.297696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:46.886064Z","time spent":"411.542584ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:47.297737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:46.968172Z","time spent":"329.557007ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T22:02:47.297744Z","caller":"traceutil/trace.go:171","msg":"trace[1043199458] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:23296; }","duration":"160.560364ms","start":"2026-03-06T22:02:47.13717Z","end":"2026-03-06T22:02:47.29773Z","steps":["trace[1043199458] 'agreement among raft nodes before linearized reading' (duration: 160.36746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:47.297945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.894603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-06T22:02:47.298063Z","caller":"traceutil/trace.go:171","msg":"trace[961893210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23296; }","duration":"155.100227ms","start":"2026-03-06T22:02:47.142938Z","end":"2026-03-06T22:02:47.298038Z","steps":["trace[961893210] 'agreement among raft nodes before linearized reading' (duration: 154.681789ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:47.504628Z","caller":"traceutil/trace.go:171","msg":"trace[685045521] transaction","detail":"{read_only:false; response_revision:23298; number_of_response:1; }","duration":"199.860906ms","start":"2026-03-06T22:02:47.304726Z","end":"2026-03-06T22:02:47.504587Z","steps":["trace[685045521] 'process raft request' (duration: 185.313298ms)","trace[685045521] 'compare' (duration: 13.854703ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:47.504634Z","caller":"traceutil/trace.go:171","msg":"trace[2092010974] transaction","detail":"{read_only:false; response_revision:23299; number_of_response:1; }","duration":"199.826245ms","start":"2026-03-06T22:02:47.304778Z","end":"2026-03-06T22:02:47.504605Z","steps":["trace[2092010974] 'process raft request' (duration: 199.682502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:47.729073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.312567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:02:47.729213Z","caller":"traceutil/trace.go:171","msg":"trace[1945321674] transaction","detail":"{read_only:false; response_revision:23300; number_of_response:1; }","duration":"216.431898ms","start":"2026-03-06T22:02:47.512755Z","end":"2026-03-06T22:02:47.729187Z","steps":["trace[1945321674] 'process raft request' (duration: 98.898567ms)","trace[1945321674] 'compare' (duration: 117.142403ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:47.99611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.809653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:02:47.996292Z","caller":"traceutil/trace.go:171","msg":"trace[2127899217] transaction","detail":"{read_only:false; response_revision:23301; number_of_response:1; }","duration":"260.003401ms","start":"2026-03-06T22:02:47.73627Z","end":"2026-03-06T22:02:47.996274Z","steps":["trace[2127899217] 'process raft request' (duration: 111.932092ms)","trace[2127899217] 'compare' (duration: 147.565318ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:47.996581Z","caller":"traceutil/trace.go:171","msg":"trace[806818326] transaction","detail":"{read_only:false; response_revision:23302; number_of_response:1; }","duration":"248.339105ms","start":"2026-03-06T22:02:47.74821Z","end":"2026-03-06T22:02:47.996549Z","steps":["trace[806818326] 'process raft request' (duration: 248.005077ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:48.166527Z","caller":"traceutil/trace.go:171","msg":"trace[751948241] transaction","detail":"{read_only:false; response_revision:23304; number_of_response:1; }","duration":"203.36345ms","start":"2026-03-06T22:02:47.963138Z","end":"2026-03-06T22:02:48.166501Z","steps":["trace[751948241] 'process raft request' (duration: 203.193887ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:48.166513Z","caller":"traceutil/trace.go:171","msg":"trace[830747752] transaction","detail":"{read_only:false; response_revision:23303; number_of_response:1; }","duration":"204.066206ms","start":"2026-03-06T22:02:47.962381Z","end":"2026-03-06T22:02:48.166447Z","steps":["trace[830747752] 'process raft request' (duration: 178.262199ms)","trace[830747752] 'compare' (duration: 25.568302ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:48.166798Z","caller":"traceutil/trace.go:171","msg":"trace[543175062] transaction","detail":"{read_only:false; response_revision:23305; number_of_response:1; }","duration":"203.060824ms","start":"2026-03-06T22:02:47.963579Z","end":"2026-03-06T22:02:48.166639Z","steps":["trace[543175062] 'process raft request' (duration: 202.812909ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:48.167108Z","caller":"traceutil/trace.go:171","msg":"trace[1444561820] transaction","detail":"{read_only:false; response_revision:23306; number_of_response:1; }","duration":"203.287839ms","start":"2026-03-06T22:02:47.963797Z","end":"2026-03-06T22:02:48.167085Z","steps":["trace[1444561820] 'process raft request' (duration: 202.631215ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:48.167097Z","caller":"traceutil/trace.go:171","msg":"trace[1105317244] linearizableReadLoop","detail":"{readStateIndex:24115; appliedIndex:24109; }","duration":"199.460368ms","start":"2026-03-06T22:02:47.967615Z","end":"2026-03-06T22:02:48.167075Z","steps":["trace[1105317244] 'read index received' (duration: 19.085995ms)","trace[1105317244] 'applied index is now lower than readState.Index' (duration: 180.373193ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:48.167222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.590621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:02:48.167272Z","caller":"traceutil/trace.go:171","msg":"trace[747975309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23306; }","duration":"199.658582ms","start":"2026-03-06T22:02:47.967604Z","end":"2026-03-06T22:02:48.167263Z","steps":["trace[747975309] 'agreement among raft nodes before linearized reading' (duration: 199.54055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:48.167562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.892999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:02:48.167641Z","caller":"traceutil/trace.go:171","msg":"trace[1707484293] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:23306; }","duration":"176.014752ms","start":"2026-03-06T22:02:47.991604Z","end":"2026-03-06T22:02:48.167618Z","steps":["trace[1707484293] 'agreement among raft nodes before linearized reading' (duration: 175.814667ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:48.750696Z","caller":"traceutil/trace.go:171","msg":"trace[323078110] transaction","detail":"{read_only:false; response_revision:23310; number_of_response:1; }","duration":"393.600014ms","start":"2026-03-06T22:02:48.357064Z","end":"2026-03-06T22:02:48.750664Z","steps":["trace[323078110] 'process raft request' (duration: 393.510392ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:48.750723Z","caller":"traceutil/trace.go:171","msg":"trace[1344055206] transaction","detail":"{read_only:false; response_revision:23309; number_of_response:1; }","duration":"393.769597ms","start":"2026-03-06T22:02:48.356903Z","end":"2026-03-06T22:02:48.750673Z","steps":["trace[1344055206] 'process raft request' (duration: 393.486862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:48.750949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:48.356876Z","time spent":"393.946712ms","remote":"127.0.0.1:45400","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-03-06T22:02:48.750943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:48.357054Z","time spent":"393.718066ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:48.750963Z","caller":"traceutil/trace.go:171","msg":"trace[757473775] transaction","detail":"{read_only:false; response_revision:23311; number_of_response:1; }","duration":"393.688266ms","start":"2026-03-06T22:02:48.35717Z","end":"2026-03-06T22:02:48.750858Z","steps":["trace[757473775] 'process raft request' (duration: 393.454421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:48.751478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:48.357157Z","time spent":"394.082634ms","remote":"127.0.0.1:45400","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-03-06T22:02:49.018002Z","caller":"traceutil/trace.go:171","msg":"trace[1329588793] transaction","detail":"{read_only:false; response_revision:23312; number_of_response:1; }","duration":"138.71654ms","start":"2026-03-06T22:02:48.879244Z","end":"2026-03-06T22:02:49.017961Z","steps":["trace[1329588793] 'process raft request' (duration: 138.474195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:49.432358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.284933ms","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-03-06T22:02:49.432443Z","caller":"traceutil/trace.go:171","msg":"trace[217260509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23313; }","duration":"114.409136ms","start":"2026-03-06T22:02:49.318012Z","end":"2026-03-06T22:02:49.432422Z","steps":["trace[217260509] 'range keys from in-memory index tree' (duration: 114.11784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:50.098748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.171448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:02:50.098897Z","caller":"traceutil/trace.go:171","msg":"trace[419352654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23315; }","duration":"129.432553ms","start":"2026-03-06T22:02:49.969445Z","end":"2026-03-06T22:02:50.098877Z","steps":["trace[419352654] 'range keys from in-memory index tree' (duration: 129.079546ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:00.59212Z","caller":"traceutil/trace.go:171","msg":"trace[89249692] transaction","detail":"{read_only:false; response_revision:23392; number_of_response:1; }","duration":"122.411685ms","start":"2026-03-06T22:03:00.469674Z","end":"2026-03-06T22:03:00.592086Z","steps":["trace[89249692] 'process raft request' (duration: 122.229101ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:02.298732Z","caller":"traceutil/trace.go:171","msg":"trace[1069700115] transaction","detail":"{read_only:false; response_revision:23399; number_of_response:1; }","duration":"144.479563ms","start":"2026-03-06T22:03:02.154213Z","end":"2026-03-06T22:03:02.298692Z","steps":["trace[1069700115] 'process raft request' (duration: 144.183057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:02.590982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.861124ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:02.591213Z","caller":"traceutil/trace.go:171","msg":"trace[233746079] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23399; }","duration":"206.123249ms","start":"2026-03-06T22:03:02.385054Z","end":"2026-03-06T22:03:02.591178Z","steps":["trace[233746079] 'range keys from in-memory index tree' (duration: 205.825972ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:02.735959Z","caller":"traceutil/trace.go:171","msg":"trace[1701044075] transaction","detail":"{read_only:false; response_revision:23400; number_of_response:1; }","duration":"517.420239ms","start":"2026-03-06T22:03:02.218507Z","end":"2026-03-06T22:03:02.735927Z","steps":["trace[1701044075] 'process raft request' (duration: 517.126743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:02.736132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.218459Z","time spent":"517.580772ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:02.736397Z","caller":"traceutil/trace.go:171","msg":"trace[284240477] transaction","detail":"{read_only:false; response_revision:23402; number_of_response:1; }","duration":"516.066889ms","start":"2026-03-06T22:03:02.220304Z","end":"2026-03-06T22:03:02.736371Z","steps":["trace[284240477] 'process raft request' (duration: 515.929686ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:02.736442Z","caller":"traceutil/trace.go:171","msg":"trace[1523630392] transaction","detail":"{read_only:false; response_revision:23401; number_of_response:1; }","duration":"517.684984ms","start":"2026-03-06T22:03:02.218709Z","end":"2026-03-06T22:03:02.736394Z","steps":["trace[1523630392] 'process raft request' (duration: 517.080391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:02.736558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.22028Z","time spent":"516.190022ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:02.736713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.218697Z","time spent":"517.871708ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:02.737429Z","caller":"traceutil/trace.go:171","msg":"trace[1291223211] transaction","detail":"{read_only:false; response_revision:23403; number_of_response:1; }","duration":"512.999705ms","start":"2026-03-06T22:03:02.224386Z","end":"2026-03-06T22:03:02.737385Z","steps":["trace[1291223211] 'process raft request' (duration: 511.914272ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:02.737388Z","caller":"traceutil/trace.go:171","msg":"trace[1080423971] linearizableReadLoop","detail":"{readStateIndex:24212; appliedIndex:24210; }","duration":"518.712667ms","start":"2026-03-06T22:03:02.218649Z","end":"2026-03-06T22:03:02.737361Z","steps":["trace[1080423971] 'read index received' (duration: 79.889454ms)","trace[1080423971] 'applied index is now lower than readState.Index' (duration: 438.821763ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:02.737547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.224375Z","time spent":"513.096407ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:02.73759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.927131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:02.738243Z","caller":"traceutil/trace.go:171","msg":"trace[1000962954] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:23403; }","duration":"518.997612ms","start":"2026-03-06T22:03:02.21862Z","end":"2026-03-06T22:03:02.737618Z","steps":["trace[1000962954] 'agreement among raft nodes before linearized reading' (duration: 518.88654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:02.73849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.218608Z","time spent":"519.775199ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:03:03.207069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.656135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:03:03.207362Z","caller":"traceutil/trace.go:171","msg":"trace[164165617] transaction","detail":"{read_only:false; response_revision:23405; number_of_response:1; }","duration":"141.523509ms","start":"2026-03-06T22:03:03.065818Z","end":"2026-03-06T22:03:03.207341Z","steps":["trace[164165617] 'process raft request' (duration: 141.375346ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:03.207522Z","caller":"traceutil/trace.go:171","msg":"trace[684735643] transaction","detail":"{read_only:false; response_revision:23404; number_of_response:1; }","duration":"889.858173ms","start":"2026-03-06T22:03:02.317616Z","end":"2026-03-06T22:03:03.207474Z","steps":["trace[684735643] 'process raft request' (duration: 603.694817ms)","trace[684735643] 'compare' (duration: 285.38304ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:03.207547Z","caller":"traceutil/trace.go:171","msg":"trace[1859423670] linearizableReadLoop","detail":"{readStateIndex:24216; appliedIndex:24215; }","duration":"470.039784ms","start":"2026-03-06T22:03:02.737478Z","end":"2026-03-06T22:03:03.207518Z","steps":["trace[1859423670] 'read index received' (duration: 183.802676ms)","trace[1859423670] 'applied index is now lower than readState.Index' (duration: 286.233828ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:03.20777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.317594Z","time spent":"890.042786ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:03.207869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.355344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:03.207917Z","caller":"traceutil/trace.go:171","msg":"trace[1165552527] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:23405; }","duration":"906.457136ms","start":"2026-03-06T22:03:02.301447Z","end":"2026-03-06T22:03:03.207905Z","steps":["trace[1165552527] 'agreement among raft nodes before linearized reading' (duration: 906.363723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.207886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.944809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:03:03.207961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.301419Z","time spent":"906.528518ms","remote":"127.0.0.1:37104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:03:03.208291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.49977ms","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-03-06T22:03:03.208327Z","caller":"traceutil/trace.go:171","msg":"trace[365589147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23405; }","duration":"608.568911ms","start":"2026-03-06T22:03:02.599747Z","end":"2026-03-06T22:03:03.208316Z","steps":["trace[365589147] 'agreement among raft nodes before linearized reading' (duration: 608.430818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.208359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.59973Z","time spent":"608.621693ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T22:03:03.20863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.26961ms","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-03-06T22:03:03.208738Z","caller":"traceutil/trace.go:171","msg":"trace[634829215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23405; }","duration":"295.421433ms","start":"2026-03-06T22:03:02.913293Z","end":"2026-03-06T22:03:03.208714Z","steps":["trace[634829215] 'agreement among raft nodes before linearized reading' (duration: 295.109556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.208716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.651152ms","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-03-06T22:03:03.208824Z","caller":"traceutil/trace.go:171","msg":"trace[258153101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23405; }","duration":"290.807034ms","start":"2026-03-06T22:03:02.917989Z","end":"2026-03-06T22:03:03.208796Z","steps":["trace[258153101] 'agreement among raft nodes before linearized reading' (duration: 290.417856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.209129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.888301ms","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-03-06T22:03:03.209173Z","caller":"traceutil/trace.go:171","msg":"trace[798681730] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:23405; }","duration":"592.080592ms","start":"2026-03-06T22:03:02.615895Z","end":"2026-03-06T22:03:03.207976Z","steps":["trace[798681730] 'agreement among raft nodes before linearized reading' (duration: 591.905058ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:03.209267Z","caller":"traceutil/trace.go:171","msg":"trace[139872988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23405; }","duration":"295.090075ms","start":"2026-03-06T22:03:02.914153Z","end":"2026-03-06T22:03:03.209243Z","steps":["trace[139872988] 'agreement among raft nodes before linearized reading' (duration: 294.37634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.209308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.615885Z","time spent":"593.40085ms","remote":"127.0.0.1:39036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-03-06T22:03:03.210874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.57134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-03-06T22:03:03.210937Z","caller":"traceutil/trace.go:171","msg":"trace[1759067393] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:23405; }","duration":"715.700703ms","start":"2026-03-06T22:03:02.495221Z","end":"2026-03-06T22:03:03.210922Z","steps":["trace[1759067393] 'agreement among raft nodes before linearized reading' (duration: 713.145308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.210985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:02.49518Z","time spent":"715.795465ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2681,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:03:03.51Z","caller":"traceutil/trace.go:171","msg":"trace[1579108844] transaction","detail":"{read_only:false; response_revision:23406; number_of_response:1; }","duration":"295.405851ms","start":"2026-03-06T22:03:03.214565Z","end":"2026-03-06T22:03:03.509971Z","steps":["trace[1579108844] 'process raft request' (duration: 295.195087ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:03.522096Z","caller":"traceutil/trace.go:171","msg":"trace[674965068] transaction","detail":"{read_only:false; response_revision:23408; number_of_response:1; }","duration":"306.970227ms","start":"2026-03-06T22:03:03.21509Z","end":"2026-03-06T22:03:03.52206Z","steps":["trace[674965068] 'process raft request' (duration: 306.62443ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:03.522209Z","caller":"traceutil/trace.go:171","msg":"trace[1545211399] transaction","detail":"{read_only:false; response_revision:23409; number_of_response:1; }","duration":"306.272353ms","start":"2026-03-06T22:03:03.215908Z","end":"2026-03-06T22:03:03.522181Z","steps":["trace[1545211399] 'process raft request' (duration: 305.844114ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:03.522241Z","caller":"traceutil/trace.go:171","msg":"trace[1651129986] transaction","detail":"{read_only:false; response_revision:23407; number_of_response:1; }","duration":"307.224643ms","start":"2026-03-06T22:03:03.214997Z","end":"2026-03-06T22:03:03.522221Z","steps":["trace[1651129986] 'process raft request' (duration: 306.551027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.522273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.215082Z","time spent":"307.103211ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:03.52236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.215895Z","time spent":"306.379116ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:03.522394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.214975Z","time spent":"307.313145ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:03.522359Z","caller":"traceutil/trace.go:171","msg":"trace[1409820716] transaction","detail":"{read_only:false; response_revision:23410; number_of_response:1; }","duration":"271.285781ms","start":"2026-03-06T22:03:03.251017Z","end":"2026-03-06T22:03:03.522303Z","steps":["trace[1409820716] 'process raft request' (duration: 270.7712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.841305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.452832ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T22:03:03.841427Z","caller":"traceutil/trace.go:171","msg":"trace[474256210] linearizableReadLoop","detail":"{readStateIndex:24221; appliedIndex:24217; }","duration":"619.500923ms","start":"2026-03-06T22:03:03.221905Z","end":"2026-03-06T22:03:03.841406Z","steps":["trace[474256210] 'read index received' (duration: 287.923083ms)","trace[474256210] 'applied index is now lower than readState.Index' (duration: 331.57573ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:03.841705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.780008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:03:03.841769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.071762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:03:03.841778Z","caller":"traceutil/trace.go:171","msg":"trace[642436696] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23410; }","duration":"619.925811ms","start":"2026-03-06T22:03:03.221834Z","end":"2026-03-06T22:03:03.84176Z","steps":["trace[642436696] 'agreement among raft nodes before linearized reading' (duration: 619.769678ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:03.841808Z","caller":"traceutil/trace.go:171","msg":"trace[228626525] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23410; }","duration":"542.139233ms","start":"2026-03-06T22:03:03.299653Z","end":"2026-03-06T22:03:03.841792Z","steps":["trace[228626525] 'agreement among raft nodes before linearized reading' (duration: 542.059801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.841823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.221801Z","time spent":"620.010324ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:03:03.841839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.299641Z","time spent":"542.189575ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:03:03.842006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.464218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-06T22:03:03.842143Z","caller":"traceutil/trace.go:171","msg":"trace[1680712113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23410; }","duration":"332.634572ms","start":"2026-03-06T22:03:03.509479Z","end":"2026-03-06T22:03:03.842114Z","steps":["trace[1680712113] 'agreement among raft nodes before linearized reading' (duration: 332.250654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.84223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.509468Z","time spent":"332.750594ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T22:03:03.842272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.740719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-03-06T22:03:03.842365Z","caller":"traceutil/trace.go:171","msg":"trace[1655494101] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23410; }","duration":"345.896622ms","start":"2026-03-06T22:03:03.496449Z","end":"2026-03-06T22:03:03.842346Z","steps":["trace[1655494101] 'agreement among raft nodes before linearized reading' (duration: 345.30845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:03.842415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.49638Z","time spent":"346.022925ms","remote":"127.0.0.1:45322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-06T22:03:04.348437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695235309248643,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T22:03:04.386869Z","caller":"traceutil/trace.go:171","msg":"trace[426379932] transaction","detail":"{read_only:false; response_revision:23411; number_of_response:1; }","duration":"539.769731ms","start":"2026-03-06T22:03:03.847073Z","end":"2026-03-06T22:03:04.386843Z","steps":["trace[426379932] 'process raft request' (duration: 539.545277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:04.387201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.847044Z","time spent":"540.044307ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:04.387579Z","caller":"traceutil/trace.go:171","msg":"trace[56683676] linearizableReadLoop","detail":"{readStateIndex:24225; appliedIndex:24223; }","duration":"539.924556ms","start":"2026-03-06T22:03:03.847622Z","end":"2026-03-06T22:03:04.387547Z","steps":["trace[56683676] 'read index received' (duration: 538.992446ms)","trace[56683676] 'applied index is now lower than readState.Index' (duration: 930.53µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:04.387947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.302344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-03-06T22:03:04.388043Z","caller":"traceutil/trace.go:171","msg":"trace[1566576114] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23412; }","duration":"540.436726ms","start":"2026-03-06T22:03:03.847562Z","end":"2026-03-06T22:03:04.387998Z","steps":["trace[1566576114] 'agreement among raft nodes before linearized reading' (duration: 540.178331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:04.388123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.847543Z","time spent":"540.540859ms","remote":"127.0.0.1:45322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-06T22:03:04.388119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.878606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:04.388203Z","caller":"traceutil/trace.go:171","msg":"trace[283448291] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:23412; }","duration":"282.003748ms","start":"2026-03-06T22:03:04.106168Z","end":"2026-03-06T22:03:04.388171Z","steps":["trace[283448291] 'agreement among raft nodes before linearized reading' (duration: 281.901686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:04.388228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.212752ms","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-03-06T22:03:04.38832Z","caller":"traceutil/trace.go:171","msg":"trace[157216337] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:23412; }","duration":"172.350234ms","start":"2026-03-06T22:03:04.215935Z","end":"2026-03-06T22:03:04.388285Z","steps":["trace[157216337] 'agreement among raft nodes before linearized reading' (duration: 172.077749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:04.387985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.19665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:04.388535Z","caller":"traceutil/trace.go:171","msg":"trace[33037530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23412; }","duration":"422.793753ms","start":"2026-03-06T22:03:03.9657Z","end":"2026-03-06T22:03:04.388493Z","steps":["trace[33037530] 'agreement among raft nodes before linearized reading' (duration: 422.206131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:04.38849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.185628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-554677b4d6-nltgm.189a5fa4b117df16\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-03-06T22:03:04.388584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:03.965671Z","time spent":"422.900184ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T22:03:04.388615Z","caller":"traceutil/trace.go:171","msg":"trace[1762552534] range","detail":"{range_begin:/registry/events/openstack/magnum-api-554677b4d6-nltgm.189a5fa4b117df16; range_end:; response_count:1; response_revision:23412; }","duration":"112.384442ms","start":"2026-03-06T22:03:04.276199Z","end":"2026-03-06T22:03:04.388583Z","steps":["trace[1762552534] 'agreement among raft nodes before linearized reading' (duration: 111.973973ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:04.812933Z","caller":"traceutil/trace.go:171","msg":"trace[579576565] linearizableReadLoop","detail":"{readStateIndex:24227; appliedIndex:24226; }","duration":"359.598923ms","start":"2026-03-06T22:03:04.453302Z","end":"2026-03-06T22:03:04.812901Z","steps":["trace[579576565] 'read index received' (duration: 274.490509ms)","trace[579576565] 'applied index is now lower than readState.Index' (duration: 85.106764ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:04.812965Z","caller":"traceutil/trace.go:171","msg":"trace[1238769751] transaction","detail":"{read_only:false; response_revision:23414; number_of_response:1; }","duration":"366.57053ms","start":"2026-03-06T22:03:04.446357Z","end":"2026-03-06T22:03:04.812927Z","steps":["trace[1238769751] 'process raft request' (duration: 281.410825ms)","trace[1238769751] 'compare' (duration: 84.92493ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:04.813126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.805878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:03:04.813129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.446338Z","time spent":"366.708573ms","remote":"127.0.0.1:39036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T22:03:04.813189Z","caller":"traceutil/trace.go:171","msg":"trace[1446615206] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23414; }","duration":"359.923451ms","start":"2026-03-06T22:03:04.453249Z","end":"2026-03-06T22:03:04.813173Z","steps":["trace[1446615206] 'agreement among raft nodes before linearized reading' (duration: 359.766918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:04.813347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.453229Z","time spent":"360.098734ms","remote":"127.0.0.1:45560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-03-06T22:03:05.164026Z","caller":"traceutil/trace.go:171","msg":"trace[1376732451] transaction","detail":"{read_only:false; response_revision:23415; number_of_response:1; }","duration":"421.072457ms","start":"2026-03-06T22:03:04.74293Z","end":"2026-03-06T22:03:05.164003Z","steps":["trace[1376732451] 'process raft request' (duration: 420.938624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.164166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.742904Z","time spent":"421.186559ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:05.437564Z","caller":"traceutil/trace.go:171","msg":"trace[1378504574] linearizableReadLoop","detail":"{readStateIndex:24231; appliedIndex:24228; }","duration":"620.495664ms","start":"2026-03-06T22:03:04.817043Z","end":"2026-03-06T22:03:05.437538Z","steps":["trace[1378504574] 'read index received' (duration: 347.058548ms)","trace[1378504574] 'applied index is now lower than readState.Index' (duration: 273.435926ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:05.437701Z","caller":"traceutil/trace.go:171","msg":"trace[1788552542] transaction","detail":"{read_only:false; response_revision:23418; number_of_response:1; }","duration":"693.652404ms","start":"2026-03-06T22:03:04.744035Z","end":"2026-03-06T22:03:05.437687Z","steps":["trace[1788552542] 'process raft request' (duration: 693.447809ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:05.437725Z","caller":"traceutil/trace.go:171","msg":"trace[458483219] transaction","detail":"{read_only:false; response_revision:23417; number_of_response:1; }","duration":"693.808837ms","start":"2026-03-06T22:03:04.743867Z","end":"2026-03-06T22:03:05.437676Z","steps":["trace[458483219] 'process raft request' (duration: 693.510881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.437903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.743856Z","time spent":"693.950201ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:05.437948Z","caller":"traceutil/trace.go:171","msg":"trace[51612696] transaction","detail":"{read_only:false; response_revision:23416; number_of_response:1; }","duration":"694.176556ms","start":"2026-03-06T22:03:04.743751Z","end":"2026-03-06T22:03:05.437928Z","steps":["trace[51612696] 'process raft request' (duration: 673.070248ms)","trace[51612696] 'compare' (duration: 20.171997ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:05.437986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.932643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:05.438067Z","caller":"traceutil/trace.go:171","msg":"trace[1741632115] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23418; }","duration":"621.057366ms","start":"2026-03-06T22:03:04.816992Z","end":"2026-03-06T22:03:05.43805Z","steps":["trace[1741632115] 'agreement among raft nodes before linearized reading' (duration: 620.920783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.438071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.743647Z","time spent":"694.333119ms","remote":"127.0.0.1:45400","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-03-06T22:03:05.438117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.816961Z","time spent":"621.146857ms","remote":"127.0.0.1:45326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-03-06T22:03:05.438396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.744023Z","time spent":"693.708007ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:05.633703Z","caller":"traceutil/trace.go:171","msg":"trace[1606753092] transaction","detail":"{read_only:false; response_revision:23420; number_of_response:1; }","duration":"233.825897ms","start":"2026-03-06T22:03:05.399827Z","end":"2026-03-06T22:03:05.633653Z","steps":["trace[1606753092] 'process raft request' (duration: 233.587022ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:05.633508Z","caller":"traceutil/trace.go:171","msg":"trace[2032935275] linearizableReadLoop","detail":"{readStateIndex:24233; appliedIndex:24231; }","duration":"195.842431ms","start":"2026-03-06T22:03:05.437635Z","end":"2026-03-06T22:03:05.633478Z","steps":["trace[2032935275] 'read index received' (duration: 195.693468ms)","trace[2032935275] 'applied index is now lower than readState.Index' (duration: 147.753µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:05.633933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.737633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-03-06T22:03:05.634Z","caller":"traceutil/trace.go:171","msg":"trace[1307495528] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:23420; }","duration":"815.869616ms","start":"2026-03-06T22:03:04.818113Z","end":"2026-03-06T22:03:05.633983Z","steps":["trace[1307495528] 'agreement among raft nodes before linearized reading' (duration: 815.674221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.634019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.255574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:03:05.634047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.818084Z","time spent":"815.953598ms","remote":"127.0.0.1:39036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1673,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-03-06T22:03:05.6341Z","caller":"traceutil/trace.go:171","msg":"trace[851865165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23420; }","duration":"666.367186ms","start":"2026-03-06T22:03:04.967714Z","end":"2026-03-06T22:03:05.634081Z","steps":["trace[851865165] 'agreement among raft nodes before linearized reading' (duration: 666.256594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.634009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.867939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:03:05.634151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.967693Z","time spent":"666.448858ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T22:03:05.634188Z","caller":"traceutil/trace.go:171","msg":"trace[857782904] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:23420; }","duration":"743.105894ms","start":"2026-03-06T22:03:04.89106Z","end":"2026-03-06T22:03:05.634166Z","steps":["trace[857782904] 'agreement among raft nodes before linearized reading' (duration: 742.859329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.634233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.891037Z","time spent":"743.182296ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:03:05.634227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.019217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-03-06T22:03:05.634313Z","caller":"traceutil/trace.go:171","msg":"trace[773750099] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:23420; }","duration":"814.128849ms","start":"2026-03-06T22:03:04.820168Z","end":"2026-03-06T22:03:05.634297Z","steps":["trace[773750099] 'agreement among raft nodes before linearized reading' (duration: 813.765282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.6344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:04.820161Z","time spent":"814.218211ms","remote":"127.0.0.1:39036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1673,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-03-06T22:03:05.633785Z","caller":"traceutil/trace.go:171","msg":"trace[1680727469] transaction","detail":"{read_only:false; response_revision:23419; number_of_response:1; }","duration":"419.541853ms","start":"2026-03-06T22:03:05.214196Z","end":"2026-03-06T22:03:05.633738Z","steps":["trace[1680727469] 'process raft request' (duration: 419.044112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.634963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:05.214162Z","time spent":"420.577996ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:05.645158Z","caller":"traceutil/trace.go:171","msg":"trace[1570302281] transaction","detail":"{read_only:false; response_revision:23421; number_of_response:1; }","duration":"115.54057ms","start":"2026-03-06T22:03:05.529603Z","end":"2026-03-06T22:03:05.645143Z","steps":["trace[1570302281] 'process raft request' (duration: 115.389566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.645621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.376331ms","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-03-06T22:03:05.645676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.499567ms","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-03-06T22:03:05.645697Z","caller":"traceutil/trace.go:171","msg":"trace[105192325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23421; }","duration":"119.467592ms","start":"2026-03-06T22:03:05.526214Z","end":"2026-03-06T22:03:05.645682Z","steps":["trace[105192325] 'agreement among raft nodes before linearized reading' (duration: 119.200897ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:05.645731Z","caller":"traceutil/trace.go:171","msg":"trace[956281505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23421; }","duration":"130.619979ms","start":"2026-03-06T22:03:05.515098Z","end":"2026-03-06T22:03:05.645717Z","steps":["trace[956281505] 'agreement among raft nodes before linearized reading' (duration: 130.406245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.645643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.479932ms","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-03-06T22:03:05.645765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.593376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-03-06T22:03:05.64582Z","caller":"traceutil/trace.go:171","msg":"trace[496096550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23421; }","duration":"118.709477ms","start":"2026-03-06T22:03:05.527079Z","end":"2026-03-06T22:03:05.645788Z","steps":["trace[496096550] 'agreement among raft nodes before linearized reading' (duration: 118.366759ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:05.645938Z","caller":"traceutil/trace.go:171","msg":"trace[2115070171] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23421; }","duration":"203.77589ms","start":"2026-03-06T22:03:05.442104Z","end":"2026-03-06T22:03:05.64588Z","steps":["trace[2115070171] 'agreement among raft nodes before linearized reading' (duration: 203.419842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:05.646161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.070134ms","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-03-06T22:03:05.646412Z","caller":"traceutil/trace.go:171","msg":"trace[382617662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23421; }","duration":"119.299069ms","start":"2026-03-06T22:03:05.527037Z","end":"2026-03-06T22:03:05.646336Z","steps":["trace[382617662] 'agreement among raft nodes before linearized reading' (duration: 118.349069ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:13.4973Z","caller":"traceutil/trace.go:171","msg":"trace[1031342960] transaction","detail":"{read_only:false; response_revision:23555; number_of_response:1; }","duration":"289.516097ms","start":"2026-03-06T22:03:13.20776Z","end":"2026-03-06T22:03:13.497276Z","steps":["trace[1031342960] 'process raft request' (duration: 289.336753ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:13.571054Z","caller":"traceutil/trace.go:171","msg":"trace[2081173426] transaction","detail":"{read_only:false; response_revision:23556; number_of_response:1; }","duration":"347.994355ms","start":"2026-03-06T22:03:13.223007Z","end":"2026-03-06T22:03:13.571001Z","steps":["trace[2081173426] 'process raft request' (duration: 290.499107ms)","trace[2081173426] 'compare' (duration: 56.891075ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:13.571293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:13.222995Z","time spent":"348.17746ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:13.671871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.986381ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T22:03:13.672469Z","caller":"traceutil/trace.go:171","msg":"trace[1085553883] transaction","detail":"{read_only:false; response_revision:23557; number_of_response:1; }","duration":"199.55705ms","start":"2026-03-06T22:03:13.472887Z","end":"2026-03-06T22:03:13.672444Z","steps":["trace[1085553883] 'process raft request' (duration: 199.0766ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:13.672819Z","caller":"traceutil/trace.go:171","msg":"trace[816553408] transaction","detail":"{read_only:false; response_revision:23558; number_of_response:1; }","duration":"195.7769ms","start":"2026-03-06T22:03:13.477Z","end":"2026-03-06T22:03:13.672777Z","steps":["trace[816553408] 'process raft request' (duration: 195.362851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:13.837174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.291793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:03:13.837518Z","caller":"traceutil/trace.go:171","msg":"trace[207645479] transaction","detail":"{read_only:false; response_revision:23559; number_of_response:1; }","duration":"360.4407ms","start":"2026-03-06T22:03:13.477039Z","end":"2026-03-06T22:03:13.837479Z","steps":["trace[207645479] 'process raft request' (duration: 195.713708ms)","trace[207645479] 'compare' (duration: 164.111889ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:13.837695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:13.477003Z","time spent":"360.597843ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:13.837494Z","caller":"traceutil/trace.go:171","msg":"trace[1141808062] transaction","detail":"{read_only:false; response_revision:23560; number_of_response:1; }","duration":"175.03522ms","start":"2026-03-06T22:03:13.662425Z","end":"2026-03-06T22:03:13.83746Z","steps":["trace[1141808062] 'process raft request' (duration: 174.916378ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:13.839019Z","caller":"traceutil/trace.go:171","msg":"trace[1397147912] linearizableReadLoop","detail":"{readStateIndex:24377; appliedIndex:24374; }","duration":"141.353396ms","start":"2026-03-06T22:03:13.697647Z","end":"2026-03-06T22:03:13.839Z","steps":["trace[1397147912] 'read index received' (duration: 61.573595ms)","trace[1397147912] 'applied index is now lower than readState.Index' (duration: 79.778531ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:13.839334Z","caller":"traceutil/trace.go:171","msg":"trace[1147401505] transaction","detail":"{read_only:false; response_revision:23561; number_of_response:1; }","duration":"165.734933ms","start":"2026-03-06T22:03:13.67358Z","end":"2026-03-06T22:03:13.839315Z","steps":["trace[1147401505] 'process raft request' (duration: 165.290843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:13.839525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.531939ms","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-03-06T22:03:13.839631Z","caller":"traceutil/trace.go:171","msg":"trace[1057963344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23561; }","duration":"140.679423ms","start":"2026-03-06T22:03:13.698921Z","end":"2026-03-06T22:03:13.8396Z","steps":["trace[1057963344] 'agreement among raft nodes before linearized reading' (duration: 140.394387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:13.839827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.305895ms","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-03-06T22:03:13.839869Z","caller":"traceutil/trace.go:171","msg":"trace[2108264658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23561; }","duration":"140.370375ms","start":"2026-03-06T22:03:13.699488Z","end":"2026-03-06T22:03:13.839858Z","steps":["trace[2108264658] 'agreement among raft nodes before linearized reading' (duration: 140.230013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:13.839833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.703203ms","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-03-06T22:03:13.83998Z","caller":"traceutil/trace.go:171","msg":"trace[1302108769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23561; }","duration":"140.852136ms","start":"2026-03-06T22:03:13.699112Z","end":"2026-03-06T22:03:13.839965Z","steps":["trace[1302108769] 'agreement among raft nodes before linearized reading' (duration: 140.609701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:13.841148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.495452ms","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-03-06T22:03:13.841198Z","caller":"traceutil/trace.go:171","msg":"trace[2117969625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23561; }","duration":"143.603714ms","start":"2026-03-06T22:03:13.697579Z","end":"2026-03-06T22:03:13.841182Z","steps":["trace[2117969625] 'agreement among raft nodes before linearized reading' (duration: 142.080152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:40.119457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.991546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:03:40.11957Z","caller":"traceutil/trace.go:171","msg":"trace[766359459] linearizableReadLoop","detail":"{readStateIndex:24578; appliedIndex:24576; }","duration":"343.100314ms","start":"2026-03-06T22:03:39.776454Z","end":"2026-03-06T22:03:40.119554Z","steps":["trace[766359459] 'read index received' (duration: 50.593742ms)","trace[766359459] 'applied index is now lower than readState.Index' (duration: 292.505372ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:40.119622Z","caller":"traceutil/trace.go:171","msg":"trace[590867707] transaction","detail":"{read_only:false; response_revision:23757; number_of_response:1; }","duration":"344.086334ms","start":"2026-03-06T22:03:39.775485Z","end":"2026-03-06T22:03:40.119572Z","steps":["trace[590867707] 'process raft request' (duration: 196.884043ms)","trace[590867707] 'compare' (duration: 146.646819ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:40.119718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.272588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-78a7335c-42b1-4e77-8d63-9a7f36de7322\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-03-06T22:03:40.11975Z","caller":"traceutil/trace.go:171","msg":"trace[1630231193] range","detail":"{range_begin:/registry/persistentvolumes/pvc-78a7335c-42b1-4e77-8d63-9a7f36de7322; range_end:; response_count:1; response_revision:23757; }","duration":"343.348429ms","start":"2026-03-06T22:03:39.776394Z","end":"2026-03-06T22:03:40.119742Z","steps":["trace[1630231193] 'agreement among raft nodes before linearized reading' (duration: 343.223787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:40.119775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:39.776373Z","time spent":"343.39726ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-78a7335c-42b1-4e77-8d63-9a7f36de7322\" "} {"level":"warn","ts":"2026-03-06T22:03:40.119791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:39.775474Z","time spent":"344.225888ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:40.120143Z","caller":"traceutil/trace.go:171","msg":"trace[1993114198] transaction","detail":"{read_only:false; response_revision:23758; number_of_response:1; }","duration":"184.152975ms","start":"2026-03-06T22:03:39.935978Z","end":"2026-03-06T22:03:40.120131Z","steps":["trace[1993114198] 'process raft request' (duration: 184.047872ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:40.120147Z","caller":"traceutil/trace.go:171","msg":"trace[967305183] transaction","detail":"{read_only:false; response_revision:23759; number_of_response:1; }","duration":"183.876869ms","start":"2026-03-06T22:03:39.936261Z","end":"2026-03-06T22:03:40.120137Z","steps":["trace[967305183] 'process raft request' (duration: 183.826148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:40.120345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.54006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:03:40.120375Z","caller":"traceutil/trace.go:171","msg":"trace[550111312] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:23760; }","duration":"266.614942ms","start":"2026-03-06T22:03:39.853754Z","end":"2026-03-06T22:03:40.120369Z","steps":["trace[550111312] 'agreement among raft nodes before linearized reading' (duration: 266.507579ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:40.120442Z","caller":"traceutil/trace.go:171","msg":"trace[108364008] transaction","detail":"{read_only:false; response_revision:23760; number_of_response:1; }","duration":"176.197645ms","start":"2026-03-06T22:03:39.944207Z","end":"2026-03-06T22:03:40.120405Z","steps":["trace[108364008] 'process raft request' (duration: 175.904649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:40.120805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.106004ms","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-03-06T22:03:40.120843Z","caller":"traceutil/trace.go:171","msg":"trace[1264837729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23760; }","duration":"102.194676ms","start":"2026-03-06T22:03:40.018638Z","end":"2026-03-06T22:03:40.120833Z","steps":["trace[1264837729] 'agreement among raft nodes before linearized reading' (duration: 102.032973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:40.120631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.998032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-06T22:03:40.120924Z","caller":"traceutil/trace.go:171","msg":"trace[1904641674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23760; }","duration":"102.330799ms","start":"2026-03-06T22:03:40.01858Z","end":"2026-03-06T22:03:40.12091Z","steps":["trace[1904641674] 'agreement among raft nodes before linearized reading' (duration: 101.976091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:40.121422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.463061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:267213"} {"level":"info","ts":"2026-03-06T22:03:40.121548Z","caller":"traceutil/trace.go:171","msg":"trace[207174914] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:23760; }","duration":"118.648786ms","start":"2026-03-06T22:03:40.002869Z","end":"2026-03-06T22:03:40.121518Z","steps":["trace[207174914] 'agreement among raft nodes before linearized reading' (duration: 117.782026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:40.415331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.66359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:03:40.415519Z","caller":"traceutil/trace.go:171","msg":"trace[2052654333] transaction","detail":"{read_only:false; response_revision:23761; number_of_response:1; }","duration":"290.59596ms","start":"2026-03-06T22:03:40.124886Z","end":"2026-03-06T22:03:40.415482Z","steps":["trace[2052654333] 'process raft request' (duration: 125.648644ms)","trace[2052654333] 'compare' (duration: 164.499017ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:40.435007Z","caller":"traceutil/trace.go:171","msg":"trace[893249252] transaction","detail":"{read_only:false; response_revision:23763; number_of_response:1; }","duration":"309.405059ms","start":"2026-03-06T22:03:40.125557Z","end":"2026-03-06T22:03:40.434962Z","steps":["trace[893249252] 'process raft request' (duration: 309.277536ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:40.435064Z","caller":"traceutil/trace.go:171","msg":"trace[1805341212] transaction","detail":"{read_only:false; response_revision:23764; number_of_response:1; }","duration":"309.348787ms","start":"2026-03-06T22:03:40.125673Z","end":"2026-03-06T22:03:40.435022Z","steps":["trace[1805341212] 'process raft request' (duration: 309.193124ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:40.435036Z","caller":"traceutil/trace.go:171","msg":"trace[119197943] transaction","detail":"{read_only:false; response_revision:23762; number_of_response:1; }","duration":"309.590633ms","start":"2026-03-06T22:03:40.125397Z","end":"2026-03-06T22:03:40.434988Z","steps":["trace[119197943] 'process raft request' (duration: 309.308317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:40.435158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:40.125531Z","time spent":"309.563062ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:40.435278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:40.125662Z","time spent":"309.48926ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:40.435361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:40.125384Z","time spent":"309.780616ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:40.779711Z","caller":"traceutil/trace.go:171","msg":"trace[128200374] linearizableReadLoop","detail":"{readStateIndex:24587; appliedIndex:24586; }","duration":"119.436391ms","start":"2026-03-06T22:03:40.660237Z","end":"2026-03-06T22:03:40.779673Z","steps":["trace[128200374] 'read index received' (duration: 52.240627ms)","trace[128200374] 'applied index is now lower than readState.Index' (duration: 67.194714ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:40.779787Z","caller":"traceutil/trace.go:171","msg":"trace[1484737712] transaction","detail":"{read_only:false; response_revision:23766; number_of_response:1; }","duration":"168.981962ms","start":"2026-03-06T22:03:40.610752Z","end":"2026-03-06T22:03:40.779734Z","steps":["trace[1484737712] 'process raft request' (duration: 101.84418ms)","trace[1484737712] 'compare' (duration: 66.874187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:40.779925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.653666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:40.780025Z","caller":"traceutil/trace.go:171","msg":"trace[1144549545] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:23766; }","duration":"119.82876ms","start":"2026-03-06T22:03:40.660173Z","end":"2026-03-06T22:03:40.780002Z","steps":["trace[1144549545] 'agreement among raft nodes before linearized reading' (duration: 119.651296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:41.909563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.979851ms","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-03-06T22:03:41.909688Z","caller":"traceutil/trace.go:171","msg":"trace[884876752] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23768; }","duration":"135.125944ms","start":"2026-03-06T22:03:41.774508Z","end":"2026-03-06T22:03:41.909634Z","steps":["trace[884876752] 'range keys from in-memory index tree' (duration: 134.731997ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:41.946374Z","caller":"traceutil/trace.go:171","msg":"trace[740422261] transaction","detail":"{read_only:false; response_revision:23769; number_of_response:1; }","duration":"113.138478ms","start":"2026-03-06T22:03:41.833203Z","end":"2026-03-06T22:03:41.946341Z","steps":["trace[740422261] 'process raft request' (duration: 112.963735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:42.234845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.852754ms","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-03-06T22:03:42.234941Z","caller":"traceutil/trace.go:171","msg":"trace[1339626465] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:23770; }","duration":"222.990308ms","start":"2026-03-06T22:03:42.011927Z","end":"2026-03-06T22:03:42.234917Z","steps":["trace[1339626465] 'range keys from in-memory index tree' (duration: 222.657101ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.235302Z","caller":"traceutil/trace.go:171","msg":"trace[1248461880] transaction","detail":"{read_only:false; response_revision:23771; number_of_response:1; }","duration":"108.167263ms","start":"2026-03-06T22:03:42.127108Z","end":"2026-03-06T22:03:42.235275Z","steps":["trace[1248461880] 'process raft request' (duration: 108.0095ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.235902Z","caller":"traceutil/trace.go:171","msg":"trace[1789511865] transaction","detail":"{read_only:false; response_revision:23774; number_of_response:1; }","duration":"105.546617ms","start":"2026-03-06T22:03:42.130339Z","end":"2026-03-06T22:03:42.235885Z","steps":["trace[1789511865] 'process raft request' (duration: 105.510076ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.236259Z","caller":"traceutil/trace.go:171","msg":"trace[840760500] transaction","detail":"{read_only:false; response_revision:23773; number_of_response:1; }","duration":"106.103928ms","start":"2026-03-06T22:03:42.130134Z","end":"2026-03-06T22:03:42.236238Z","steps":["trace[840760500] 'process raft request' (duration: 105.685109ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.236357Z","caller":"traceutil/trace.go:171","msg":"trace[1678709641] transaction","detail":"{read_only:false; response_revision:23772; number_of_response:1; }","duration":"106.300692ms","start":"2026-03-06T22:03:42.130048Z","end":"2026-03-06T22:03:42.236348Z","steps":["trace[1678709641] 'process raft request' (duration: 105.672909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:42.585866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.682118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-06T22:03:42.58588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.423803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-06T22:03:42.585898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.147537ms","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-03-06T22:03:42.585963Z","caller":"traceutil/trace.go:171","msg":"trace[493733173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23774; }","duration":"145.813651ms","start":"2026-03-06T22:03:42.440127Z","end":"2026-03-06T22:03:42.585941Z","steps":["trace[493733173] 'range keys from in-memory index tree' (duration: 145.527885ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.585981Z","caller":"traceutil/trace.go:171","msg":"trace[1631018421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23774; }","duration":"145.372152ms","start":"2026-03-06T22:03:42.440593Z","end":"2026-03-06T22:03:42.585965Z","steps":["trace[1631018421] 'range keys from in-memory index tree' (duration: 144.991824ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.58598Z","caller":"traceutil/trace.go:171","msg":"trace[1643591734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23774; }","duration":"145.565306ms","start":"2026-03-06T22:03:42.440389Z","end":"2026-03-06T22:03:42.585954Z","steps":["trace[1643591734] 'range keys from in-memory index tree' (duration: 145.25516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:42.585878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.236184ms","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":"warn","ts":"2026-03-06T22:03:42.585967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.231936ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:42.586093Z","caller":"traceutil/trace.go:171","msg":"trace[872973367] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23774; }","duration":"201.338118ms","start":"2026-03-06T22:03:42.38472Z","end":"2026-03-06T22:03:42.586058Z","steps":["trace[872973367] 'range keys from in-memory index tree' (duration: 201.216806ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.586152Z","caller":"traceutil/trace.go:171","msg":"trace[567748812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23774; }","duration":"166.458638ms","start":"2026-03-06T22:03:42.419582Z","end":"2026-03-06T22:03:42.586041Z","steps":["trace[567748812] 'range keys from in-memory index tree' (duration: 166.013689ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.74977Z","caller":"traceutil/trace.go:171","msg":"trace[784864167] transaction","detail":"{read_only:false; response_revision:23775; number_of_response:1; }","duration":"158.928869ms","start":"2026-03-06T22:03:42.590813Z","end":"2026-03-06T22:03:42.749742Z","steps":["trace[784864167] 'process raft request' (duration: 158.758776ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.781501Z","caller":"traceutil/trace.go:171","msg":"trace[1996360944] linearizableReadLoop","detail":"{readStateIndex:24599; appliedIndex:24595; }","duration":"176.828439ms","start":"2026-03-06T22:03:42.604648Z","end":"2026-03-06T22:03:42.781477Z","steps":["trace[1996360944] 'read index received' (duration: 144.914013ms)","trace[1996360944] 'applied index is now lower than readState.Index' (duration: 31.913126ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:42.781653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.989842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:42.781692Z","caller":"traceutil/trace.go:171","msg":"trace[780471850] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:23779; }","duration":"177.077034ms","start":"2026-03-06T22:03:42.604602Z","end":"2026-03-06T22:03:42.781679Z","steps":["trace[780471850] 'agreement among raft nodes before linearized reading' (duration: 176.972392ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.782016Z","caller":"traceutil/trace.go:171","msg":"trace[573256461] transaction","detail":"{read_only:false; response_revision:23776; number_of_response:1; }","duration":"190.851036ms","start":"2026-03-06T22:03:42.591153Z","end":"2026-03-06T22:03:42.782004Z","steps":["trace[573256461] 'process raft request' (duration: 190.08605ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.782394Z","caller":"traceutil/trace.go:171","msg":"trace[120980316] transaction","detail":"{read_only:false; response_revision:23778; number_of_response:1; }","duration":"190.717673ms","start":"2026-03-06T22:03:42.591661Z","end":"2026-03-06T22:03:42.782379Z","steps":["trace[120980316] 'process raft request' (duration: 189.726392ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.782579Z","caller":"traceutil/trace.go:171","msg":"trace[1785768666] transaction","detail":"{read_only:false; response_revision:23779; number_of_response:1; }","duration":"170.181197ms","start":"2026-03-06T22:03:42.612388Z","end":"2026-03-06T22:03:42.78257Z","steps":["trace[1785768666] 'process raft request' (duration: 169.042783ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:42.784601Z","caller":"traceutil/trace.go:171","msg":"trace[629196044] transaction","detail":"{read_only:false; response_revision:23777; number_of_response:1; }","duration":"193.341149ms","start":"2026-03-06T22:03:42.591201Z","end":"2026-03-06T22:03:42.784542Z","steps":["trace[629196044] 'process raft request' (duration: 190.144951ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:43.145342Z","caller":"traceutil/trace.go:171","msg":"trace[382911471] linearizableReadLoop","detail":"{readStateIndex:24601; appliedIndex:24600; }","duration":"186.434273ms","start":"2026-03-06T22:03:42.958879Z","end":"2026-03-06T22:03:43.145313Z","steps":["trace[382911471] 'read index received' (duration: 186.225779ms)","trace[382911471] 'applied index is now lower than readState.Index' (duration: 207.244µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:43.145464Z","caller":"traceutil/trace.go:171","msg":"trace[1313910528] transaction","detail":"{read_only:false; response_revision:23780; number_of_response:1; }","duration":"357.217013ms","start":"2026-03-06T22:03:42.7882Z","end":"2026-03-06T22:03:43.145417Z","steps":["trace[1313910528] 'process raft request' (duration: 356.944147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:43.145617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:42.788183Z","time spent":"357.355066ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:43.145632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.871138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:03:43.145677Z","caller":"traceutil/trace.go:171","msg":"trace[1212604665] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:23780; }","duration":"166.96782ms","start":"2026-03-06T22:03:42.978696Z","end":"2026-03-06T22:03:43.145663Z","steps":["trace[1212604665] 'agreement among raft nodes before linearized reading' (duration: 166.822007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:43.145679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.752459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:43.145778Z","caller":"traceutil/trace.go:171","msg":"trace[1410923101] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:23780; }","duration":"186.936873ms","start":"2026-03-06T22:03:42.958824Z","end":"2026-03-06T22:03:43.145761Z","steps":["trace[1410923101] 'agreement among raft nodes before linearized reading' (duration: 186.678258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:44.561304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.949623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:44.561405Z","caller":"traceutil/trace.go:171","msg":"trace[1529457044] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:23784; }","duration":"538.091027ms","start":"2026-03-06T22:03:44.023285Z","end":"2026-03-06T22:03:44.561376Z","steps":["trace[1529457044] 'count revisions from in-memory index tree' (duration: 537.877032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:44.561453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.02325Z","time spent":"538.191569ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:03:44.561667Z","caller":"traceutil/trace.go:171","msg":"trace[541454638] transaction","detail":"{read_only:false; response_revision:23785; number_of_response:1; }","duration":"320.855892ms","start":"2026-03-06T22:03:44.240792Z","end":"2026-03-06T22:03:44.561648Z","steps":["trace[541454638] 'process raft request' (duration: 316.230714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:44.56176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.240772Z","time spent":"320.930913ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:44.883071Z","caller":"traceutil/trace.go:171","msg":"trace[532803774] linearizableReadLoop","detail":"{readStateIndex:24609; appliedIndex:24606; }","duration":"556.252061ms","start":"2026-03-06T22:03:44.326793Z","end":"2026-03-06T22:03:44.883045Z","steps":["trace[532803774] 'read index received' (duration: 230.468095ms)","trace[532803774] 'applied index is now lower than readState.Index' (duration: 325.782716ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:44.88327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.450206ms","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:8"} {"level":"info","ts":"2026-03-06T22:03:44.883323Z","caller":"traceutil/trace.go:171","msg":"trace[1804056575] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:23789; }","duration":"556.545877ms","start":"2026-03-06T22:03:44.326761Z","end":"2026-03-06T22:03:44.883307Z","steps":["trace[1804056575] 'agreement among raft nodes before linearized reading' (duration: 556.416294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:44.883388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.326745Z","time spent":"556.630419ms","remote":"127.0.0.1:46660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":30,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:03:44.883394Z","caller":"traceutil/trace.go:171","msg":"trace[649574040] transaction","detail":"{read_only:false; response_revision:23788; number_of_response:1; }","duration":"556.443695ms","start":"2026-03-06T22:03:44.326924Z","end":"2026-03-06T22:03:44.883367Z","steps":["trace[649574040] 'process raft request' (duration: 556.026287ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:44.883401Z","caller":"traceutil/trace.go:171","msg":"trace[77055265] transaction","detail":"{read_only:false; response_revision:23786; number_of_response:1; }","duration":"632.468407ms","start":"2026-03-06T22:03:44.250881Z","end":"2026-03-06T22:03:44.883349Z","steps":["trace[77055265] 'process raft request' (duration: 615.105509ms)","trace[77055265] 'compare' (duration: 16.447539ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:44.883229Z","caller":"traceutil/trace.go:171","msg":"trace[1910956562] transaction","detail":"{read_only:false; response_revision:23787; number_of_response:1; }","duration":"632.060058ms","start":"2026-03-06T22:03:44.251094Z","end":"2026-03-06T22:03:44.883154Z","steps":["trace[1910956562] 'process raft request' (duration: 631.706491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:44.883578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.326916Z","time spent":"556.556148ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:44.883621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.250848Z","time spent":"632.64284ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:44.8837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.251089Z","time spent":"632.506947ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:44.884043Z","caller":"traceutil/trace.go:171","msg":"trace[589874325] transaction","detail":"{read_only:false; response_revision:23789; number_of_response:1; }","duration":"364.452746ms","start":"2026-03-06T22:03:44.51875Z","end":"2026-03-06T22:03:44.883202Z","steps":["trace[589874325] 'process raft request' (duration: 364.251192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:44.884297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.518728Z","time spent":"365.484938ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:45.168293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.303212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:03:45.168391Z","caller":"traceutil/trace.go:171","msg":"trace[1448469505] linearizableReadLoop","detail":"{readStateIndex:24612; appliedIndex:24611; }","duration":"285.232996ms","start":"2026-03-06T22:03:44.883144Z","end":"2026-03-06T22:03:45.168377Z","steps":["trace[1448469505] 'read index received' (duration: 104.544947ms)","trace[1448469505] 'applied index is now lower than readState.Index' (duration: 180.687209ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:45.168423Z","caller":"traceutil/trace.go:171","msg":"trace[220649114] transaction","detail":"{read_only:false; response_revision:23790; number_of_response:1; }","duration":"380.016746ms","start":"2026-03-06T22:03:44.788368Z","end":"2026-03-06T22:03:45.168385Z","steps":["trace[220649114] 'process raft request' (duration: 199.5009ms)","trace[220649114] 'compare' (duration: 180.155978ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:45.168502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.282941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:45.168524Z","caller":"traceutil/trace.go:171","msg":"trace[1976410006] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:23790; }","duration":"630.318242ms","start":"2026-03-06T22:03:44.538198Z","end":"2026-03-06T22:03:45.168516Z","steps":["trace[1976410006] 'agreement among raft nodes before linearized reading' (duration: 630.272261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.168557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.538192Z","time spent":"630.357933ms","remote":"127.0.0.1:55690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:03:45.168585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.117218ms","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-03-06T22:03:45.16862Z","caller":"traceutil/trace.go:171","msg":"trace[650205971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23790; }","duration":"380.204ms","start":"2026-03-06T22:03:44.788407Z","end":"2026-03-06T22:03:45.168611Z","steps":["trace[650205971] 'agreement among raft nodes before linearized reading' (duration: 380.137479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.168604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.788332Z","time spent":"380.174419ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:45.168694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.095049ms","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-03-06T22:03:45.168719Z","caller":"traceutil/trace.go:171","msg":"trace[1734301051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23790; }","duration":"381.136409ms","start":"2026-03-06T22:03:44.787576Z","end":"2026-03-06T22:03:45.168712Z","steps":["trace[1734301051] 'agreement among raft nodes before linearized reading' (duration: 381.080358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.168739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.787566Z","time spent":"381.16872ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T22:03:45.168783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.338483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-554677b4d6-nltgm.189a5fa4b117df16\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-03-06T22:03:45.168811Z","caller":"traceutil/trace.go:171","msg":"trace[266851806] range","detail":"{range_begin:/registry/events/openstack/magnum-api-554677b4d6-nltgm.189a5fa4b117df16; range_end:; response_count:1; response_revision:23790; }","duration":"631.373674ms","start":"2026-03-06T22:03:44.537431Z","end":"2026-03-06T22:03:45.168804Z","steps":["trace[266851806] 'agreement among raft nodes before linearized reading' (duration: 631.295223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.168829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.997008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-03-06T22:03:45.168832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.537426Z","time spent":"631.400845ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":805,"request content":"key:\"/registry/events/openstack/magnum-api-554677b4d6-nltgm.189a5fa4b117df16\" "} {"level":"info","ts":"2026-03-06T22:03:45.168847Z","caller":"traceutil/trace.go:171","msg":"trace[1655880119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23790; }","duration":"382.045658ms","start":"2026-03-06T22:03:44.786797Z","end":"2026-03-06T22:03:45.168843Z","steps":["trace[1655880119] 'agreement among raft nodes before linearized reading' (duration: 381.994667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.168866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.786762Z","time spent":"382.099819ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T22:03:45.168644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.788375Z","time spent":"380.261731ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T22:03:45.168944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.604229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:45.16897Z","caller":"traceutil/trace.go:171","msg":"trace[939252337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23790; }","duration":"631.6731ms","start":"2026-03-06T22:03:44.537291Z","end":"2026-03-06T22:03:45.168964Z","steps":["trace[939252337] 'agreement among raft nodes before linearized reading' (duration: 631.634899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.168989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.537265Z","time spent":"631.721391ms","remote":"127.0.0.1:45106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:03:45.168524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.546516ms","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-03-06T22:03:45.169159Z","caller":"traceutil/trace.go:171","msg":"trace[308023271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23790; }","duration":"414.229591ms","start":"2026-03-06T22:03:44.754898Z","end":"2026-03-06T22:03:45.169127Z","steps":["trace[308023271] 'agreement among raft nodes before linearized reading' (duration: 413.522096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.169094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.342929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:45.169246Z","caller":"traceutil/trace.go:171","msg":"trace[160300956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23790; }","duration":"636.501973ms","start":"2026-03-06T22:03:44.532718Z","end":"2026-03-06T22:03:45.16922Z","steps":["trace[160300956] 'agreement among raft nodes before linearized reading' (duration: 636.35682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.169266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.754859Z","time spent":"414.364554ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:03:45.169284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:44.532701Z","time spent":"636.573944ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T22:03:45.385284Z","caller":"traceutil/trace.go:171","msg":"trace[724547464] transaction","detail":"{read_only:false; response_revision:23791; number_of_response:1; }","duration":"213.425815ms","start":"2026-03-06T22:03:45.171811Z","end":"2026-03-06T22:03:45.385237Z","steps":["trace[724547464] 'process raft request' (duration: 149.5015ms)","trace[724547464] 'compare' (duration: 63.772231ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:03:45.47675Z","caller":"traceutil/trace.go:171","msg":"trace[1661818136] transaction","detail":"{read_only:false; response_revision:23792; number_of_response:1; }","duration":"303.845461ms","start":"2026-03-06T22:03:45.172884Z","end":"2026-03-06T22:03:45.476729Z","steps":["trace[1661818136] 'process raft request' (duration: 303.705518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.476866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:45.172869Z","time spent":"303.933762ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:45.477158Z","caller":"traceutil/trace.go:171","msg":"trace[98294264] transaction","detail":"{read_only:false; response_revision:23793; number_of_response:1; }","duration":"304.132046ms","start":"2026-03-06T22:03:45.172994Z","end":"2026-03-06T22:03:45.477126Z","steps":["trace[98294264] 'process raft request' (duration: 303.691957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.477326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:45.172985Z","time spent":"304.239549ms","remote":"127.0.0.1:45400","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-03-06T22:03:45.477365Z","caller":"traceutil/trace.go:171","msg":"trace[455438573] transaction","detail":"{read_only:false; response_revision:23794; number_of_response:1; }","duration":"303.402661ms","start":"2026-03-06T22:03:45.173956Z","end":"2026-03-06T22:03:45.477359Z","steps":["trace[455438573] 'process raft request' (duration: 303.074084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.47741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:45.173943Z","time spent":"303.442063ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:03:45.477505Z","caller":"traceutil/trace.go:171","msg":"trace[543767897] transaction","detail":"{read_only:false; response_revision:23796; number_of_response:1; }","duration":"303.32215ms","start":"2026-03-06T22:03:45.174174Z","end":"2026-03-06T22:03:45.477496Z","steps":["trace[543767897] 'process raft request' (duration: 303.131516ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:45.477338Z","caller":"traceutil/trace.go:171","msg":"trace[1525263557] transaction","detail":"{read_only:false; response_revision:23795; number_of_response:1; }","duration":"303.212167ms","start":"2026-03-06T22:03:45.174117Z","end":"2026-03-06T22:03:45.477329Z","steps":["trace[1525263557] 'process raft request' (duration: 303.147486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:45.477563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:45.174164Z","time spent":"303.35493ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:45.477672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:03:45.174108Z","time spent":"303.459992ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:03:45.657051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.482864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:03:45.657164Z","caller":"traceutil/trace.go:171","msg":"trace[1406613454] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:23796; }","duration":"120.657589ms","start":"2026-03-06T22:03:45.53648Z","end":"2026-03-06T22:03:45.657137Z","steps":["trace[1406613454] 'count revisions from in-memory index tree' (duration: 120.349032ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:45.74168Z","caller":"traceutil/trace.go:171","msg":"trace[12012408] transaction","detail":"{read_only:false; response_revision:23797; number_of_response:1; }","duration":"111.709839ms","start":"2026-03-06T22:03:45.629942Z","end":"2026-03-06T22:03:45.741652Z","steps":["trace[12012408] 'process raft request' (duration: 111.484574ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:46.095618Z","caller":"traceutil/trace.go:171","msg":"trace[1359003336] linearizableReadLoop","detail":"{readStateIndex:24620; appliedIndex:24619; }","duration":"128.009864ms","start":"2026-03-06T22:03:45.967572Z","end":"2026-03-06T22:03:46.095582Z","steps":["trace[1359003336] 'read index received' (duration: 127.761219ms)","trace[1359003336] 'applied index is now lower than readState.Index' (duration: 247.035µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:03:46.095815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.224389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:03:46.095851Z","caller":"traceutil/trace.go:171","msg":"trace[2054101226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23798; }","duration":"128.341981ms","start":"2026-03-06T22:03:45.9675Z","end":"2026-03-06T22:03:46.095842Z","steps":["trace[2054101226] 'agreement among raft nodes before linearized reading' (duration: 128.205308ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:46.095939Z","caller":"traceutil/trace.go:171","msg":"trace[885930206] transaction","detail":"{read_only:false; response_revision:23798; number_of_response:1; }","duration":"137.675579ms","start":"2026-03-06T22:03:45.958231Z","end":"2026-03-06T22:03:46.095907Z","steps":["trace[885930206] 'process raft request' (duration: 137.150868ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:47.728031Z","caller":"traceutil/trace.go:171","msg":"trace[1044529366] transaction","detail":"{read_only:false; response_revision:23806; number_of_response:1; }","duration":"160.057143ms","start":"2026-03-06T22:03:47.56794Z","end":"2026-03-06T22:03:47.727998Z","steps":["trace[1044529366] 'process raft request' (duration: 159.784987ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:47.72804Z","caller":"traceutil/trace.go:171","msg":"trace[708014093] transaction","detail":"{read_only:false; response_revision:23808; number_of_response:1; }","duration":"157.97559ms","start":"2026-03-06T22:03:47.570026Z","end":"2026-03-06T22:03:47.728001Z","steps":["trace[708014093] 'process raft request' (duration: 157.903808ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:47.728117Z","caller":"traceutil/trace.go:171","msg":"trace[301307340] transaction","detail":"{read_only:false; response_revision:23807; number_of_response:1; }","duration":"159.558482ms","start":"2026-03-06T22:03:47.568467Z","end":"2026-03-06T22:03:47.728026Z","steps":["trace[301307340] 'process raft request' (duration: 159.42009ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:47.72833Z","caller":"traceutil/trace.go:171","msg":"trace[397215214] transaction","detail":"{read_only:false; response_revision:23809; number_of_response:1; }","duration":"157.507159ms","start":"2026-03-06T22:03:47.570795Z","end":"2026-03-06T22:03:47.728302Z","steps":["trace[397215214] 'process raft request' (duration: 157.168462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:12.949054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.266275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:04:12.949163Z","caller":"traceutil/trace.go:171","msg":"trace[294662848] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24110; }","duration":"116.387647ms","start":"2026-03-06T22:04:12.832753Z","end":"2026-03-06T22:04:12.94914Z","steps":["trace[294662848] 'count revisions from in-memory index tree' (duration: 116.153642ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:12.949201Z","caller":"traceutil/trace.go:171","msg":"trace[15242297] transaction","detail":"{read_only:false; response_revision:24111; number_of_response:1; }","duration":"111.467603ms","start":"2026-03-06T22:04:12.837682Z","end":"2026-03-06T22:04:12.94915Z","steps":["trace[15242297] 'process raft request' (duration: 56.823944ms)","trace[15242297] 'compare' (duration: 54.253751ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:04:16.022616Z","caller":"traceutil/trace.go:171","msg":"trace[1036143443] transaction","detail":"{read_only:false; response_revision:24152; number_of_response:1; }","duration":"142.587503ms","start":"2026-03-06T22:04:15.880002Z","end":"2026-03-06T22:04:16.022589Z","steps":["trace[1036143443] 'process raft request' (duration: 142.346638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:16.022829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.113096ms","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-03-06T22:04:16.022904Z","caller":"traceutil/trace.go:171","msg":"trace[1254600043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24152; }","duration":"128.235199ms","start":"2026-03-06T22:04:15.894651Z","end":"2026-03-06T22:04:16.022886Z","steps":["trace[1254600043] 'agreement among raft nodes before linearized reading' (duration: 128.025674ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:16.023016Z","caller":"traceutil/trace.go:171","msg":"trace[1274990439] linearizableReadLoop","detail":"{readStateIndex:24982; appliedIndex:24981; }","duration":"127.852861ms","start":"2026-03-06T22:04:15.894698Z","end":"2026-03-06T22:04:16.022551Z","steps":["trace[1274990439] 'read index received' (duration: 127.615526ms)","trace[1274990439] 'applied index is now lower than readState.Index' (duration: 236.055µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:04:16.023311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.441257ms","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-03-06T22:04:16.023356Z","caller":"traceutil/trace.go:171","msg":"trace[1145187119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24152; }","duration":"115.512038ms","start":"2026-03-06T22:04:15.907833Z","end":"2026-03-06T22:04:16.023345Z","steps":["trace[1145187119] 'agreement among raft nodes before linearized reading' (duration: 115.388476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:16.023367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.149328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-06T22:04:16.023409Z","caller":"traceutil/trace.go:171","msg":"trace[1811385462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24152; }","duration":"113.222239ms","start":"2026-03-06T22:04:15.910177Z","end":"2026-03-06T22:04:16.023399Z","steps":["trace[1811385462] 'agreement among raft nodes before linearized reading' (duration: 113.106687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:16.023498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.73494ms","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-03-06T22:04:16.023527Z","caller":"traceutil/trace.go:171","msg":"trace[830229459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24152; }","duration":"113.799072ms","start":"2026-03-06T22:04:15.90972Z","end":"2026-03-06T22:04:16.023519Z","steps":["trace[830229459] 'agreement among raft nodes before linearized reading' (duration: 113.7214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:16.023665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.418405ms","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-03-06T22:04:16.023697Z","caller":"traceutil/trace.go:171","msg":"trace[1129995137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24152; }","duration":"114.472505ms","start":"2026-03-06T22:04:15.909217Z","end":"2026-03-06T22:04:16.023689Z","steps":["trace[1129995137] 'agreement among raft nodes before linearized reading' (duration: 114.393004ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:16.271505Z","caller":"traceutil/trace.go:171","msg":"trace[44105443] transaction","detail":"{read_only:false; response_revision:24153; number_of_response:1; }","duration":"243.600674ms","start":"2026-03-06T22:04:16.027866Z","end":"2026-03-06T22:04:16.271467Z","steps":["trace[44105443] 'process raft request' (duration: 202.443991ms)","trace[44105443] 'compare' (duration: 40.857216ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:04:16.305947Z","caller":"traceutil/trace.go:171","msg":"trace[578448502] transaction","detail":"{read_only:false; response_revision:24154; number_of_response:1; }","duration":"277.472672ms","start":"2026-03-06T22:04:16.028408Z","end":"2026-03-06T22:04:16.30588Z","steps":["trace[578448502] 'process raft request' (duration: 277.130534ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:16.306269Z","caller":"traceutil/trace.go:171","msg":"trace[1419939332] transaction","detail":"{read_only:false; response_revision:24155; number_of_response:1; }","duration":"277.745917ms","start":"2026-03-06T22:04:16.028502Z","end":"2026-03-06T22:04:16.306248Z","steps":["trace[1419939332] 'process raft request' (duration: 277.187286ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:16.307154Z","caller":"traceutil/trace.go:171","msg":"trace[965509655] transaction","detail":"{read_only:false; response_revision:24156; number_of_response:1; }","duration":"278.29858ms","start":"2026-03-06T22:04:16.028835Z","end":"2026-03-06T22:04:16.307134Z","steps":["trace[965509655] 'process raft request' (duration: 277.176765ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:16.307311Z","caller":"traceutil/trace.go:171","msg":"trace[604297009] transaction","detail":"{read_only:false; response_revision:24157; number_of_response:1; }","duration":"277.490162ms","start":"2026-03-06T22:04:16.029808Z","end":"2026-03-06T22:04:16.307299Z","steps":["trace[604297009] 'process raft request' (duration: 277.011292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:16.560384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.11695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:04:16.560484Z","caller":"traceutil/trace.go:171","msg":"trace[1572847101] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:24158; }","duration":"123.307424ms","start":"2026-03-06T22:04:16.437154Z","end":"2026-03-06T22:04:16.560461Z","steps":["trace[1572847101] 'range keys from in-memory index tree' (duration: 122.973917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:16.560714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.001197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:04:16.560735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.564819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:04:16.56081Z","caller":"traceutil/trace.go:171","msg":"trace[536196413] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:24158; }","duration":"123.132551ms","start":"2026-03-06T22:04:16.437655Z","end":"2026-03-06T22:04:16.560787Z","steps":["trace[536196413] 'range keys from in-memory index tree' (duration: 122.810434ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:16.560827Z","caller":"traceutil/trace.go:171","msg":"trace[342333234] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:24158; }","duration":"123.704573ms","start":"2026-03-06T22:04:16.437092Z","end":"2026-03-06T22:04:16.560797Z","steps":["trace[342333234] 'range keys from in-memory index tree' (duration: 123.478608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:16.560598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.955037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:04:16.561066Z","caller":"traceutil/trace.go:171","msg":"trace[1580847270] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:24158; }","duration":"123.469977ms","start":"2026-03-06T22:04:16.437578Z","end":"2026-03-06T22:04:16.561048Z","steps":["trace[1580847270] 'range keys from in-memory index tree' (duration: 122.887225ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:05:14.945904Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21911} {"level":"info","ts":"2026-03-06T22:05:15.0931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21911,"took":"142.134882ms","hash":4026885060,"current-db-size-bytes":49770496,"current-db-size":"50 MB","current-db-size-in-use-bytes":32890880,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-06T22:05:15.093164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4026885060,"revision":21911,"compact-revision":18873} {"level":"info","ts":"2026-03-06T22:07:29.208683Z","caller":"traceutil/trace.go:171","msg":"trace[315526946] transaction","detail":"{read_only:false; response_revision:26023; number_of_response:1; }","duration":"108.017499ms","start":"2026-03-06T22:07:29.100613Z","end":"2026-03-06T22:07:29.208631Z","steps":["trace[315526946] 'process raft request' (duration: 85.304997ms)","trace[315526946] 'compare' (duration: 22.545998ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:10:14.953431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24725} {"level":"info","ts":"2026-03-06T22:10:15.096954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24725,"took":"137.632338ms","hash":3729068734,"current-db-size-bytes":50614272,"current-db-size":"51 MB","current-db-size-in-use-bytes":33779712,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-06T22:10:15.097035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3729068734,"revision":24725,"compact-revision":21911} {"level":"info","ts":"2026-03-06T22:14:41.710408Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"344940fc142d902f","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-06T22:14:41.715314Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-03-06T22:14:41.715441Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-03-06T22:15:14.960511Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27223} {"level":"info","ts":"2026-03-06T22:15:15.108287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27223,"took":"141.974479ms","hash":2639674504,"current-db-size-bytes":50614272,"current-db-size":"51 MB","current-db-size-in-use-bytes":27148288,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-06T22:15:15.108424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639674504,"revision":27223,"compact-revision":24725} {"level":"info","ts":"2026-03-06T22:20:14.970709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29293} {"level":"info","ts":"2026-03-06T22:20:15.102969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29293,"took":"127.219427ms","hash":4269718228,"current-db-size-bytes":50614272,"current-db-size":"51 MB","current-db-size-in-use-bytes":28155904,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-06T22:20:15.103061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4269718228,"revision":29293,"compact-revision":27223} {"level":"info","ts":"2026-03-06T22:25:14.979479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31409} {"level":"info","ts":"2026-03-06T22:25:15.13419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31409,"took":"148.799516ms","hash":1891638287,"current-db-size-bytes":50614272,"current-db-size":"51 MB","current-db-size-in-use-bytes":29265920,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-06T22:25:15.134284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1891638287,"revision":31409,"compact-revision":29293} {"level":"info","ts":"2026-03-06T22:29:17.330952Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000008e18.wal"} {"level":"info","ts":"2026-03-06T22:30:14.985981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33561} {"level":"info","ts":"2026-03-06T22:30:15.126366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33561,"took":"132.284673ms","hash":2306033634,"current-db-size-bytes":51920896,"current-db-size":"52 MB","current-db-size-in-use-bytes":29282304,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-06T22:30:15.126457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2306033634,"revision":33561,"compact-revision":31409} {"level":"info","ts":"2026-03-06T22:35:14.994668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35641} {"level":"info","ts":"2026-03-06T22:35:15.115802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35641,"took":"114.772252ms","hash":3593837821,"current-db-size-bytes":51920896,"current-db-size":"52 MB","current-db-size-in-use-bytes":28463104,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-06T22:35:15.115936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3593837821,"revision":35641,"compact-revision":33561}