{"level":"warn","ts":"2026-08-05T11:22:54.947153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:22:54.947253Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.62: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.19.213.62:2380","--initial-cluster=instance=https://199.19.213.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.62: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-08-05T11:22:54.94734Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:22:54.947366Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-08-05T11:22:54.947397Z","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-08-05T11:22:54.948176Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"]} {"level":"info","ts":"2026-08-05T11:22:54.948293Z","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.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.62: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-08-05T11:22:54.951487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.905875ms"} {"level":"info","ts":"2026-08-05T11:22:54.957133Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2451868e402db19e","cluster-id":"1953d1daf63e3757"} {"level":"info","ts":"2026-08-05T11:22:54.957203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:22:54.957221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 0"} {"level":"info","ts":"2026-08-05T11:22:54.957235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2451868e402db19e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T11:22:54.957252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 1"} {"level":"info","ts":"2026-08-05T11:22:54.957309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"warn","ts":"2026-08-05T11:22:54.961425Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:22:54.962748Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:22:54.963936Z","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-08-05T11:22:54.96608Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2451868e402db19e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T11:22:54.966301Z","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-08-05T11:22:54.966292Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2451868e402db19e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T11:22:54.966388Z","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-08-05T11:22:54.966468Z","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-08-05T11:22:54.968458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"info","ts":"2026-08-05T11:22:54.968574Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","added-peer-id":"2451868e402db19e","added-peer-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-08-05T11:22:54.97164Z","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-08-05T11:22:54.971784Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-08-05T11:22:54.971854Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-08-05T11:22:54.971974Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2451868e402db19e","initial-advertise-peer-urls":["https://199.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T11:22:54.972035Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:22:55.157798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:22:55.157859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:22:55.157897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgPreVoteResp from 2451868e402db19e at term 1"} {"level":"info","ts":"2026-08-05T11:22:55.157921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:22:55.157937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgVoteResp from 2451868e402db19e at term 2"} {"level":"info","ts":"2026-08-05T11:22:55.157983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became leader at term 2"} {"level":"info","ts":"2026-08-05T11:22:55.15801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2451868e402db19e elected leader 2451868e402db19e at term 2"} {"level":"info","ts":"2026-08-05T11:22:55.159298Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2451868e402db19e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.62:2379]}","request-path":"/0/members/2451868e402db19e/attributes","cluster-id":"1953d1daf63e3757","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T11:22:55.159351Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:55.15956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:22:55.159584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:22:55.159886Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:22:55.159934Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:22:55.160541Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:55.16064Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:55.160674Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:22:55.16302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.62:2379"} {"level":"info","ts":"2026-08-05T11:22:55.163135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T11:23:38.573713Z","caller":"traceutil/trace.go:171","msg":"trace[617939540] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"146.701074ms","start":"2026-08-05T11:23:38.426968Z","end":"2026-08-05T11:23:38.573669Z","steps":["trace[617939540] 'process raft request' (duration: 145.712624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:38.811779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.70019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:23:38.811902Z","caller":"traceutil/trace.go:171","msg":"trace[219708368] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:581; }","duration":"179.918895ms","start":"2026-08-05T11:23:38.631961Z","end":"2026-08-05T11:23:38.81188Z","steps":["trace[219708368] 'range keys from in-memory index tree' (duration: 179.596048ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:39.305347Z","caller":"traceutil/trace.go:171","msg":"trace[1742332093] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"316.324816ms","start":"2026-08-05T11:23:38.988996Z","end":"2026-08-05T11:23:39.30532Z","steps":["trace[1742332093] 'read index received' (duration: 316.157682ms)","trace[1742332093] 'applied index is now lower than readState.Index' (duration: 166.034µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:23:39.305444Z","caller":"traceutil/trace.go:171","msg":"trace[844983310] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"317.129933ms","start":"2026-08-05T11:23:38.988289Z","end":"2026-08-05T11:23:39.305419Z","steps":["trace[844983310] 'process raft request' (duration: 316.646263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:39.305662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.56084ms","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-08-05T11:23:39.305731Z","caller":"traceutil/trace.go:171","msg":"trace[1537310646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"316.760346ms","start":"2026-08-05T11:23:38.988954Z","end":"2026-08-05T11:23:39.305715Z","steps":["trace[1537310646] 'agreement among raft nodes before linearized reading' (duration: 316.490089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:39.305893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:38.988274Z","time spent":"317.229404ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:23:39.305953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:38.988939Z","time spent":"316.881738ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T11:23:39.323051Z","caller":"traceutil/trace.go:171","msg":"trace[1777833898] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"333.182046ms","start":"2026-08-05T11:23:38.989841Z","end":"2026-08-05T11:23:39.323023Z","steps":["trace[1777833898] 'process raft request' (duration: 326.391044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:39.323208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:38.98983Z","time spent":"333.294318ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:23:39.326323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.596146ms","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-08-05T11:23:39.326597Z","caller":"traceutil/trace.go:171","msg":"trace[1432647411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"336.852871ms","start":"2026-08-05T11:23:38.989689Z","end":"2026-08-05T11:23:39.326542Z","steps":["trace[1432647411] 'agreement among raft nodes before linearized reading' (duration: 335.945253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:23:39.326653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:23:38.989683Z","time spent":"336.955613ms","remote":"127.0.0.1:47572","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-08-05T11:25:09.426882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.872425ms","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-08-05T11:25:09.426996Z","caller":"traceutil/trace.go:171","msg":"trace[1511621047] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"168.039789ms","start":"2026-08-05T11:25:09.258934Z","end":"2026-08-05T11:25:09.426974Z","steps":["trace[1511621047] 'range keys from in-memory index tree' (duration: 167.62371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:00.488747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.223182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:26:00.488872Z","caller":"traceutil/trace.go:171","msg":"trace[1955951265] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"339.696052ms","start":"2026-08-05T11:26:00.149156Z","end":"2026-08-05T11:26:00.488852Z","steps":["trace[1955951265] 'read index received' (duration: 143.139093ms)","trace[1955951265] 'applied index is now lower than readState.Index' (duration: 196.555849ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:26:00.488969Z","caller":"traceutil/trace.go:171","msg":"trace[186623917] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"348.299119ms","start":"2026-08-05T11:26:00.140612Z","end":"2026-08-05T11:26:00.488911Z","steps":["trace[186623917] 'process raft request' (duration: 151.6922ms)","trace[186623917] 'compare' (duration: 196.114739ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:26:00.489097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.929907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T11:26:00.489179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:00.140585Z","time spent":"348.494465ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:26:00.489198Z","caller":"traceutil/trace.go:171","msg":"trace[1254102367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2151; }","duration":"340.077561ms","start":"2026-08-05T11:26:00.149099Z","end":"2026-08-05T11:26:00.489176Z","steps":["trace[1254102367] 'agreement among raft nodes before linearized reading' (duration: 339.831585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:00.489247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:00.149081Z","time spent":"340.157242ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T11:26:00.520918Z","caller":"traceutil/trace.go:171","msg":"trace[1239222820] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"226.407477ms","start":"2026-08-05T11:26:00.29448Z","end":"2026-08-05T11:26:00.520887Z","steps":["trace[1239222820] 'process raft request' (duration: 226.281474ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:00.828851Z","caller":"traceutil/trace.go:171","msg":"trace[832459503] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"390.100015ms","start":"2026-08-05T11:26:00.43872Z","end":"2026-08-05T11:26:00.82882Z","steps":["trace[832459503] 'process raft request' (duration: 389.8341ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:00.829019Z","caller":"traceutil/trace.go:171","msg":"trace[1462629453] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2232; }","duration":"340.090511ms","start":"2026-08-05T11:26:00.488898Z","end":"2026-08-05T11:26:00.828988Z","steps":["trace[1462629453] 'read index received' (duration: 31.86618ms)","trace[1462629453] 'applied index is now lower than readState.Index' (duration: 308.222741ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:26:00.829063Z","caller":"traceutil/trace.go:171","msg":"trace[567813748] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"335.595777ms","start":"2026-08-05T11:26:00.493437Z","end":"2026-08-05T11:26:00.829032Z","steps":["trace[567813748] 'process raft request' (duration: 335.305102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:00.829185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.767574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:26:00.829228Z","caller":"traceutil/trace.go:171","msg":"trace[1880891196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2154; }","duration":"368.869266ms","start":"2026-08-05T11:26:00.460348Z","end":"2026-08-05T11:26:00.829217Z","steps":["trace[1880891196] 'agreement among raft nodes before linearized reading' (duration: 368.765044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:00.829224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:00.493415Z","time spent":"335.72833ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:26:00.829269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:00.460324Z","time spent":"368.936937ms","remote":"127.0.0.1:47332","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-08-05T11:26:00.829048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:00.438693Z","time spent":"390.264548ms","remote":"127.0.0.1:47572","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-08-05T11:26:00.895137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.056466ms","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-08-05T11:26:00.895232Z","caller":"traceutil/trace.go:171","msg":"trace[812452811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2155; }","duration":"306.191449ms","start":"2026-08-05T11:26:00.589013Z","end":"2026-08-05T11:26:00.895205Z","steps":["trace[812452811] 'agreement among raft nodes before linearized reading' (duration: 305.953634ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:00.895224Z","caller":"traceutil/trace.go:171","msg":"trace[953448701] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"131.725258ms","start":"2026-08-05T11:26:00.763462Z","end":"2026-08-05T11:26:00.895187Z","steps":["trace[953448701] 'process raft request' (duration: 131.309609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:00.895303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:00.588994Z","time spent":"306.289771ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-05T11:27:34.049579Z","caller":"traceutil/trace.go:171","msg":"trace[702933634] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"143.768955ms","start":"2026-08-05T11:27:33.905778Z","end":"2026-08-05T11:27:34.049547Z","steps":["trace[702933634] 'process raft request' (duration: 143.609762ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:27:34.138511Z","caller":"traceutil/trace.go:171","msg":"trace[2090254396] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"100.41211ms","start":"2026-08-05T11:27:34.038071Z","end":"2026-08-05T11:27:34.138483Z","steps":["trace[2090254396] 'process raft request' (duration: 74.248224ms)","trace[2090254396] 'compare' (duration: 26.078765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:31:27.261152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.203065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-05T11:31:27.261276Z","caller":"traceutil/trace.go:171","msg":"trace[769764222] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5044; }","duration":"184.639284ms","start":"2026-08-05T11:31:27.076611Z","end":"2026-08-05T11:31:27.26125Z","steps":["trace[769764222] 'process raft request' (duration: 60.231715ms)","trace[769764222] 'compare' (duration: 124.167065ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:31:27.650151Z","caller":"traceutil/trace.go:171","msg":"trace[1757212384] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"145.25109ms","start":"2026-08-05T11:31:27.504871Z","end":"2026-08-05T11:31:27.650122Z","steps":["trace[1757212384] 'process raft request' (duration: 125.235767ms)","trace[1757212384] 'compare' (duration: 19.886981ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:31:27.651879Z","caller":"traceutil/trace.go:171","msg":"trace[1531574562] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"112.848896ms","start":"2026-08-05T11:31:27.539018Z","end":"2026-08-05T11:31:27.651867Z","steps":["trace[1531574562] 'process raft request' (duration: 112.750804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:31:40.242473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.694502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:31:40.242593Z","caller":"traceutil/trace.go:171","msg":"trace[1345903749] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"127.343236ms","start":"2026-08-05T11:31:40.115229Z","end":"2026-08-05T11:31:40.242572Z","steps":["trace[1345903749] 'process raft request' (duration: 13.46412ms)","trace[1345903749] 'compare' (duration: 113.401506ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:31:40.289429Z","caller":"traceutil/trace.go:171","msg":"trace[744806305] linearizableReadLoop","detail":"{readStateIndex:5515; appliedIndex:5514; }","duration":"114.024357ms","start":"2026-08-05T11:31:40.175383Z","end":"2026-08-05T11:31:40.289408Z","steps":["trace[744806305] 'read index received' (duration: 113.899185ms)","trace[744806305] 'applied index is now lower than readState.Index' (duration: 123.912µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:31:40.289805Z","caller":"traceutil/trace.go:171","msg":"trace[999982105] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"117.164933ms","start":"2026-08-05T11:31:40.172619Z","end":"2026-08-05T11:31:40.289784Z","steps":["trace[999982105] 'process raft request' (duration: 116.676763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:31:40.290104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.713002ms","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-08-05T11:31:40.290149Z","caller":"traceutil/trace.go:171","msg":"trace[1516192302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5314; }","duration":"114.806374ms","start":"2026-08-05T11:31:40.175332Z","end":"2026-08-05T11:31:40.290138Z","steps":["trace[1516192302] 'agreement among raft nodes before linearized reading' (duration: 114.660281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:31:40.290375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.940651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nkvh5\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-08-05T11:31:40.290414Z","caller":"traceutil/trace.go:171","msg":"trace[193203666] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nkvh5; range_end:; response_count:1; response_revision:5314; }","duration":"108.026283ms","start":"2026-08-05T11:31:40.182377Z","end":"2026-08-05T11:31:40.290403Z","steps":["trace[193203666] 'agreement among raft nodes before linearized reading' (duration: 107.90751ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:31:48.481828Z","caller":"traceutil/trace.go:171","msg":"trace[120014150] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"121.834381ms","start":"2026-08-05T11:31:48.359958Z","end":"2026-08-05T11:31:48.481793Z","steps":["trace[120014150] 'process raft request' (duration: 116.419858ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:31:48.481846Z","caller":"traceutil/trace.go:171","msg":"trace[1449841803] linearizableReadLoop","detail":"{readStateIndex:5687; appliedIndex:5686; }","duration":"106.194915ms","start":"2026-08-05T11:31:48.375567Z","end":"2026-08-05T11:31:48.481762Z","steps":["trace[1449841803] 'read index received' (duration: 100.705971ms)","trace[1449841803] 'applied index is now lower than readState.Index' (duration: 5.487874ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:31:48.482076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.486541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nkvh5\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-08-05T11:31:48.482122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.792565ms","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-08-05T11:31:48.482139Z","caller":"traceutil/trace.go:171","msg":"trace[1635575633] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nkvh5; range_end:; response_count:1; response_revision:5484; }","duration":"106.609314ms","start":"2026-08-05T11:31:48.375514Z","end":"2026-08-05T11:31:48.482123Z","steps":["trace[1635575633] 'agreement among raft nodes before linearized reading' (duration: 106.362649ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:31:48.48216Z","caller":"traceutil/trace.go:171","msg":"trace[1925718987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5484; }","duration":"104.915427ms","start":"2026-08-05T11:31:48.377233Z","end":"2026-08-05T11:31:48.482148Z","steps":["trace[1925718987] 'agreement among raft nodes before linearized reading' (duration: 104.753534ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:32:55.343527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-08-05T11:32:55.410499Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"64.648724ms","hash":309339047,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":34054144,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T11:32:55.410557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":309339047,"revision":2969,"compact-revision":-1} {"level":"info","ts":"2026-08-05T11:37:55.353028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6093} {"level":"info","ts":"2026-08-05T11:37:55.457589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6093,"took":"101.17039ms","hash":2781925653,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":21549056,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-05T11:37:55.457659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2781925653,"revision":6093,"compact-revision":2969} {"level":"info","ts":"2026-08-05T11:38:00.239816Z","caller":"traceutil/trace.go:171","msg":"trace[351229426] transaction","detail":"{read_only:false; response_revision:8339; number_of_response:1; }","duration":"248.98956ms","start":"2026-08-05T11:37:59.990791Z","end":"2026-08-05T11:38:00.239781Z","steps":["trace[351229426] 'process raft request' (duration: 248.751196ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:38:00.410956Z","caller":"traceutil/trace.go:171","msg":"trace[696831734] transaction","detail":"{read_only:false; response_revision:8340; number_of_response:1; }","duration":"381.908146ms","start":"2026-08-05T11:38:00.029008Z","end":"2026-08-05T11:38:00.410916Z","steps":["trace[696831734] 'process raft request' (duration: 345.830342ms)","trace[696831734] 'compare' (duration: 35.600305ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:38:00.411173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:38:00.028981Z","time spent":"382.06465ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:38:12.106201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.076436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:38:12.106295Z","caller":"traceutil/trace.go:171","msg":"trace[1022243652] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8401; }","duration":"220.24686ms","start":"2026-08-05T11:38:11.886027Z","end":"2026-08-05T11:38:12.106274Z","steps":["trace[1022243652] 'count revisions from in-memory index tree' (duration: 219.919853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:12.106527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.521916ms","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-08-05T11:38:12.106639Z","caller":"traceutil/trace.go:171","msg":"trace[1701210442] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8401; }","duration":"105.69907ms","start":"2026-08-05T11:38:12.000911Z","end":"2026-08-05T11:38:12.10661Z","steps":["trace[1701210442] 'count revisions from in-memory index tree' (duration: 105.428833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:13.029666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.855268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:38:13.029763Z","caller":"traceutil/trace.go:171","msg":"trace[309026100] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8407; }","duration":"212.031821ms","start":"2026-08-05T11:38:12.817707Z","end":"2026-08-05T11:38:13.029739Z","steps":["trace[309026100] 'count revisions from in-memory index tree' (duration: 211.755376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:13.029865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.491188ms","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-08-05T11:38:13.02997Z","caller":"traceutil/trace.go:171","msg":"trace[467610586] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8407; }","duration":"181.689802ms","start":"2026-08-05T11:38:12.848258Z","end":"2026-08-05T11:38:13.029948Z","steps":["trace[467610586] 'count revisions from in-memory index tree' (duration: 181.364584ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:38:40.806335Z","caller":"traceutil/trace.go:171","msg":"trace[79961177] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"299.224593ms","start":"2026-08-05T11:38:40.507088Z","end":"2026-08-05T11:38:40.806312Z","steps":["trace[79961177] 'process raft request' (duration: 299.09164ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:38:40.80694Z","caller":"traceutil/trace.go:171","msg":"trace[1701133609] linearizableReadLoop","detail":"{readStateIndex:8915; appliedIndex:8913; }","duration":"194.520443ms","start":"2026-08-05T11:38:40.612406Z","end":"2026-08-05T11:38:40.806927Z","steps":["trace[1701133609] 'read index received' (duration: 193.704687ms)","trace[1701133609] 'applied index is now lower than readState.Index' (duration: 814.976µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:38:40.807231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.807709ms","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-08-05T11:38:40.807558Z","caller":"traceutil/trace.go:171","msg":"trace[385325978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8609; }","duration":"195.173766ms","start":"2026-08-05T11:38:40.612369Z","end":"2026-08-05T11:38:40.807543Z","steps":["trace[385325978] 'agreement among raft nodes before linearized reading' (duration: 194.755948ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:38:40.808077Z","caller":"traceutil/trace.go:171","msg":"trace[681299628] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"255.56966ms","start":"2026-08-05T11:38:40.551428Z","end":"2026-08-05T11:38:40.806997Z","steps":["trace[681299628] 'process raft request' (duration: 255.379567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:54.364881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.728295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-wrzbr\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-08-05T11:38:54.364962Z","caller":"traceutil/trace.go:171","msg":"trace[1136340387] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-wrzbr; range_end:; response_count:1; response_revision:8720; }","duration":"118.849667ms","start":"2026-08-05T11:38:54.246099Z","end":"2026-08-05T11:38:54.364949Z","steps":["trace[1136340387] 'range keys from in-memory index tree' (duration: 118.547391ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:38:54.376994Z","caller":"traceutil/trace.go:171","msg":"trace[1376516088] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"130.288951ms","start":"2026-08-05T11:38:54.246689Z","end":"2026-08-05T11:38:54.376978Z","steps":["trace[1376516088] 'process raft request' (duration: 130.167979ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:38:55.050229Z","caller":"traceutil/trace.go:171","msg":"trace[1949082055] linearizableReadLoop","detail":"{readStateIndex:9039; appliedIndex:9038; }","duration":"151.196028ms","start":"2026-08-05T11:38:54.898997Z","end":"2026-08-05T11:38:55.050193Z","steps":["trace[1949082055] 'read index received' (duration: 119.873487ms)","trace[1949082055] 'applied index is now lower than readState.Index' (duration: 31.321131ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:38:55.050409Z","caller":"traceutil/trace.go:171","msg":"trace[1796052145] transaction","detail":"{read_only:false; response_revision:8725; number_of_response:1; }","duration":"160.111781ms","start":"2026-08-05T11:38:54.89027Z","end":"2026-08-05T11:38:55.050382Z","steps":["trace[1796052145] 'process raft request' (duration: 128.603556ms)","trace[1796052145] 'compare' (duration: 31.160538ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:38:55.050606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.582216ms","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-08-05T11:38:55.050669Z","caller":"traceutil/trace.go:171","msg":"trace[848329967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8725; }","duration":"151.683498ms","start":"2026-08-05T11:38:54.898971Z","end":"2026-08-05T11:38:55.050655Z","steps":["trace[848329967] 'agreement among raft nodes before linearized reading' (duration: 151.455064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:55.05082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.019085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-05T11:38:55.050869Z","caller":"traceutil/trace.go:171","msg":"trace[1656695082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8725; }","duration":"150.100006ms","start":"2026-08-05T11:38:54.900757Z","end":"2026-08-05T11:38:55.050857Z","steps":["trace[1656695082] 'agreement among raft nodes before linearized reading' (duration: 149.948963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:55.050978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.997249ms","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-08-05T11:38:55.051009Z","caller":"traceutil/trace.go:171","msg":"trace[421467124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8725; }","duration":"138.029229ms","start":"2026-08-05T11:38:54.912972Z","end":"2026-08-05T11:38:55.051001Z","steps":["trace[421467124] 'agreement among raft nodes before linearized reading' (duration: 137.951688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:55.051125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.209295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-05T11:38:55.051155Z","caller":"traceutil/trace.go:171","msg":"trace[1500731680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8725; }","duration":"143.259646ms","start":"2026-08-05T11:38:54.907888Z","end":"2026-08-05T11:38:55.051147Z","steps":["trace[1500731680] 'agreement among raft nodes before linearized reading' (duration: 143.179855ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:39:11.686165Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023fb.wal"} {"level":"info","ts":"2026-08-05T11:40:35.811137Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T11:40:35.818682Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T11:40:35.81882Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T11:42:55.358859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8184} {"level":"info","ts":"2026-08-05T11:42:55.422603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8184,"took":"61.073806ms","hash":621316961,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":27795456,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T11:42:55.422687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":621316961,"revision":8184,"compact-revision":6093} {"level":"info","ts":"2026-08-05T11:44:23.900242Z","caller":"traceutil/trace.go:171","msg":"trace[1847491985] transaction","detail":"{read_only:false; response_revision:11098; number_of_response:1; }","duration":"108.211472ms","start":"2026-08-05T11:44:23.791986Z","end":"2026-08-05T11:44:23.900197Z","steps":["trace[1847491985] 'process raft request' (duration: 107.529449ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:20.485183Z","caller":"traceutil/trace.go:171","msg":"trace[120736935] transaction","detail":"{read_only:false; response_revision:11715; number_of_response:1; }","duration":"129.172092ms","start":"2026-08-05T11:45:20.355958Z","end":"2026-08-05T11:45:20.48513Z","steps":["trace[120736935] 'process raft request' (duration: 128.861226ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:31.291354Z","caller":"traceutil/trace.go:171","msg":"trace[280759907] transaction","detail":"{read_only:false; response_revision:11797; number_of_response:1; }","duration":"137.050056ms","start":"2026-08-05T11:45:31.154269Z","end":"2026-08-05T11:45:31.291319Z","steps":["trace[280759907] 'process raft request' (duration: 110.820343ms)","trace[280759907] 'compare' (duration: 25.969847ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:45:31.291381Z","caller":"traceutil/trace.go:171","msg":"trace[1155271842] transaction","detail":"{read_only:false; response_revision:11798; number_of_response:1; }","duration":"136.633176ms","start":"2026-08-05T11:45:31.154713Z","end":"2026-08-05T11:45:31.291347Z","steps":["trace[1155271842] 'process raft request' (duration: 136.524374ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:31.291596Z","caller":"traceutil/trace.go:171","msg":"trace[858699404] transaction","detail":"{read_only:false; response_revision:11799; number_of_response:1; }","duration":"132.121684ms","start":"2026-08-05T11:45:31.159464Z","end":"2026-08-05T11:45:31.291586Z","steps":["trace[858699404] 'process raft request' (duration: 131.841398ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:31.294706Z","caller":"traceutil/trace.go:171","msg":"trace[280583384] transaction","detail":"{read_only:false; response_revision:11800; number_of_response:1; }","duration":"116.691104ms","start":"2026-08-05T11:45:31.177985Z","end":"2026-08-05T11:45:31.294676Z","steps":["trace[280583384] 'process raft request' (duration: 116.375028ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:52.510648Z","caller":"traceutil/trace.go:171","msg":"trace[1625521584] transaction","detail":"{read_only:false; response_revision:11915; number_of_response:1; }","duration":"114.49654ms","start":"2026-08-05T11:45:52.396104Z","end":"2026-08-05T11:45:52.510601Z","steps":["trace[1625521584] 'process raft request' (duration: 114.303396ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:47:55.36578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10534} {"level":"info","ts":"2026-08-05T11:47:55.443624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10534,"took":"75.642388ms","hash":1870943605,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":25825280,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-05T11:47:55.443703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1870943605,"revision":10534,"compact-revision":8184} {"level":"info","ts":"2026-08-05T11:51:46.176332Z","caller":"traceutil/trace.go:171","msg":"trace[72355644] transaction","detail":"{read_only:false; response_revision:14325; number_of_response:1; }","duration":"136.114194ms","start":"2026-08-05T11:51:46.040191Z","end":"2026-08-05T11:51:46.176306Z","steps":["trace[72355644] 'process raft request' (duration: 135.444441ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:52:10.67387Z","caller":"traceutil/trace.go:171","msg":"trace[1271278314] transaction","detail":"{read_only:false; response_revision:14456; number_of_response:1; }","duration":"129.341306ms","start":"2026-08-05T11:52:10.544469Z","end":"2026-08-05T11:52:10.67381Z","steps":["trace[1271278314] 'process raft request' (duration: 129.06439ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:52:27.231079Z","caller":"traceutil/trace.go:171","msg":"trace[384657532] transaction","detail":"{read_only:false; response_revision:14556; number_of_response:1; }","duration":"380.368703ms","start":"2026-08-05T11:52:26.850675Z","end":"2026-08-05T11:52:27.231044Z","steps":["trace[384657532] 'process raft request' (duration: 372.9253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:52:27.231411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:52:26.850659Z","time spent":"380.519856ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:52:50.361453Z","caller":"traceutil/trace.go:171","msg":"trace[1656977916] linearizableReadLoop","detail":"{readStateIndex:15392; appliedIndex:15391; }","duration":"104.583711ms","start":"2026-08-05T11:52:50.256843Z","end":"2026-08-05T11:52:50.361427Z","steps":["trace[1656977916] 'read index received' (duration: 104.318525ms)","trace[1656977916] 'applied index is now lower than readState.Index' (duration: 264.316µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:52:50.361583Z","caller":"traceutil/trace.go:171","msg":"trace[1704122488] transaction","detail":"{read_only:false; response_revision:14872; number_of_response:1; }","duration":"220.713306ms","start":"2026-08-05T11:52:50.140827Z","end":"2026-08-05T11:52:50.36154Z","steps":["trace[1704122488] 'process raft request' (duration: 220.417809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:52:50.361689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.831116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jv66n\" ","response":"range_response_count:1 size:20655"} {"level":"info","ts":"2026-08-05T11:52:50.361721Z","caller":"traceutil/trace.go:171","msg":"trace[151954342] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jv66n; range_end:; response_count:1; response_revision:14872; }","duration":"104.932138ms","start":"2026-08-05T11:52:50.256779Z","end":"2026-08-05T11:52:50.361712Z","steps":["trace[151954342] 'agreement among raft nodes before linearized reading' (duration: 104.749594ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:52:55.373418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12754} {"level":"info","ts":"2026-08-05T11:52:55.45158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12754,"took":"75.442904ms","hash":3828267295,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":29020160,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-05T11:52:55.451648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3828267295,"revision":12754,"compact-revision":10534} {"level":"info","ts":"2026-08-05T11:53:43.131573Z","caller":"traceutil/trace.go:171","msg":"trace[475729315] linearizableReadLoop","detail":"{readStateIndex:16046; appliedIndex:16045; }","duration":"101.175683ms","start":"2026-08-05T11:53:43.030369Z","end":"2026-08-05T11:53:43.131545Z","steps":["trace[475729315] 'read index received' (duration: 100.922348ms)","trace[475729315] 'applied index is now lower than readState.Index' (duration: 252.215µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:53:43.131659Z","caller":"traceutil/trace.go:171","msg":"trace[1795999342] transaction","detail":"{read_only:false; response_revision:15506; number_of_response:1; }","duration":"110.698628ms","start":"2026-08-05T11:53:43.020908Z","end":"2026-08-05T11:53:43.131607Z","steps":["trace[1795999342] 'process raft request' (duration: 110.432203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:53:43.132494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.504449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:53:43.132625Z","caller":"traceutil/trace.go:171","msg":"trace[705072650] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:15506; }","duration":"102.295225ms","start":"2026-08-05T11:53:43.030303Z","end":"2026-08-05T11:53:43.132599Z","steps":["trace[705072650] 'agreement among raft nodes before linearized reading' (duration: 101.401147ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:54:15.941229Z","caller":"traceutil/trace.go:171","msg":"trace[821251406] transaction","detail":"{read_only:false; response_revision:15675; number_of_response:1; }","duration":"121.060408ms","start":"2026-08-05T11:54:15.820135Z","end":"2026-08-05T11:54:15.941195Z","steps":["trace[821251406] 'process raft request' (duration: 120.881484ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:54:15.95388Z","caller":"traceutil/trace.go:171","msg":"trace[587467431] transaction","detail":"{read_only:false; response_revision:15676; number_of_response:1; }","duration":"102.801975ms","start":"2026-08-05T11:54:15.851044Z","end":"2026-08-05T11:54:15.953846Z","steps":["trace[587467431] 'process raft request' (duration: 102.613641ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:54:16.52653Z","caller":"traceutil/trace.go:171","msg":"trace[1124066803] transaction","detail":"{read_only:false; response_revision:15678; number_of_response:1; }","duration":"321.500035ms","start":"2026-08-05T11:54:16.205001Z","end":"2026-08-05T11:54:16.526501Z","steps":["trace[1124066803] 'process raft request' (duration: 321.307541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:54:16.526725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:54:16.204973Z","time spent":"321.643118ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:54:16.548689Z","caller":"traceutil/trace.go:171","msg":"trace[2125835845] linearizableReadLoop","detail":"{readStateIndex:16227; appliedIndex:16224; }","duration":"263.87999ms","start":"2026-08-05T11:54:16.284769Z","end":"2026-08-05T11:54:16.548649Z","steps":["trace[2125835845] 'read index received' (duration: 241.492424ms)","trace[2125835845] 'applied index is now lower than readState.Index' (duration: 22.385916ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:54:16.549112Z","caller":"traceutil/trace.go:171","msg":"trace[818658992] transaction","detail":"{read_only:false; response_revision:15679; number_of_response:1; }","duration":"344.024484ms","start":"2026-08-05T11:54:16.20507Z","end":"2026-08-05T11:54:16.549095Z","steps":["trace[818658992] 'process raft request' (duration: 343.2892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:54:16.549285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:54:16.205048Z","time spent":"344.108956ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:54:16.549538Z","caller":"traceutil/trace.go:171","msg":"trace[797794900] transaction","detail":"{read_only:false; response_revision:15680; number_of_response:1; }","duration":"342.87945ms","start":"2026-08-05T11:54:16.206642Z","end":"2026-08-05T11:54:16.549522Z","steps":["trace[797794900] 'process raft request' (duration: 341.941191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:54:16.549616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:54:16.206623Z","time spent":"342.954742ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:54:16.549991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.216387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-05T11:54:16.550043Z","caller":"traceutil/trace.go:171","msg":"trace[1783833704] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:15680; }","duration":"265.297319ms","start":"2026-08-05T11:54:16.28473Z","end":"2026-08-05T11:54:16.550027Z","steps":["trace[1783833704] 'agreement among raft nodes before linearized reading' (duration: 265.126796ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:54:16.717329Z","caller":"traceutil/trace.go:171","msg":"trace[904145357] transaction","detail":"{read_only:false; response_revision:15681; number_of_response:1; }","duration":"158.599413ms","start":"2026-08-05T11:54:16.558689Z","end":"2026-08-05T11:54:16.717288Z","steps":["trace[904145357] 'process raft request' (duration: 138.558165ms)","trace[904145357] 'compare' (duration: 19.849784ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:54:16.717396Z","caller":"traceutil/trace.go:171","msg":"trace[1016504499] linearizableReadLoop","detail":"{readStateIndex:16228; appliedIndex:16227; }","duration":"158.576064ms","start":"2026-08-05T11:54:16.558782Z","end":"2026-08-05T11:54:16.717358Z","steps":["trace[1016504499] 'read index received' (duration: 138.422563ms)","trace[1016504499] 'applied index is now lower than readState.Index' (duration: 20.150201ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:54:16.717637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.810188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:54:16.717635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.284121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:54:16.71772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.32125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-05T11:54:16.71774Z","caller":"traceutil/trace.go:171","msg":"trace[582200346] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15682; }","duration":"142.429854ms","start":"2026-08-05T11:54:16.575288Z","end":"2026-08-05T11:54:16.717718Z","steps":["trace[582200346] 'agreement among raft nodes before linearized reading' (duration: 142.297401ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:54:16.717887Z","caller":"traceutil/trace.go:171","msg":"trace[655840282] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15682; }","duration":"139.516405ms","start":"2026-08-05T11:54:16.578346Z","end":"2026-08-05T11:54:16.717863Z","steps":["trace[655840282] 'agreement among raft nodes before linearized reading' (duration: 139.27697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:54:16.718043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.056435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:54:16.718084Z","caller":"traceutil/trace.go:171","msg":"trace[2005618605] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15682; }","duration":"140.114267ms","start":"2026-08-05T11:54:16.577959Z","end":"2026-08-05T11:54:16.718073Z","steps":["trace[2005618605] 'agreement among raft nodes before linearized reading' (duration: 140.055276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:54:16.71818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.74838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:54:16.718215Z","caller":"traceutil/trace.go:171","msg":"trace[1019355724] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15682; }","duration":"142.809802ms","start":"2026-08-05T11:54:16.575394Z","end":"2026-08-05T11:54:16.718203Z","steps":["trace[1019355724] 'agreement among raft nodes before linearized reading' (duration: 142.763561ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:54:16.717687Z","caller":"traceutil/trace.go:171","msg":"trace[1786621442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15682; }","duration":"158.950061ms","start":"2026-08-05T11:54:16.558722Z","end":"2026-08-05T11:54:16.717672Z","steps":["trace[1786621442] 'agreement among raft nodes before linearized reading' (duration: 158.822988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:54:16.718509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.631352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n5nss\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-08-05T11:54:16.718565Z","caller":"traceutil/trace.go:171","msg":"trace[70312239] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n5nss; range_end:; response_count:1; response_revision:15682; }","duration":"130.688004ms","start":"2026-08-05T11:54:16.587862Z","end":"2026-08-05T11:54:16.71855Z","steps":["trace[70312239] 'agreement among raft nodes before linearized reading' (duration: 130.50033ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:57:55.380383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14914} {"level":"info","ts":"2026-08-05T11:57:55.493517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14914,"took":"108.979496ms","hash":2903381800,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":30347264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T11:57:55.493636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2903381800,"revision":14914,"compact-revision":12754} {"level":"info","ts":"2026-08-05T12:00:08.700135Z","caller":"traceutil/trace.go:171","msg":"trace[1102299485] linearizableReadLoop","detail":"{readStateIndex:18970; appliedIndex:18969; }","duration":"127.990892ms","start":"2026-08-05T12:00:08.572115Z","end":"2026-08-05T12:00:08.700105Z","steps":["trace[1102299485] 'read index received' (duration: 127.89211ms)","trace[1102299485] 'applied index is now lower than readState.Index' (duration: 96.952µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:00:08.700361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.228708ms","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-08-05T12:00:08.700391Z","caller":"traceutil/trace.go:171","msg":"trace[1970866079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18338; }","duration":"128.316939ms","start":"2026-08-05T12:00:08.572065Z","end":"2026-08-05T12:00:08.700382Z","steps":["trace[1970866079] 'agreement among raft nodes before linearized reading' (duration: 128.163166ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:00:08.700495Z","caller":"traceutil/trace.go:171","msg":"trace[506082186] transaction","detail":"{read_only:false; response_revision:18338; number_of_response:1; }","duration":"175.992959ms","start":"2026-08-05T12:00:08.524231Z","end":"2026-08-05T12:00:08.700224Z","steps":["trace[506082186] 'process raft request' (duration: 175.697513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:08.71329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.718063ms","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-08-05T12:00:08.713374Z","caller":"traceutil/trace.go:171","msg":"trace[714246057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18339; }","duration":"115.873816ms","start":"2026-08-05T12:00:08.597481Z","end":"2026-08-05T12:00:08.713355Z","steps":["trace[714246057] 'agreement among raft nodes before linearized reading' (duration: 115.647701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:15.828414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.48478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:00:15.828537Z","caller":"traceutil/trace.go:171","msg":"trace[883664449] linearizableReadLoop","detail":"{readStateIndex:19070; appliedIndex:19069; }","duration":"334.123432ms","start":"2026-08-05T12:00:15.494394Z","end":"2026-08-05T12:00:15.828518Z","steps":["trace[883664449] 'read index received' (duration: 89.334436ms)","trace[883664449] 'applied index is now lower than readState.Index' (duration: 244.787296ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:00:15.828595Z","caller":"traceutil/trace.go:171","msg":"trace[2062432175] transaction","detail":"{read_only:false; response_revision:18437; number_of_response:1; }","duration":"342.986142ms","start":"2026-08-05T12:00:15.485575Z","end":"2026-08-05T12:00:15.828561Z","steps":["trace[2062432175] 'process raft request' (duration: 98.253857ms)","trace[2062432175] 'compare' (duration: 244.095573ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:00:15.828767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.367007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9566"} {"level":"warn","ts":"2026-08-05T12:00:15.828771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.485559Z","time spent":"343.123166ms","remote":"127.0.0.1:47396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:00:15.828809Z","caller":"traceutil/trace.go:171","msg":"trace[1269586870] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18437; }","duration":"334.425209ms","start":"2026-08-05T12:00:15.494374Z","end":"2026-08-05T12:00:15.828799Z","steps":["trace[1269586870] 'agreement among raft nodes before linearized reading' (duration: 334.228125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:15.828846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.494365Z","time spent":"334.47271ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":9588,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" "} {"level":"warn","ts":"2026-08-05T12:00:15.8291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.308192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:00:15.829152Z","caller":"traceutil/trace.go:171","msg":"trace[1622354192] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:18437; }","duration":"226.411344ms","start":"2026-08-05T12:00:15.602727Z","end":"2026-08-05T12:00:15.829138Z","steps":["trace[1622354192] 'agreement among raft nodes before linearized reading' (duration: 226.325202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:15.829108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.388516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-08-05T12:00:15.82925Z","caller":"traceutil/trace.go:171","msg":"trace[20282131] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:18437; }","duration":"326.532589ms","start":"2026-08-05T12:00:15.502692Z","end":"2026-08-05T12:00:15.829225Z","steps":["trace[20282131] 'agreement among raft nodes before linearized reading' (duration: 326.344636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:15.829346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.502682Z","time spent":"326.639461ms","remote":"127.0.0.1:47410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3707,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-08-05T12:00:16.377525Z","caller":"traceutil/trace.go:171","msg":"trace[1133486243] linearizableReadLoop","detail":"{readStateIndex:19072; appliedIndex:19071; }","duration":"477.751633ms","start":"2026-08-05T12:00:15.899749Z","end":"2026-08-05T12:00:16.3775Z","steps":["trace[1133486243] 'read index received' (duration: 397.41166ms)","trace[1133486243] 'applied index is now lower than readState.Index' (duration: 80.339283ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:00:16.377552Z","caller":"traceutil/trace.go:171","msg":"trace[583689443] transaction","detail":"{read_only:false; response_revision:18439; number_of_response:1; }","duration":"478.288563ms","start":"2026-08-05T12:00:15.899226Z","end":"2026-08-05T12:00:16.377514Z","steps":["trace[583689443] 'process raft request' (duration: 397.91569ms)","trace[583689443] 'compare' (duration: 80.250751ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:00:16.377697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.899214Z","time spent":"478.409415ms","remote":"127.0.0.1:47572","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-08-05T12:00:16.377728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.967507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-08-05T12:00:16.377866Z","caller":"traceutil/trace.go:171","msg":"trace[1649101346] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:18439; }","duration":"478.11639ms","start":"2026-08-05T12:00:15.899734Z","end":"2026-08-05T12:00:16.37785Z","steps":["trace[1649101346] 'agreement among raft nodes before linearized reading' (duration: 477.847585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.377913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.899726Z","time spent":"478.174031ms","remote":"127.0.0.1:47410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":719,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" "} {"level":"info","ts":"2026-08-05T12:00:16.441007Z","caller":"traceutil/trace.go:171","msg":"trace[1859633816] transaction","detail":"{read_only:false; response_revision:18440; number_of_response:1; }","duration":"330.341637ms","start":"2026-08-05T12:00:16.110638Z","end":"2026-08-05T12:00:16.44098Z","steps":["trace[1859633816] 'process raft request' (duration: 329.96793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.441091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.526511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-bnbk8\" ","response":"range_response_count:1 size:9796"} {"level":"warn","ts":"2026-08-05T12:00:16.441144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:16.110606Z","time spent":"330.469899ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:00:16.441106Z","caller":"traceutil/trace.go:171","msg":"trace[1136212895] transaction","detail":"{read_only:false; response_revision:18441; number_of_response:1; }","duration":"238.394898ms","start":"2026-08-05T12:00:16.202656Z","end":"2026-08-05T12:00:16.44105Z","steps":["trace[1136212895] 'process raft request' (duration: 238.101132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.441259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.388738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-heat-nodes\" ","response":"range_response_count:1 size:1248"} {"level":"warn","ts":"2026-08-05T12:00:16.441234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.64414ms","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":"warn","ts":"2026-08-05T12:00:16.441238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.024586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-005f6a0f-ba82-4a92-8c3d-b9c9a658b8b5\" ","response":"range_response_count:1 size:3639"} {"level":"info","ts":"2026-08-05T12:00:16.44115Z","caller":"traceutil/trace.go:171","msg":"trace[753059418] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-bnbk8; range_end:; response_count:1; response_revision:18441; }","duration":"453.635473ms","start":"2026-08-05T12:00:15.987498Z","end":"2026-08-05T12:00:16.441133Z","steps":["trace[753059418] 'agreement among raft nodes before linearized reading' (duration: 453.433539ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:00:16.441329Z","caller":"traceutil/trace.go:171","msg":"trace[1441411211] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:18441; }","duration":"382.797183ms","start":"2026-08-05T12:00:16.05851Z","end":"2026-08-05T12:00:16.441307Z","steps":["trace[1441411211] 'agreement among raft nodes before linearized reading' (duration: 382.605749ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:00:16.441355Z","caller":"traceutil/trace.go:171","msg":"trace[740383824] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-005f6a0f-ba82-4a92-8c3d-b9c9a658b8b5; range_end:; response_count:1; response_revision:18441; }","duration":"103.213269ms","start":"2026-08-05T12:00:16.338113Z","end":"2026-08-05T12:00:16.441326Z","steps":["trace[740383824] 'agreement among raft nodes before linearized reading' (duration: 102.969444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.441369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.98748Z","time spent":"453.861398ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9818,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-bnbk8\" "} {"level":"warn","ts":"2026-08-05T12:00:16.441391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:16.058488Z","time spent":"382.891995ms","remote":"127.0.0.1:47694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-05T12:00:16.441455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.140768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jv66n\" ","response":"range_response_count:1 size:20758"} {"level":"info","ts":"2026-08-05T12:00:16.441301Z","caller":"traceutil/trace.go:171","msg":"trace[634207152] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-heat-nodes; range_end:; response_count:1; response_revision:18441; }","duration":"541.448749ms","start":"2026-08-05T12:00:15.899843Z","end":"2026-08-05T12:00:16.441292Z","steps":["trace[634207152] 'agreement among raft nodes before linearized reading' (duration: 541.347157ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:00:16.441483Z","caller":"traceutil/trace.go:171","msg":"trace[443223105] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jv66n; range_end:; response_count:1; response_revision:18441; }","duration":"528.167178ms","start":"2026-08-05T12:00:15.913307Z","end":"2026-08-05T12:00:16.441474Z","steps":["trace[443223105] 'agreement among raft nodes before linearized reading' (duration: 528.087417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.441514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.913275Z","time spent":"528.23212ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20780,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jv66n\" "} {"level":"warn","ts":"2026-08-05T12:00:16.441509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.899838Z","time spent":"541.653663ms","remote":"127.0.0.1:47520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1270,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-heat-nodes\" "} {"level":"warn","ts":"2026-08-05T12:00:16.441377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.460567ms","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-08-05T12:00:16.441621Z","caller":"traceutil/trace.go:171","msg":"trace[1101434192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18441; }","duration":"507.730093ms","start":"2026-08-05T12:00:15.933878Z","end":"2026-08-05T12:00:16.441608Z","steps":["trace[1101434192] 'agreement among raft nodes before linearized reading' (duration: 507.442077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.441645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:00:15.933864Z","time spent":"507.774254ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T12:00:16.579607Z","caller":"traceutil/trace.go:171","msg":"trace[1999240586] transaction","detail":"{read_only:false; response_revision:18444; number_of_response:1; }","duration":"121.864377ms","start":"2026-08-05T12:00:16.45771Z","end":"2026-08-05T12:00:16.579574Z","steps":["trace[1999240586] 'process raft request' (duration: 121.736014ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:00:16.579718Z","caller":"traceutil/trace.go:171","msg":"trace[1830843907] transaction","detail":"{read_only:false; response_revision:18443; number_of_response:1; }","duration":"131.084253ms","start":"2026-08-05T12:00:16.44862Z","end":"2026-08-05T12:00:16.579704Z","steps":["trace[1830843907] 'process raft request' (duration: 130.660325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.580039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.898261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-heat.18c8e6fa624fff97\" ","response":"range_response_count:1 size:734"} {"level":"info","ts":"2026-08-05T12:00:16.580098Z","caller":"traceutil/trace.go:171","msg":"trace[899521478] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-heat.18c8e6fa624fff97; range_end:; response_count:1; response_revision:18444; }","duration":"132.066165ms","start":"2026-08-05T12:00:16.448016Z","end":"2026-08-05T12:00:16.580082Z","steps":["trace[899521478] 'agreement among raft nodes before linearized reading' (duration: 131.905632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.580313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.057354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:1434"} {"level":"info","ts":"2026-08-05T12:00:16.580375Z","caller":"traceutil/trace.go:171","msg":"trace[1689285480] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18444; }","duration":"132.150606ms","start":"2026-08-05T12:00:16.448214Z","end":"2026-08-05T12:00:16.580365Z","steps":["trace[1689285480] 'agreement among raft nodes before linearized reading' (duration: 132.033563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:16.582754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.948693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:98 size:978583"} {"level":"info","ts":"2026-08-05T12:00:16.582837Z","caller":"traceutil/trace.go:171","msg":"trace[1830582481] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:98; response_revision:18444; }","duration":"134.061535ms","start":"2026-08-05T12:00:16.448755Z","end":"2026-08-05T12:00:16.582816Z","steps":["trace[1830582481] 'agreement among raft nodes before linearized reading' (duration: 130.833368ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:00:16.724779Z","caller":"traceutil/trace.go:171","msg":"trace[1411155422] transaction","detail":"{read_only:false; response_revision:18447; number_of_response:1; }","duration":"102.81277ms","start":"2026-08-05T12:00:16.621936Z","end":"2026-08-05T12:00:16.724749Z","steps":["trace[1411155422] 'process raft request' (duration: 102.335591ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:00:42.076232Z","caller":"traceutil/trace.go:171","msg":"trace[399083937] transaction","detail":"{read_only:false; response_revision:18705; number_of_response:1; }","duration":"100.488173ms","start":"2026-08-05T12:00:41.975696Z","end":"2026-08-05T12:00:42.076185Z","steps":["trace[399083937] 'process raft request' (duration: 100.262019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:53.21249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.589512ms","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-08-05T12:00:53.21259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.856676ms","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-08-05T12:00:53.212624Z","caller":"traceutil/trace.go:171","msg":"trace[2106660067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18926; }","duration":"212.788826ms","start":"2026-08-05T12:00:52.999802Z","end":"2026-08-05T12:00:53.212591Z","steps":["trace[2106660067] 'range keys from in-memory index tree' (duration: 212.303387ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:00:53.212683Z","caller":"traceutil/trace.go:171","msg":"trace[825642267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18926; }","duration":"210.967509ms","start":"2026-08-05T12:00:53.001691Z","end":"2026-08-05T12:00:53.212659Z","steps":["trace[825642267] 'range keys from in-memory index tree' (duration: 210.622312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:00:53.212789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.073379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-bnbk8\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-08-05T12:00:53.212862Z","caller":"traceutil/trace.go:171","msg":"trace[893180736] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-bnbk8; range_end:; response_count:1; response_revision:18926; }","duration":"204.195431ms","start":"2026-08-05T12:00:53.00865Z","end":"2026-08-05T12:00:53.212845Z","steps":["trace[893180736] 'range keys from in-memory index tree' (duration: 203.910675ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:01:46.880577Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T12:01:46.928144Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T12:01:46.928342Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-05T12:02:55.387552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17373} {"level":"info","ts":"2026-08-05T12:02:55.485918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17373,"took":"94.663294ms","hash":3647312803,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":34631680,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-05T12:02:55.485981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3647312803,"revision":17373,"compact-revision":14914} {"level":"info","ts":"2026-08-05T12:05:38.456482Z","caller":"traceutil/trace.go:171","msg":"trace[1988693927] transaction","detail":"{read_only:false; response_revision:21016; number_of_response:1; }","duration":"125.314487ms","start":"2026-08-05T12:05:38.331133Z","end":"2026-08-05T12:05:38.456448Z","steps":["trace[1988693927] 'process raft request' (duration: 125.094763ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:05:43.956539Z","caller":"traceutil/trace.go:171","msg":"trace[920317532] linearizableReadLoop","detail":"{readStateIndex:21760; appliedIndex:21758; }","duration":"151.804386ms","start":"2026-08-05T12:05:43.804702Z","end":"2026-08-05T12:05:43.956506Z","steps":["trace[920317532] 'read index received' (duration: 23.15141ms)","trace[920317532] 'applied index is now lower than readState.Index' (duration: 128.652086ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:05:43.956667Z","caller":"traceutil/trace.go:171","msg":"trace[1158322696] transaction","detail":"{read_only:false; response_revision:21043; number_of_response:1; }","duration":"174.073559ms","start":"2026-08-05T12:05:43.782569Z","end":"2026-08-05T12:05:43.956642Z","steps":["trace[1158322696] 'process raft request' (duration: 171.68483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:05:43.956748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.032261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-05T12:05:43.956791Z","caller":"traceutil/trace.go:171","msg":"trace[1614627067] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21043; }","duration":"152.117553ms","start":"2026-08-05T12:05:43.80466Z","end":"2026-08-05T12:05:43.956778Z","steps":["trace[1614627067] 'agreement among raft nodes before linearized reading' (duration: 151.954389ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:05:59.993306Z","caller":"traceutil/trace.go:171","msg":"trace[424886158] transaction","detail":"{read_only:false; response_revision:21125; number_of_response:1; }","duration":"106.984375ms","start":"2026-08-05T12:05:59.886293Z","end":"2026-08-05T12:05:59.993277Z","steps":["trace[424886158] 'process raft request' (duration: 101.560345ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:06:00.279482Z","caller":"traceutil/trace.go:171","msg":"trace[781666170] transaction","detail":"{read_only:false; response_revision:21128; number_of_response:1; }","duration":"100.575945ms","start":"2026-08-05T12:06:00.178876Z","end":"2026-08-05T12:06:00.279452Z","steps":["trace[781666170] 'process raft request' (duration: 100.413251ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:06:00.279719Z","caller":"traceutil/trace.go:171","msg":"trace[325998634] transaction","detail":"{read_only:false; response_revision:21127; number_of_response:1; }","duration":"268.373607ms","start":"2026-08-05T12:06:00.011154Z","end":"2026-08-05T12:06:00.279528Z","steps":["trace[325998634] 'process raft request' (duration: 188.974532ms)","trace[325998634] 'compare' (duration: 78.962455ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:06:00.55751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.605904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:06:00.557595Z","caller":"traceutil/trace.go:171","msg":"trace[340267972] transaction","detail":"{read_only:false; response_revision:21130; number_of_response:1; }","duration":"273.442159ms","start":"2026-08-05T12:06:00.284138Z","end":"2026-08-05T12:06:00.55758Z","steps":["trace[340267972] 'process raft request' (duration: 148.704813ms)","trace[340267972] 'compare' (duration: 124.40083ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:06:00.880191Z","caller":"traceutil/trace.go:171","msg":"trace[689839872] transaction","detail":"{read_only:false; response_revision:21132; number_of_response:1; }","duration":"258.136298ms","start":"2026-08-05T12:06:00.622023Z","end":"2026-08-05T12:06:00.880159Z","steps":["trace[689839872] 'process raft request' (duration: 231.685971ms)","trace[689839872] 'compare' (duration: 26.201062ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:06:01.155541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.479499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:06:01.155712Z","caller":"traceutil/trace.go:171","msg":"trace[1409826811] transaction","detail":"{read_only:false; response_revision:21133; number_of_response:1; }","duration":"271.241015ms","start":"2026-08-05T12:06:00.884454Z","end":"2026-08-05T12:06:01.155695Z","steps":["trace[1409826811] 'process raft request' (duration: 118.51945ms)","trace[1409826811] 'compare' (duration: 152.258965ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:06:01.155969Z","caller":"traceutil/trace.go:171","msg":"trace[1492728732] transaction","detail":"{read_only:false; response_revision:21134; number_of_response:1; }","duration":"271.009999ms","start":"2026-08-05T12:06:00.884922Z","end":"2026-08-05T12:06:01.155932Z","steps":["trace[1492728732] 'process raft request' (duration: 270.709793ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:07:55.396292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19800} {"level":"info","ts":"2026-08-05T12:07:55.518246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19800,"took":"118.845734ms","hash":72124134,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":30552064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T12:07:55.518317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":72124134,"revision":19800,"compact-revision":17373} {"level":"info","ts":"2026-08-05T12:12:55.404662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22081} {"level":"info","ts":"2026-08-05T12:12:55.520715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22081,"took":"112.165283ms","hash":3766994102,"current-db-size-bytes":57778176,"current-db-size":"58 MB","current-db-size-in-use-bytes":44908544,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-05T12:12:55.520779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3766994102,"revision":22081,"compact-revision":19800} {"level":"info","ts":"2026-08-05T12:13:24.185211Z","caller":"traceutil/trace.go:171","msg":"trace[1961417599] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"108.852765ms","start":"2026-08-05T12:13:24.076328Z","end":"2026-08-05T12:13:24.185181Z","steps":["trace[1961417599] 'process raft request' (duration: 108.675622ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:24.571505Z","caller":"traceutil/trace.go:171","msg":"trace[302886203] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"102.341853ms","start":"2026-08-05T12:13:24.469128Z","end":"2026-08-05T12:13:24.57147Z","steps":["trace[302886203] 'process raft request' (duration: 102.139469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:24.854636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.407918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:24.854716Z","caller":"traceutil/trace.go:171","msg":"trace[561883800] linearizableReadLoop","detail":"{readStateIndex:26536; appliedIndex:26534; }","duration":"319.740947ms","start":"2026-08-05T12:13:24.534963Z","end":"2026-08-05T12:13:24.854704Z","steps":["trace[561883800] 'read index received' (duration: 36.376133ms)","trace[561883800] 'applied index is now lower than readState.Index' (duration: 283.363644ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:24.854748Z","caller":"traceutil/trace.go:171","msg":"trace[1636659967] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"355.774834ms","start":"2026-08-05T12:13:24.498944Z","end":"2026-08-05T12:13:24.854719Z","steps":["trace[1636659967] 'process raft request' (duration: 221.183452ms)","trace[1636659967] 'compare' (duration: 133.928729ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:24.854861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:24.498913Z","time spent":"355.886956ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T12:13:24.854908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.938113ms","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-08-05T12:13:24.85487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.87469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:24.854947Z","caller":"traceutil/trace.go:171","msg":"trace[395516974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25687; }","duration":"168.031315ms","start":"2026-08-05T12:13:24.686903Z","end":"2026-08-05T12:13:24.854935Z","steps":["trace[395516974] 'agreement among raft nodes before linearized reading' (duration: 167.887852ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:24.85497Z","caller":"traceutil/trace.go:171","msg":"trace[1309125264] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25687; }","duration":"320.041342ms","start":"2026-08-05T12:13:24.53491Z","end":"2026-08-05T12:13:24.854951Z","steps":["trace[1309125264] 'agreement among raft nodes before linearized reading' (duration: 319.852469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:24.855017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:24.534886Z","time spent":"320.115674ms","remote":"127.0.0.1:35658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-08-05T12:13:24.97647Z","caller":"traceutil/trace.go:171","msg":"trace[498210455] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"118.174646ms","start":"2026-08-05T12:13:24.858257Z","end":"2026-08-05T12:13:24.976431Z","steps":["trace[498210455] 'process raft request' (duration: 116.360669ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:24.976509Z","caller":"traceutil/trace.go:171","msg":"trace[1289601303] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"117.298528ms","start":"2026-08-05T12:13:24.85917Z","end":"2026-08-05T12:13:24.976468Z","steps":["trace[1289601303] 'process raft request' (duration: 117.196656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:29.750609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.472658ms","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-08-05T12:13:29.750699Z","caller":"traceutil/trace.go:171","msg":"trace[1417033130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25728; }","duration":"129.634311ms","start":"2026-08-05T12:13:29.621043Z","end":"2026-08-05T12:13:29.750677Z","steps":["trace[1417033130] 'range keys from in-memory index tree' (duration: 129.287404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:29.75098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.040569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T12:13:29.751032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.792265ms","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-08-05T12:13:29.751072Z","caller":"traceutil/trace.go:171","msg":"trace[277701800] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25728; }","duration":"198.188002ms","start":"2026-08-05T12:13:29.552862Z","end":"2026-08-05T12:13:29.75105Z","steps":["trace[277701800] 'count revisions from in-memory index tree' (duration: 197.953698ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:29.751093Z","caller":"traceutil/trace.go:171","msg":"trace[148853717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25728; }","duration":"131.881406ms","start":"2026-08-05T12:13:29.619197Z","end":"2026-08-05T12:13:29.751078Z","steps":["trace[148853717] 'range keys from in-memory index tree' (duration: 131.635012ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:29.892498Z","caller":"traceutil/trace.go:171","msg":"trace[720482670] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"136.94534ms","start":"2026-08-05T12:13:29.755521Z","end":"2026-08-05T12:13:29.892467Z","steps":["trace[720482670] 'process raft request' (duration: 136.740396ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:29.918767Z","caller":"traceutil/trace.go:171","msg":"trace[1097717635] transaction","detail":"{read_only:false; response_revision:25730; number_of_response:1; }","duration":"163.148856ms","start":"2026-08-05T12:13:29.755579Z","end":"2026-08-05T12:13:29.918728Z","steps":["trace[1097717635] 'process raft request' (duration: 162.897741ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:30.387105Z","caller":"traceutil/trace.go:171","msg":"trace[246774106] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"176.247194ms","start":"2026-08-05T12:13:30.210838Z","end":"2026-08-05T12:13:30.387086Z","steps":["trace[246774106] 'process raft request' (duration: 176.139582ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:30.400444Z","caller":"traceutil/trace.go:171","msg":"trace[144417185] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"189.144848ms","start":"2026-08-05T12:13:30.211234Z","end":"2026-08-05T12:13:30.400379Z","steps":["trace[144417185] 'process raft request' (duration: 187.678877ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:30.752956Z","caller":"traceutil/trace.go:171","msg":"trace[1734421996] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"155.706853ms","start":"2026-08-05T12:13:30.597224Z","end":"2026-08-05T12:13:30.75293Z","steps":["trace[1734421996] 'process raft request' (duration: 155.532019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:31.368623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.252045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:31.368744Z","caller":"traceutil/trace.go:171","msg":"trace[582471964] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"308.521218ms","start":"2026-08-05T12:13:31.060198Z","end":"2026-08-05T12:13:31.368719Z","steps":["trace[582471964] 'process raft request' (duration: 151.050618ms)","trace[582471964] 'compare' (duration: 156.559131ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:31.368806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.060185Z","time spent":"308.590909ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:13:31.525208Z","caller":"traceutil/trace.go:171","msg":"trace[595541504] linearizableReadLoop","detail":"{readStateIndex:26590; appliedIndex:26588; }","duration":"405.757456ms","start":"2026-08-05T12:13:31.119305Z","end":"2026-08-05T12:13:31.525063Z","steps":["trace[595541504] 'read index received' (duration: 91.95809ms)","trace[595541504] 'applied index is now lower than readState.Index' (duration: 313.797736ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:31.525486Z","caller":"traceutil/trace.go:171","msg":"trace[1315643272] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"412.894941ms","start":"2026-08-05T12:13:31.112571Z","end":"2026-08-05T12:13:31.525466Z","steps":["trace[1315643272] 'process raft request' (duration: 392.75939ms)","trace[1315643272] 'compare' (duration: 19.233273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:31.525595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.112541Z","time spent":"412.984753ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T12:13:31.525921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.574282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T12:13:31.526086Z","caller":"traceutil/trace.go:171","msg":"trace[834222725] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25740; }","duration":"406.742125ms","start":"2026-08-05T12:13:31.119279Z","end":"2026-08-05T12:13:31.526021Z","steps":["trace[834222725] 'agreement among raft nodes before linearized reading' (duration: 406.332057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:31.526151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.11927Z","time spent":"406.869078ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T12:13:31.82476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.515403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:31.82495Z","caller":"traceutil/trace.go:171","msg":"trace[2049193165] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"294.509601ms","start":"2026-08-05T12:13:31.530425Z","end":"2026-08-05T12:13:31.824934Z","steps":["trace[2049193165] 'process raft request' (duration: 294.47819ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:31.825144Z","caller":"traceutil/trace.go:171","msg":"trace[1080667170] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"593.269699ms","start":"2026-08-05T12:13:31.231865Z","end":"2026-08-05T12:13:31.825135Z","steps":["trace[1080667170] 'process raft request' (duration: 428.287906ms)","trace[1080667170] 'compare' (duration: 164.270209ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:31.82519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.231833Z","time spent":"593.33173ms","remote":"127.0.0.1:59540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418382,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:13:31.825256Z","caller":"traceutil/trace.go:171","msg":"trace[355013945] linearizableReadLoop","detail":"{readStateIndex:26592; appliedIndex:26590; }","duration":"299.969902ms","start":"2026-08-05T12:13:31.525278Z","end":"2026-08-05T12:13:31.825248Z","steps":["trace[355013945] 'read index received' (duration: 134.957308ms)","trace[355013945] 'applied index is now lower than readState.Index' (duration: 165.011854ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:31.825245Z","caller":"traceutil/trace.go:171","msg":"trace[712301190] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"342.306098ms","start":"2026-08-05T12:13:31.482895Z","end":"2026-08-05T12:13:31.825202Z","steps":["trace[712301190] 'process raft request' (duration: 341.949651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:31.825341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.427519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:31.825363Z","caller":"traceutil/trace.go:171","msg":"trace[1122462106] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25743; }","duration":"564.49356ms","start":"2026-08-05T12:13:31.260862Z","end":"2026-08-05T12:13:31.825355Z","steps":["trace[1122462106] 'agreement among raft nodes before linearized reading' (duration: 564.428209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:31.825385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.260841Z","time spent":"564.537961ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-05T12:13:31.825403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.482863Z","time spent":"342.451841ms","remote":"127.0.0.1:47572","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-08-05T12:13:31.825392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.212226ms","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-08-05T12:13:31.825435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.778517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:31.825479Z","caller":"traceutil/trace.go:171","msg":"trace[2069649213] 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:25743; }","duration":"410.309178ms","start":"2026-08-05T12:13:31.415144Z","end":"2026-08-05T12:13:31.825453Z","steps":["trace[2069649213] 'agreement among raft nodes before linearized reading' (duration: 410.140475ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:31.825551Z","caller":"traceutil/trace.go:171","msg":"trace[1336974192] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25743; }","duration":"678.94763ms","start":"2026-08-05T12:13:31.146566Z","end":"2026-08-05T12:13:31.825514Z","steps":["trace[1336974192] 'agreement among raft nodes before linearized reading' (duration: 678.747806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:31.825547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.415122Z","time spent":"410.40652ms","remote":"127.0.0.1:35638","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-08-05T12:13:31.82559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.146543Z","time spent":"679.039313ms","remote":"127.0.0.1:47536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-05T12:13:31.829225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.933145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:31.829312Z","caller":"traceutil/trace.go:171","msg":"trace[453614326] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25744; }","duration":"104.084048ms","start":"2026-08-05T12:13:31.725206Z","end":"2026-08-05T12:13:31.82929Z","steps":["trace[453614326] 'agreement among raft nodes before linearized reading' (duration: 103.927245ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:32.174717Z","caller":"traceutil/trace.go:171","msg":"trace[1336118598] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"266.001549ms","start":"2026-08-05T12:13:31.908691Z","end":"2026-08-05T12:13:32.174693Z","steps":["trace[1336118598] 'process raft request' (duration: 264.212772ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:32.17518Z","caller":"traceutil/trace.go:171","msg":"trace[1183653188] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26594; }","duration":"251.803869ms","start":"2026-08-05T12:13:31.922735Z","end":"2026-08-05T12:13:32.174538Z","steps":["trace[1183653188] 'read index received' (duration: 250.936691ms)","trace[1183653188] 'applied index is now lower than readState.Index' (duration: 866.038µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:32.175199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.401751ms","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-08-05T12:13:32.175353Z","caller":"traceutil/trace.go:171","msg":"trace[38385757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25745; }","duration":"252.616115ms","start":"2026-08-05T12:13:31.92269Z","end":"2026-08-05T12:13:32.175306Z","steps":["trace[38385757] 'agreement among raft nodes before linearized reading' (duration: 252.080684ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:32.306004Z","caller":"traceutil/trace.go:171","msg":"trace[1986128342] linearizableReadLoop","detail":"{readStateIndex:26598; appliedIndex:26595; }","duration":"123.379623ms","start":"2026-08-05T12:13:32.182591Z","end":"2026-08-05T12:13:32.30597Z","steps":["trace[1986128342] 'read index received' (duration: 95.613905ms)","trace[1986128342] 'applied index is now lower than readState.Index' (duration: 27.764358ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:32.306038Z","caller":"traceutil/trace.go:171","msg":"trace[388966995] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"382.308456ms","start":"2026-08-05T12:13:31.923647Z","end":"2026-08-05T12:13:32.305956Z","steps":["trace[388966995] 'process raft request' (duration: 354.540309ms)","trace[388966995] 'compare' (duration: 27.39689ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:32.306066Z","caller":"traceutil/trace.go:171","msg":"trace[2089899718] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"382.202694ms","start":"2026-08-05T12:13:31.923843Z","end":"2026-08-05T12:13:32.306046Z","steps":["trace[2089899718] 'process raft request' (duration: 381.973469ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:32.306171Z","caller":"traceutil/trace.go:171","msg":"trace[683971537] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"126.409224ms","start":"2026-08-05T12:13:32.179746Z","end":"2026-08-05T12:13:32.306155Z","steps":["trace[683971537] 'process raft request' (duration: 126.164329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:32.306221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.923831Z","time spent":"382.300437ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T12:13:32.306474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:31.923634Z","time spent":"382.492041ms","remote":"127.0.0.1:47572","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-08-05T12:13:32.306532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.938283ms","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-08-05T12:13:32.306587Z","caller":"traceutil/trace.go:171","msg":"trace[1779291639] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25748; }","duration":"123.996305ms","start":"2026-08-05T12:13:32.182572Z","end":"2026-08-05T12:13:32.306568Z","steps":["trace[1779291639] 'agreement among raft nodes before linearized reading' (duration: 123.900433ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:32.875662Z","caller":"traceutil/trace.go:171","msg":"trace[443266997] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"115.41749ms","start":"2026-08-05T12:13:32.760204Z","end":"2026-08-05T12:13:32.875621Z","steps":["trace[443266997] 'process raft request' (duration: 115.155234ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:33.055212Z","caller":"traceutil/trace.go:171","msg":"trace[1180993425] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"153.10474ms","start":"2026-08-05T12:13:32.902073Z","end":"2026-08-05T12:13:33.055178Z","steps":["trace[1180993425] 'process raft request' (duration: 132.767514ms)","trace[1180993425] 'compare' (duration: 20.095321ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:33.334831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.55256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:33.334964Z","caller":"traceutil/trace.go:171","msg":"trace[342652327] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"233.404901ms","start":"2026-08-05T12:13:33.101533Z","end":"2026-08-05T12:13:33.334938Z","steps":["trace[342652327] 'process raft request' (duration: 101.646908ms)","trace[342652327] 'compare' (duration: 131.378216ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:33.491812Z","caller":"traceutil/trace.go:171","msg":"trace[465149791] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"114.44091ms","start":"2026-08-05T12:13:33.377303Z","end":"2026-08-05T12:13:33.491744Z","steps":["trace[465149791] 'process raft request' (duration: 112.891939ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:33.909942Z","caller":"traceutil/trace.go:171","msg":"trace[1285025611] linearizableReadLoop","detail":"{readStateIndex:26606; appliedIndex:26605; }","duration":"279.145086ms","start":"2026-08-05T12:13:33.630769Z","end":"2026-08-05T12:13:33.909915Z","steps":["trace[1285025611] 'read index received' (duration: 279.069905ms)","trace[1285025611] 'applied index is now lower than readState.Index' (duration: 73.821µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:33.910121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.33404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:33.910166Z","caller":"traceutil/trace.go:171","msg":"trace[518429715] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25756; }","duration":"279.436662ms","start":"2026-08-05T12:13:33.630716Z","end":"2026-08-05T12:13:33.910153Z","steps":["trace[518429715] 'agreement among raft nodes before linearized reading' (duration: 279.32481ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:33.910142Z","caller":"traceutil/trace.go:171","msg":"trace[1627066272] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"378.859745ms","start":"2026-08-05T12:13:33.531246Z","end":"2026-08-05T12:13:33.910106Z","steps":["trace[1627066272] 'process raft request' (duration: 378.477157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:33.910401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:33.531218Z","time spent":"379.090051ms","remote":"127.0.0.1:47572","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-08-05T12:13:34.074383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.369574ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T12:13:34.074671Z","caller":"traceutil/trace.go:171","msg":"trace[2054064820] linearizableReadLoop","detail":"{readStateIndex:26608; appliedIndex:26606; }","duration":"164.630636ms","start":"2026-08-05T12:13:33.91001Z","end":"2026-08-05T12:13:34.074641Z","steps":["trace[2054064820] 'read index received' (duration: 11.956855ms)","trace[2054064820] 'applied index is now lower than readState.Index' (duration: 152.672031ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:34.07476Z","caller":"traceutil/trace.go:171","msg":"trace[1592602319] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"243.287564ms","start":"2026-08-05T12:13:33.831437Z","end":"2026-08-05T12:13:34.074724Z","steps":["trace[1592602319] 'process raft request' (duration: 243.033069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:34.150293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.53398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-05T12:13:34.150331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.849679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T12:13:34.150383Z","caller":"traceutil/trace.go:171","msg":"trace[1416681361] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25757; }","duration":"470.690793ms","start":"2026-08-05T12:13:33.679669Z","end":"2026-08-05T12:13:34.15036Z","steps":["trace[1416681361] 'agreement among raft nodes before linearized reading' (duration: 395.069857ms)","trace[1416681361] 'range keys from in-memory index tree' (duration: 75.245779ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:34.150391Z","caller":"traceutil/trace.go:171","msg":"trace[1517778098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25757; }","duration":"318.953491ms","start":"2026-08-05T12:13:33.831417Z","end":"2026-08-05T12:13:34.150371Z","steps":["trace[1517778098] 'agreement among raft nodes before linearized reading' (duration: 243.343866ms)","trace[1517778098] 'range keys from in-memory index tree' (duration: 75.214217ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:34.150426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:33.831398Z","time spent":"319.021032ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T12:13:34.150433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:33.679643Z","time spent":"470.779844ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-05T12:13:34.297663Z","caller":"traceutil/trace.go:171","msg":"trace[303139069] linearizableReadLoop","detail":"{readStateIndex:26609; appliedIndex:26608; }","duration":"140.697366ms","start":"2026-08-05T12:13:34.156937Z","end":"2026-08-05T12:13:34.297634Z","steps":["trace[303139069] 'read index received' (duration: 140.505002ms)","trace[303139069] 'applied index is now lower than readState.Index' (duration: 191.314µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:34.298049Z","caller":"traceutil/trace.go:171","msg":"trace[766997599] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"142.77726ms","start":"2026-08-05T12:13:34.155226Z","end":"2026-08-05T12:13:34.298004Z","steps":["trace[766997599] 'process raft request' (duration: 142.255329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:34.29837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.413161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-05T12:13:34.298412Z","caller":"traceutil/trace.go:171","msg":"trace[1524746120] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25758; }","duration":"141.479872ms","start":"2026-08-05T12:13:34.156922Z","end":"2026-08-05T12:13:34.298402Z","steps":["trace[1524746120] 'agreement among raft nodes before linearized reading' (duration: 141.323868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:34.298547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.389272ms","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-08-05T12:13:34.298644Z","caller":"traceutil/trace.go:171","msg":"trace[1524259576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25758; }","duration":"119.542174ms","start":"2026-08-05T12:13:34.179082Z","end":"2026-08-05T12:13:34.298624Z","steps":["trace[1524259576] 'agreement among raft nodes before linearized reading' (duration: 119.289139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:34.54515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.957106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:34.545287Z","caller":"traceutil/trace.go:171","msg":"trace[340580888] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26609; }","duration":"234.442392ms","start":"2026-08-05T12:13:34.310826Z","end":"2026-08-05T12:13:34.545268Z","steps":["trace[340580888] 'read index received' (duration: 126.256211ms)","trace[340580888] 'applied index is now lower than readState.Index' (duration: 108.184631ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:34.545345Z","caller":"traceutil/trace.go:171","msg":"trace[1251158145] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"238.26148ms","start":"2026-08-05T12:13:34.307009Z","end":"2026-08-05T12:13:34.545271Z","steps":["trace[1251158145] 'process raft request' (duration: 130.060079ms)","trace[1251158145] 'compare' (duration: 107.475617ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:34.545481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.651916ms","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-08-05T12:13:34.545518Z","caller":"traceutil/trace.go:171","msg":"trace[1334493685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25759; }","duration":"234.707928ms","start":"2026-08-05T12:13:34.310799Z","end":"2026-08-05T12:13:34.545507Z","steps":["trace[1334493685] 'agreement among raft nodes before linearized reading' (duration: 234.546125ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:34.687173Z","caller":"traceutil/trace.go:171","msg":"trace[325732041] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"371.510226ms","start":"2026-08-05T12:13:34.315641Z","end":"2026-08-05T12:13:34.687152Z","steps":["trace[325732041] 'process raft request' (duration: 371.463035ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:34.687236Z","caller":"traceutil/trace.go:171","msg":"trace[511777984] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"375.292093ms","start":"2026-08-05T12:13:34.311906Z","end":"2026-08-05T12:13:34.687198Z","steps":["trace[511777984] 'process raft request' (duration: 374.992397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:34.687295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:34.315619Z","time spent":"371.611917ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T12:13:34.687408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:34.311893Z","time spent":"375.425155ms","remote":"127.0.0.1:47572","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-08-05T12:13:34.717882Z","caller":"traceutil/trace.go:171","msg":"trace[1195962423] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"273.221246ms","start":"2026-08-05T12:13:34.444638Z","end":"2026-08-05T12:13:34.717859Z","steps":["trace[1195962423] 'process raft request' (duration: 272.997582ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:34.718224Z","caller":"traceutil/trace.go:171","msg":"trace[1272602926] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"166.811141ms","start":"2026-08-05T12:13:34.551391Z","end":"2026-08-05T12:13:34.718202Z","steps":["trace[1272602926] 'process raft request' (duration: 166.671128ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:34.718214Z","caller":"traceutil/trace.go:171","msg":"trace[1918004151] linearizableReadLoop","detail":"{readStateIndex:26614; appliedIndex:26610; }","duration":"172.871805ms","start":"2026-08-05T12:13:34.545314Z","end":"2026-08-05T12:13:34.718186Z","steps":["trace[1918004151] 'read index received' (duration: 141.548725ms)","trace[1918004151] 'applied index is now lower than readState.Index' (duration: 31.32204ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:34.718295Z","caller":"traceutil/trace.go:171","msg":"trace[1225136752] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"237.020936ms","start":"2026-08-05T12:13:34.481242Z","end":"2026-08-05T12:13:34.718262Z","steps":["trace[1225136752] 'process raft request' (duration: 236.573567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:34.71836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.961002ms","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-08-05T12:13:34.718371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.894742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:34.718396Z","caller":"traceutil/trace.go:171","msg":"trace[1572338193] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25764; }","duration":"344.050543ms","start":"2026-08-05T12:13:34.374334Z","end":"2026-08-05T12:13:34.718385Z","steps":["trace[1572338193] 'agreement among raft nodes before linearized reading' (duration: 343.942371ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:34.718406Z","caller":"traceutil/trace.go:171","msg":"trace[177485156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25764; }","duration":"256.981004ms","start":"2026-08-05T12:13:34.461415Z","end":"2026-08-05T12:13:34.718396Z","steps":["trace[177485156] 'agreement among raft nodes before linearized reading' (duration: 256.908213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:34.718435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:34.374316Z","time spent":"344.109605ms","remote":"127.0.0.1:47742","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-08-05T12:13:35.014931Z","caller":"traceutil/trace.go:171","msg":"trace[1643178571] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"133.158783ms","start":"2026-08-05T12:13:34.88174Z","end":"2026-08-05T12:13:35.014899Z","steps":["trace[1643178571] 'process raft request' (duration: 132.889128ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:35.450098Z","caller":"traceutil/trace.go:171","msg":"trace[245462680] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"386.618095ms","start":"2026-08-05T12:13:35.063448Z","end":"2026-08-05T12:13:35.450066Z","steps":["trace[245462680] 'process raft request' (duration: 386.462582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:35.450259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:35.063413Z","time spent":"386.764447ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:13:35.45078Z","caller":"traceutil/trace.go:171","msg":"trace[1190331480] linearizableReadLoop","detail":"{readStateIndex:26617; appliedIndex:26617; }","duration":"372.779652ms","start":"2026-08-05T12:13:35.077988Z","end":"2026-08-05T12:13:35.450767Z","steps":["trace[1190331480] 'read index received' (duration: 372.775272ms)","trace[1190331480] 'applied index is now lower than readState.Index' (duration: 3.75µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:35.450946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.569138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-27pxg.18c8e709c70798cd\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-08-05T12:13:35.450988Z","caller":"traceutil/trace.go:171","msg":"trace[919717572] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-27pxg.18c8e709c70798cd; range_end:; response_count:1; response_revision:25766; }","duration":"128.650611ms","start":"2026-08-05T12:13:35.322323Z","end":"2026-08-05T12:13:35.450974Z","steps":["trace[919717572] 'agreement among raft nodes before linearized reading' (duration: 128.515247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:35.451122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.105819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-08-05T12:13:35.45121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.04418ms","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-08-05T12:13:35.451216Z","caller":"traceutil/trace.go:171","msg":"trace[1178931039] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25766; }","duration":"373.238202ms","start":"2026-08-05T12:13:35.077958Z","end":"2026-08-05T12:13:35.451197Z","steps":["trace[1178931039] 'agreement among raft nodes before linearized reading' (duration: 372.957195ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:35.451241Z","caller":"traceutil/trace.go:171","msg":"trace[979604515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25766; }","duration":"108.105711ms","start":"2026-08-05T12:13:35.343126Z","end":"2026-08-05T12:13:35.451232Z","steps":["trace[979604515] 'agreement among raft nodes before linearized reading' (duration: 108.000169ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:35.451264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:35.077949Z","time spent":"373.305213ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-05T12:13:35.451388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.402107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:35.451416Z","caller":"traceutil/trace.go:171","msg":"trace[706797722] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25766; }","duration":"109.459208ms","start":"2026-08-05T12:13:35.341949Z","end":"2026-08-05T12:13:35.451408Z","steps":["trace[706797722] 'agreement among raft nodes before linearized reading' (duration: 109.410317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:35.777254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.915401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:35.77742Z","caller":"traceutil/trace.go:171","msg":"trace[1191849670] linearizableReadLoop","detail":"{readStateIndex:26620; appliedIndex:26617; }","duration":"315.335527ms","start":"2026-08-05T12:13:35.462068Z","end":"2026-08-05T12:13:35.777403Z","steps":["trace[1191849670] 'read index received' (duration: 43.912578ms)","trace[1191849670] 'applied index is now lower than readState.Index' (duration: 271.421659ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:35.777495Z","caller":"traceutil/trace.go:171","msg":"trace[1612358300] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"322.045523ms","start":"2026-08-05T12:13:35.455438Z","end":"2026-08-05T12:13:35.777483Z","steps":["trace[1612358300] 'process raft request' (duration: 321.905391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:35.777556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:35.455427Z","time spent":"322.097194ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:13:35.77754Z","caller":"traceutil/trace.go:171","msg":"trace[422817369] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"322.920301ms","start":"2026-08-05T12:13:35.454584Z","end":"2026-08-05T12:13:35.777504Z","steps":["trace[422817369] 'process raft request' (duration: 180.679973ms)","trace[422817369] 'compare' (duration: 141.739858ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:35.777713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.623063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:35.777782Z","caller":"traceutil/trace.go:171","msg":"trace[440630427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25769; }","duration":"315.711534ms","start":"2026-08-05T12:13:35.462056Z","end":"2026-08-05T12:13:35.777767Z","steps":["trace[440630427] 'agreement among raft nodes before linearized reading' (duration: 315.537631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:35.777818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:35.46205Z","time spent":"315.759405ms","remote":"127.0.0.1:47332","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-08-05T12:13:35.777824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:35.454566Z","time spent":"323.133726ms","remote":"127.0.0.1:47572","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-08-05T12:13:35.802761Z","caller":"traceutil/trace.go:171","msg":"trace[741764116] transaction","detail":"{read_only:false; response_revision:25770; number_of_response:1; }","duration":"286.577578ms","start":"2026-08-05T12:13:35.516166Z","end":"2026-08-05T12:13:35.802744Z","steps":["trace[741764116] 'process raft request' (duration: 285.931395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:35.803094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.744269ms","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-08-05T12:13:35.803123Z","caller":"traceutil/trace.go:171","msg":"trace[2143630610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25770; }","duration":"303.777719ms","start":"2026-08-05T12:13:35.499337Z","end":"2026-08-05T12:13:35.803114Z","steps":["trace[2143630610] 'agreement among raft nodes before linearized reading' (duration: 303.636396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:35.803141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:35.499309Z","time spent":"303.826891ms","remote":"127.0.0.1:47572","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":"info","ts":"2026-08-05T12:13:38.106552Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006810.wal"} {"level":"info","ts":"2026-08-05T12:13:42.347934Z","caller":"traceutil/trace.go:171","msg":"trace[2080093499] linearizableReadLoop","detail":"{readStateIndex:26670; appliedIndex:26669; }","duration":"199.597921ms","start":"2026-08-05T12:13:42.148305Z","end":"2026-08-05T12:13:42.347903Z","steps":["trace[2080093499] 'read index received' (duration: 199.414347ms)","trace[2080093499] 'applied index is now lower than readState.Index' (duration: 182.404µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:42.348048Z","caller":"traceutil/trace.go:171","msg":"trace[897039502] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"230.496373ms","start":"2026-08-05T12:13:42.117518Z","end":"2026-08-05T12:13:42.348015Z","steps":["trace[897039502] 'process raft request' (duration: 230.198596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:42.348568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.223294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-6hhqp.18c8e70a730d7e5a\" ","response":"range_response_count:1 size:957"} {"level":"info","ts":"2026-08-05T12:13:42.348697Z","caller":"traceutil/trace.go:171","msg":"trace[589721571] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-6hhqp.18c8e70a730d7e5a; range_end:; response_count:1; response_revision:25818; }","duration":"200.383357ms","start":"2026-08-05T12:13:42.148283Z","end":"2026-08-05T12:13:42.348666Z","steps":["trace[589721571] 'agreement among raft nodes before linearized reading' (duration: 199.761865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:42.936645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.012928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:42.936797Z","caller":"traceutil/trace.go:171","msg":"trace[1667962964] linearizableReadLoop","detail":"{readStateIndex:26672; appliedIndex:26671; }","duration":"477.777509ms","start":"2026-08-05T12:13:42.458995Z","end":"2026-08-05T12:13:42.936773Z","steps":["trace[1667962964] 'read index received' (duration: 188.363961ms)","trace[1667962964] 'applied index is now lower than readState.Index' (duration: 289.411178ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:42.936894Z","caller":"traceutil/trace.go:171","msg":"trace[1052889566] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"583.4961ms","start":"2026-08-05T12:13:42.353383Z","end":"2026-08-05T12:13:42.936879Z","steps":["trace[1052889566] 'process raft request' (duration: 295.105804ms)","trace[1052889566] 'compare' (duration: 287.789014ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:42.936978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:42.35336Z","time spent":"583.577402ms","remote":"127.0.0.1:47572","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-08-05T12:13:42.937216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.207357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:42.937313Z","caller":"traceutil/trace.go:171","msg":"trace[1336788425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25820; }","duration":"478.3562ms","start":"2026-08-05T12:13:42.458932Z","end":"2026-08-05T12:13:42.937288Z","steps":["trace[1336788425] 'agreement among raft nodes before linearized reading' (duration: 478.166756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:42.937399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:42.458906Z","time spent":"478.449283ms","remote":"127.0.0.1:47332","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-08-05T12:13:42.94877Z","caller":"traceutil/trace.go:171","msg":"trace[871217123] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"235.30012ms","start":"2026-08-05T12:13:42.713442Z","end":"2026-08-05T12:13:42.948742Z","steps":["trace[871217123] 'process raft request' (duration: 235.180078ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:43.014987Z","caller":"traceutil/trace.go:171","msg":"trace[378034784] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"299.489853ms","start":"2026-08-05T12:13:42.715473Z","end":"2026-08-05T12:13:43.014963Z","steps":["trace[378034784] 'process raft request' (duration: 299.279029ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:43.015453Z","caller":"traceutil/trace.go:171","msg":"trace[420520549] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"272.646104ms","start":"2026-08-05T12:13:42.742769Z","end":"2026-08-05T12:13:43.015415Z","steps":["trace[420520549] 'process raft request' (duration: 272.157015ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:43.015652Z","caller":"traceutil/trace.go:171","msg":"trace[1444014880] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"265.697842ms","start":"2026-08-05T12:13:42.749942Z","end":"2026-08-05T12:13:43.015639Z","steps":["trace[1444014880] 'process raft request' (duration: 265.359275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:43.015864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.062235ms","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-08-05T12:13:43.015923Z","caller":"traceutil/trace.go:171","msg":"trace[2128786642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25824; }","duration":"435.188747ms","start":"2026-08-05T12:13:42.580719Z","end":"2026-08-05T12:13:43.015908Z","steps":["trace[2128786642] 'agreement among raft nodes before linearized reading' (duration: 434.998334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:43.015965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:42.580691Z","time spent":"435.263948ms","remote":"127.0.0.1:47572","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-08-05T12:13:43.016135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.248547ms","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":"warn","ts":"2026-08-05T12:13:43.016149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.728363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:43.016244Z","caller":"traceutil/trace.go:171","msg":"trace[12835960] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25824; }","duration":"175.866746ms","start":"2026-08-05T12:13:42.840354Z","end":"2026-08-05T12:13:43.01622Z","steps":["trace[12835960] 'agreement among raft nodes before linearized reading' (duration: 175.700522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:43.016305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.13061ms","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-08-05T12:13:43.016421Z","caller":"traceutil/trace.go:171","msg":"trace[234152741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25824; }","duration":"266.294814ms","start":"2026-08-05T12:13:42.7501Z","end":"2026-08-05T12:13:43.016395Z","steps":["trace[234152741] 'agreement among raft nodes before linearized reading' (duration: 265.924727ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:43.016176Z","caller":"traceutil/trace.go:171","msg":"trace[1853014832] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25824; }","duration":"273.335718ms","start":"2026-08-05T12:13:42.742825Z","end":"2026-08-05T12:13:43.016161Z","steps":["trace[1853014832] 'agreement among raft nodes before linearized reading' (duration: 273.256007ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:43.246487Z","caller":"traceutil/trace.go:171","msg":"trace[985734630] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"225.97474ms","start":"2026-08-05T12:13:43.020485Z","end":"2026-08-05T12:13:43.24646Z","steps":["trace[985734630] 'process raft request' (duration: 225.573652ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:43.26794Z","caller":"traceutil/trace.go:171","msg":"trace[1805466150] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"246.417258ms","start":"2026-08-05T12:13:43.0215Z","end":"2026-08-05T12:13:43.267918Z","steps":["trace[1805466150] 'process raft request' (duration: 246.188213ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:43.268086Z","caller":"traceutil/trace.go:171","msg":"trace[1493734719] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"208.287228ms","start":"2026-08-05T12:13:43.059756Z","end":"2026-08-05T12:13:43.268043Z","steps":["trace[1493734719] 'process raft request' (duration: 208.072824ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:43.53484Z","caller":"traceutil/trace.go:171","msg":"trace[1287882944] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"225.51579ms","start":"2026-08-05T12:13:43.309306Z","end":"2026-08-05T12:13:43.534822Z","steps":["trace[1287882944] 'process raft request' (duration: 224.582582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:44.08335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.219125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T12:13:44.083487Z","caller":"traceutil/trace.go:171","msg":"trace[378896256] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25829; }","duration":"427.396478ms","start":"2026-08-05T12:13:43.656067Z","end":"2026-08-05T12:13:44.083463Z","steps":["trace[378896256] 'count revisions from in-memory index tree' (duration: 427.092031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:44.083636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:43.656052Z","time spent":"427.571912ms","remote":"127.0.0.1:47612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":22,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-08-05T12:13:44.160537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.777608ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T12:13:44.160915Z","caller":"traceutil/trace.go:171","msg":"trace[1491040649] linearizableReadLoop","detail":"{readStateIndex:26682; appliedIndex:26681; }","duration":"336.715795ms","start":"2026-08-05T12:13:43.824165Z","end":"2026-08-05T12:13:44.160881Z","steps":["trace[1491040649] 'read index received' (duration: 47.101µs)","trace[1491040649] 'applied index is now lower than readState.Index' (duration: 336.665504ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:44.160986Z","caller":"traceutil/trace.go:171","msg":"trace[1267107906] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"215.256801ms","start":"2026-08-05T12:13:43.945701Z","end":"2026-08-05T12:13:44.160958Z","steps":["trace[1267107906] 'process raft request' (duration: 214.988875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:44.161295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.116363ms","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-08-05T12:13:44.161362Z","caller":"traceutil/trace.go:171","msg":"trace[226057044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25830; }","duration":"337.243656ms","start":"2026-08-05T12:13:43.824104Z","end":"2026-08-05T12:13:44.161347Z","steps":["trace[226057044] 'agreement among raft nodes before linearized reading' (duration: 336.97482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:44.16144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:43.824075Z","time spent":"337.352987ms","remote":"127.0.0.1:47572","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-08-05T12:13:44.161342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.5255ms","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-08-05T12:13:44.161573Z","caller":"traceutil/trace.go:171","msg":"trace[1721631773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25830; }","duration":"269.791836ms","start":"2026-08-05T12:13:43.891749Z","end":"2026-08-05T12:13:44.161541Z","steps":["trace[1721631773] 'agreement among raft nodes before linearized reading' (duration: 269.370547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:46.635105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.820405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-05T12:13:46.635138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.531368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:46.635367Z","caller":"traceutil/trace.go:171","msg":"trace[586247824] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25904; }","duration":"198.110931ms","start":"2026-08-05T12:13:46.437238Z","end":"2026-08-05T12:13:46.635349Z","steps":["trace[586247824] 'count revisions from in-memory index tree' (duration: 197.720102ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:46.635379Z","caller":"traceutil/trace.go:171","msg":"trace[289658647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25904; }","duration":"174.816314ms","start":"2026-08-05T12:13:46.460546Z","end":"2026-08-05T12:13:46.635362Z","steps":["trace[289658647] 'range keys from in-memory index tree' (duration: 174.460277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:46.635595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.42934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-099ed03d-840f-49b1-ad59-c3ef66512f28\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-05T12:13:46.635675Z","caller":"traceutil/trace.go:171","msg":"trace[1863019607] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-099ed03d-840f-49b1-ad59-c3ef66512f28; range_end:; response_count:1; response_revision:25904; }","duration":"205.557892ms","start":"2026-08-05T12:13:46.430094Z","end":"2026-08-05T12:13:46.635651Z","steps":["trace[1863019607] 'range keys from in-memory index tree' (duration: 205.241246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:46.635685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.935461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:13:46.635743Z","caller":"traceutil/trace.go:171","msg":"trace[639343361] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:25904; }","duration":"118.071804ms","start":"2026-08-05T12:13:46.517655Z","end":"2026-08-05T12:13:46.635726Z","steps":["trace[639343361] 'count revisions from in-memory index tree' (duration: 117.749067ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:48.327432Z","caller":"traceutil/trace.go:171","msg":"trace[38648764] linearizableReadLoop","detail":"{readStateIndex:26774; appliedIndex:26773; }","duration":"120.85552ms","start":"2026-08-05T12:13:48.206548Z","end":"2026-08-05T12:13:48.327404Z","steps":["trace[38648764] 'read index received' (duration: 120.607535ms)","trace[38648764] 'applied index is now lower than readState.Index' (duration: 246.395µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:48.327535Z","caller":"traceutil/trace.go:171","msg":"trace[969416528] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"153.043319ms","start":"2026-08-05T12:13:48.174458Z","end":"2026-08-05T12:13:48.327501Z","steps":["trace[969416528] 'process raft request' (duration: 152.680672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:48.327734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.145927ms","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-08-05T12:13:48.327793Z","caller":"traceutil/trace.go:171","msg":"trace[668637061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25921; }","duration":"121.287249ms","start":"2026-08-05T12:13:48.206491Z","end":"2026-08-05T12:13:48.327778Z","steps":["trace[668637061] 'agreement among raft nodes before linearized reading' (duration: 121.060554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:48.327791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.243527ms","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-08-05T12:13:48.327864Z","caller":"traceutil/trace.go:171","msg":"trace[1923695418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25921; }","duration":"117.344359ms","start":"2026-08-05T12:13:48.210496Z","end":"2026-08-05T12:13:48.327841Z","steps":["trace[1923695418] 'agreement among raft nodes before linearized reading' (duration: 117.123705ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:53.224683Z","caller":"traceutil/trace.go:171","msg":"trace[1370388841] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"174.343274ms","start":"2026-08-05T12:13:53.050295Z","end":"2026-08-05T12:13:53.224639Z","steps":["trace[1370388841] 'process raft request' (duration: 95.306118ms)","trace[1370388841] 'compare' (duration: 78.690869ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:53.506122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.488844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:53.506359Z","caller":"traceutil/trace.go:171","msg":"trace[2089463643] transaction","detail":"{read_only:false; response_revision:25985; number_of_response:1; }","duration":"205.687356ms","start":"2026-08-05T12:13:53.300651Z","end":"2026-08-05T12:13:53.506339Z","steps":["trace[2089463643] 'process raft request' (duration: 205.644035ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:53.506464Z","caller":"traceutil/trace.go:171","msg":"trace[399850405] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"454.778769ms","start":"2026-08-05T12:13:53.051656Z","end":"2026-08-05T12:13:53.506435Z","steps":["trace[399850405] 'process raft request' (duration: 310.881126ms)","trace[399850405] 'compare' (duration: 143.369652ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:53.506589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:53.051628Z","time spent":"454.88653ms","remote":"127.0.0.1:47572","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-08-05T12:13:53.506629Z","caller":"traceutil/trace.go:171","msg":"trace[1707797917] transaction","detail":"{read_only:false; response_revision:25984; number_of_response:1; }","duration":"448.773954ms","start":"2026-08-05T12:13:53.057843Z","end":"2026-08-05T12:13:53.506617Z","steps":["trace[1707797917] 'process raft request' (duration: 448.383146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:53.506696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:13:53.057825Z","time spent":"448.833296ms","remote":"127.0.0.1:47572","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-08-05T12:13:53.506791Z","caller":"traceutil/trace.go:171","msg":"trace[1600548396] linearizableReadLoop","detail":"{readStateIndex:26838; appliedIndex:26836; }","duration":"221.723843ms","start":"2026-08-05T12:13:53.285057Z","end":"2026-08-05T12:13:53.506781Z","steps":["trace[1600548396] 'read index received' (duration: 77.643947ms)","trace[1600548396] 'applied index is now lower than readState.Index' (duration: 144.078986ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:13:53.506987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.938208ms","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-08-05T12:13:53.507023Z","caller":"traceutil/trace.go:171","msg":"trace[225937487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25985; }","duration":"222.018979ms","start":"2026-08-05T12:13:53.284993Z","end":"2026-08-05T12:13:53.507012Z","steps":["trace[225937487] 'agreement among raft nodes before linearized reading' (duration: 221.874796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:53.507116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.862687ms","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-08-05T12:13:53.507227Z","caller":"traceutil/trace.go:171","msg":"trace[2034512433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25985; }","duration":"202.0241ms","start":"2026-08-05T12:13:53.305175Z","end":"2026-08-05T12:13:53.507199Z","steps":["trace[2034512433] 'agreement among raft nodes before linearized reading' (duration: 201.720974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:53.797871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.585449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:13:53.798118Z","caller":"traceutil/trace.go:171","msg":"trace[559637155] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"228.206036ms","start":"2026-08-05T12:13:53.569892Z","end":"2026-08-05T12:13:53.798098Z","steps":["trace[559637155] 'process raft request' (duration: 228.159285ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:53.798276Z","caller":"traceutil/trace.go:171","msg":"trace[15959963] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"285.60575ms","start":"2026-08-05T12:13:53.512647Z","end":"2026-08-05T12:13:53.798253Z","steps":["trace[15959963] 'process raft request' (duration: 133.458649ms)","trace[15959963] 'compare' (duration: 151.080449ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:13:53.798264Z","caller":"traceutil/trace.go:171","msg":"trace[239508047] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"285.438427ms","start":"2026-08-05T12:13:53.512796Z","end":"2026-08-05T12:13:53.798234Z","steps":["trace[239508047] 'process raft request' (duration: 285.187601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:13:57.504847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.211879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T12:13:57.50493Z","caller":"traceutil/trace.go:171","msg":"trace[1749591549] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:26014; }","duration":"145.364202ms","start":"2026-08-05T12:13:57.35955Z","end":"2026-08-05T12:13:57.504914Z","steps":["trace[1749591549] 'count revisions from in-memory index tree' (duration: 145.151638ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:57.679643Z","caller":"traceutil/trace.go:171","msg":"trace[1198120026] transaction","detail":"{read_only:false; response_revision:26015; number_of_response:1; }","duration":"159.342777ms","start":"2026-08-05T12:13:57.520271Z","end":"2026-08-05T12:13:57.679614Z","steps":["trace[1198120026] 'process raft request' (duration: 159.154903ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:57.680842Z","caller":"traceutil/trace.go:171","msg":"trace[1960740479] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"155.461469ms","start":"2026-08-05T12:13:57.525363Z","end":"2026-08-05T12:13:57.680825Z","steps":["trace[1960740479] 'process raft request' (duration: 155.354426ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:57.680914Z","caller":"traceutil/trace.go:171","msg":"trace[1141499797] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"159.840518ms","start":"2026-08-05T12:13:57.52104Z","end":"2026-08-05T12:13:57.680881Z","steps":["trace[1141499797] 'process raft request' (duration: 159.598493ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:59.959957Z","caller":"traceutil/trace.go:171","msg":"trace[771664820] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"101.200758ms","start":"2026-08-05T12:13:59.85873Z","end":"2026-08-05T12:13:59.95993Z","steps":["trace[771664820] 'process raft request' (duration: 101.163398ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:13:59.960013Z","caller":"traceutil/trace.go:171","msg":"trace[1959720969] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"101.28329ms","start":"2026-08-05T12:13:59.858682Z","end":"2026-08-05T12:13:59.959965Z","steps":["trace[1959720969] 'process raft request' (duration: 66.272975ms)","trace[1959720969] 'compare' (duration: 34.808271ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:02.645644Z","caller":"traceutil/trace.go:171","msg":"trace[44924254] transaction","detail":"{read_only:false; response_revision:26057; number_of_response:1; }","duration":"164.772159ms","start":"2026-08-05T12:14:02.480846Z","end":"2026-08-05T12:14:02.645619Z","steps":["trace[44924254] 'process raft request' (duration: 164.620306ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:02.648079Z","caller":"traceutil/trace.go:171","msg":"trace[1440165274] transaction","detail":"{read_only:false; response_revision:26058; number_of_response:1; }","duration":"165.531834ms","start":"2026-08-05T12:14:02.482517Z","end":"2026-08-05T12:14:02.648049Z","steps":["trace[1440165274] 'process raft request' (duration: 165.388152ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:06.164142Z","caller":"traceutil/trace.go:171","msg":"trace[1516824294] transaction","detail":"{read_only:false; response_revision:26083; number_of_response:1; }","duration":"102.538866ms","start":"2026-08-05T12:14:06.061556Z","end":"2026-08-05T12:14:06.164095Z","steps":["trace[1516824294] 'process raft request' (duration: 71.386289ms)","trace[1516824294] 'compare' (duration: 30.633446ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:17.917321Z","caller":"traceutil/trace.go:171","msg":"trace[1734936344] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"160.474331ms","start":"2026-08-05T12:14:17.756808Z","end":"2026-08-05T12:14:17.917283Z","steps":["trace[1734936344] 'process raft request' (duration: 155.825746ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:17.91771Z","caller":"traceutil/trace.go:171","msg":"trace[2138731515] transaction","detail":"{read_only:false; response_revision:26179; number_of_response:1; }","duration":"158.077082ms","start":"2026-08-05T12:14:17.759603Z","end":"2026-08-05T12:14:17.91768Z","steps":["trace[2138731515] 'process raft request' (duration: 157.602753ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:17.917914Z","caller":"traceutil/trace.go:171","msg":"trace[2102179662] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"157.203403ms","start":"2026-08-05T12:14:17.760677Z","end":"2026-08-05T12:14:17.91788Z","steps":["trace[2102179662] 'process raft request' (duration: 156.787435ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:18.576906Z","caller":"traceutil/trace.go:171","msg":"trace[1016286402] linearizableReadLoop","detail":"{readStateIndex:27045; appliedIndex:27044; }","duration":"114.48ms","start":"2026-08-05T12:14:18.462397Z","end":"2026-08-05T12:14:18.576877Z","steps":["trace[1016286402] 'read index received' (duration: 24.259756ms)","trace[1016286402] 'applied index is now lower than readState.Index' (duration: 90.218974ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:14:18.577071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.637643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:18.577135Z","caller":"traceutil/trace.go:171","msg":"trace[371333290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26184; }","duration":"114.772646ms","start":"2026-08-05T12:14:18.462343Z","end":"2026-08-05T12:14:18.577116Z","steps":["trace[371333290] 'agreement among raft nodes before linearized reading' (duration: 114.644353ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:18.577317Z","caller":"traceutil/trace.go:171","msg":"trace[1840049874] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"114.935649ms","start":"2026-08-05T12:14:18.462351Z","end":"2026-08-05T12:14:18.577286Z","steps":["trace[1840049874] 'process raft request' (duration: 70.757726ms)","trace[1840049874] 'compare' (duration: 43.410638ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:18.644017Z","caller":"traceutil/trace.go:171","msg":"trace[1759782459] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"156.323286ms","start":"2026-08-05T12:14:18.487664Z","end":"2026-08-05T12:14:18.643987Z","steps":["trace[1759782459] 'process raft request' (duration: 156.043011ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:18.644043Z","caller":"traceutil/trace.go:171","msg":"trace[182513754] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"126.363183ms","start":"2026-08-05T12:14:18.517661Z","end":"2026-08-05T12:14:18.644024Z","steps":["trace[182513754] 'process raft request' (duration: 126.21408ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:19.529661Z","caller":"traceutil/trace.go:171","msg":"trace[1818949358] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"208.662976ms","start":"2026-08-05T12:14:19.320961Z","end":"2026-08-05T12:14:19.529624Z","steps":["trace[1818949358] 'process raft request' (duration: 140.614275ms)","trace[1818949358] 'compare' (duration: 67.672343ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:19.557679Z","caller":"traceutil/trace.go:171","msg":"trace[191872236] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"153.238043ms","start":"2026-08-05T12:14:19.404413Z","end":"2026-08-05T12:14:19.557651Z","steps":["trace[191872236] 'process raft request' (duration: 153.08243ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:20.031252Z","caller":"traceutil/trace.go:171","msg":"trace[538039652] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"108.072819ms","start":"2026-08-05T12:14:19.923135Z","end":"2026-08-05T12:14:20.031208Z","steps":["trace[538039652] 'process raft request' (duration: 107.800004ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:20.031475Z","caller":"traceutil/trace.go:171","msg":"trace[1489579866] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"107.408785ms","start":"2026-08-05T12:14:19.92404Z","end":"2026-08-05T12:14:20.031448Z","steps":["trace[1489579866] 'process raft request' (duration: 107.043608ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:20.294119Z","caller":"traceutil/trace.go:171","msg":"trace[1739907581] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"123.684529ms","start":"2026-08-05T12:14:20.1704Z","end":"2026-08-05T12:14:20.294085Z","steps":["trace[1739907581] 'process raft request' (duration: 71.40545ms)","trace[1739907581] 'compare' (duration: 52.168637ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:21.576716Z","caller":"traceutil/trace.go:171","msg":"trace[668725700] linearizableReadLoop","detail":"{readStateIndex:27067; appliedIndex:27066; }","duration":"116.102794ms","start":"2026-08-05T12:14:21.460573Z","end":"2026-08-05T12:14:21.576675Z","steps":["trace[668725700] 'read index received' (duration: 16.876056ms)","trace[668725700] 'applied index is now lower than readState.Index' (duration: 99.225278ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:14:21.576874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.278228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:21.576953Z","caller":"traceutil/trace.go:171","msg":"trace[1409925656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26206; }","duration":"116.40176ms","start":"2026-08-05T12:14:21.460534Z","end":"2026-08-05T12:14:21.576935Z","steps":["trace[1409925656] 'agreement among raft nodes before linearized reading' (duration: 116.259738ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:21.576762Z","caller":"traceutil/trace.go:171","msg":"trace[1168937618] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"254.990734ms","start":"2026-08-05T12:14:21.321706Z","end":"2026-08-05T12:14:21.576697Z","steps":["trace[1168937618] 'process raft request' (duration: 155.786296ms)","trace[1168937618] 'compare' (duration: 98.997074ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:21.718448Z","caller":"traceutil/trace.go:171","msg":"trace[398502044] linearizableReadLoop","detail":"{readStateIndex:27068; appliedIndex:27067; }","duration":"141.602395ms","start":"2026-08-05T12:14:21.5768Z","end":"2026-08-05T12:14:21.718402Z","steps":["trace[398502044] 'read index received' (duration: 101.618077ms)","trace[398502044] 'applied index is now lower than readState.Index' (duration: 39.982338ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:21.718509Z","caller":"traceutil/trace.go:171","msg":"trace[1296351280] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"153.716903ms","start":"2026-08-05T12:14:21.564736Z","end":"2026-08-05T12:14:21.718453Z","steps":["trace[1296351280] 'process raft request' (duration: 113.823847ms)","trace[1296351280] 'compare' (duration: 39.219931ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:14:21.71871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.225156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:21.718763Z","caller":"traceutil/trace.go:171","msg":"trace[1125288979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26207; }","duration":"140.354569ms","start":"2026-08-05T12:14:21.578393Z","end":"2026-08-05T12:14:21.718748Z","steps":["trace[1125288979] 'agreement among raft nodes before linearized reading' (duration: 140.216566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:14:21.718737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.426961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T12:14:21.718837Z","caller":"traceutil/trace.go:171","msg":"trace[1380703032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26207; }","duration":"184.565843ms","start":"2026-08-05T12:14:21.534247Z","end":"2026-08-05T12:14:21.718812Z","steps":["trace[1380703032] 'agreement among raft nodes before linearized reading' (duration: 184.287018ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:32.350257Z","caller":"traceutil/trace.go:171","msg":"trace[464567320] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"122.674708ms","start":"2026-08-05T12:14:32.227524Z","end":"2026-08-05T12:14:32.350198Z","steps":["trace[464567320] 'process raft request' (duration: 89.879517ms)","trace[464567320] 'compare' (duration: 32.614137ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:14:32.566488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.766683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T12:14:32.56656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.721901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:32.566586Z","caller":"traceutil/trace.go:171","msg":"trace[1731568126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26287; }","duration":"106.920815ms","start":"2026-08-05T12:14:32.459642Z","end":"2026-08-05T12:14:32.566563Z","steps":["trace[1731568126] 'range keys from in-memory index tree' (duration: 106.688471ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:32.566649Z","caller":"traceutil/trace.go:171","msg":"trace[2079569791] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26287; }","duration":"102.870513ms","start":"2026-08-05T12:14:32.463752Z","end":"2026-08-05T12:14:32.566623Z","steps":["trace[2079569791] 'count revisions from in-memory index tree' (duration: 102.662659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:14:32.981716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.198562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:14:32.981804Z","caller":"traceutil/trace.go:171","msg":"trace[1493016171] linearizableReadLoop","detail":"{readStateIndex:27152; appliedIndex:27151; }","duration":"200.801135ms","start":"2026-08-05T12:14:32.780991Z","end":"2026-08-05T12:14:32.981793Z","steps":["trace[1493016171] 'read index received' (duration: 46.399919ms)","trace[1493016171] 'applied index is now lower than readState.Index' (duration: 154.400416ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:14:32.981894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.009732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:32.981911Z","caller":"traceutil/trace.go:171","msg":"trace[1337970] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26289; }","duration":"163.075223ms","start":"2026-08-05T12:14:32.818827Z","end":"2026-08-05T12:14:32.981903Z","steps":["trace[1337970] 'agreement among raft nodes before linearized reading' (duration: 163.030462ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:32.98198Z","caller":"traceutil/trace.go:171","msg":"trace[1393342728] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"257.122647ms","start":"2026-08-05T12:14:32.724824Z","end":"2026-08-05T12:14:32.981946Z","steps":["trace[1393342728] 'process raft request' (duration: 102.618359ms)","trace[1393342728] 'compare' (duration: 153.973068ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:14:32.982166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.421385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-05T12:14:32.98212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.098651ms","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-08-05T12:14:32.982233Z","caller":"traceutil/trace.go:171","msg":"trace[1440581997] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26289; }","duration":"201.275494ms","start":"2026-08-05T12:14:32.780927Z","end":"2026-08-05T12:14:32.982203Z","steps":["trace[1440581997] 'agreement among raft nodes before linearized reading' (duration: 200.928997ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:32.982189Z","caller":"traceutil/trace.go:171","msg":"trace[134649090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26289; }","duration":"105.493106ms","start":"2026-08-05T12:14:32.87669Z","end":"2026-08-05T12:14:32.982183Z","steps":["trace[134649090] 'agreement among raft nodes before linearized reading' (duration: 105.390434ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:37.907544Z","caller":"traceutil/trace.go:171","msg":"trace[1919264841] linearizableReadLoop","detail":"{readStateIndex:27198; appliedIndex:27197; }","duration":"111.752514ms","start":"2026-08-05T12:14:37.795757Z","end":"2026-08-05T12:14:37.907509Z","steps":["trace[1919264841] 'read index received' (duration: 111.495868ms)","trace[1919264841] 'applied index is now lower than readState.Index' (duration: 255.136µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:14:37.907758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.950999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:37.907817Z","caller":"traceutil/trace.go:171","msg":"trace[498107591] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26334; }","duration":"112.092042ms","start":"2026-08-05T12:14:37.795702Z","end":"2026-08-05T12:14:37.907794Z","steps":["trace[498107591] 'agreement among raft nodes before linearized reading' (duration: 111.958879ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:37.907971Z","caller":"traceutil/trace.go:171","msg":"trace[1295080354] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"130.995919ms","start":"2026-08-05T12:14:37.776586Z","end":"2026-08-05T12:14:37.907582Z","steps":["trace[1295080354] 'process raft request' (duration: 130.749573ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:38.362374Z","caller":"traceutil/trace.go:171","msg":"trace[2068204989] transaction","detail":"{read_only:false; response_revision:26339; number_of_response:1; }","duration":"158.95799ms","start":"2026-08-05T12:14:38.203392Z","end":"2026-08-05T12:14:38.36235Z","steps":["trace[2068204989] 'process raft request' (duration: 158.917189ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:38.362681Z","caller":"traceutil/trace.go:171","msg":"trace[1292399724] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"162.519613ms","start":"2026-08-05T12:14:38.200152Z","end":"2026-08-05T12:14:38.362672Z","steps":["trace[1292399724] 'process raft request' (duration: 136.882309ms)","trace[1292399724] 'compare' (duration: 25.175034ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:38.584453Z","caller":"traceutil/trace.go:171","msg":"trace[169807330] linearizableReadLoop","detail":"{readStateIndex:27206; appliedIndex:27204; }","duration":"125.443965ms","start":"2026-08-05T12:14:38.458979Z","end":"2026-08-05T12:14:38.584423Z","steps":["trace[169807330] 'read index received' (duration: 64.654522ms)","trace[169807330] 'applied index is now lower than readState.Index' (duration: 60.788293ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:14:38.5846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.604858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:38.584594Z","caller":"traceutil/trace.go:171","msg":"trace[903328074] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"140.398011ms","start":"2026-08-05T12:14:38.444157Z","end":"2026-08-05T12:14:38.584555Z","steps":["trace[903328074] 'process raft request' (duration: 140.086415ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:38.584791Z","caller":"traceutil/trace.go:171","msg":"trace[413649469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26341; }","duration":"125.71421ms","start":"2026-08-05T12:14:38.458912Z","end":"2026-08-05T12:14:38.584626Z","steps":["trace[413649469] 'agreement among raft nodes before linearized reading' (duration: 125.609168ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:39.228746Z","caller":"traceutil/trace.go:171","msg":"trace[515352325] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"127.364063ms","start":"2026-08-05T12:14:39.101341Z","end":"2026-08-05T12:14:39.228705Z","steps":["trace[515352325] 'process raft request' (duration: 58.105237ms)","trace[515352325] 'compare' (duration: 69.129373ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:39.249127Z","caller":"traceutil/trace.go:171","msg":"trace[2121703353] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"147.173558ms","start":"2026-08-05T12:14:39.101891Z","end":"2026-08-05T12:14:39.249065Z","steps":["trace[2121703353] 'process raft request' (duration: 146.978214ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:39.249294Z","caller":"traceutil/trace.go:171","msg":"trace[1128897158] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"117.122933ms","start":"2026-08-05T12:14:39.132148Z","end":"2026-08-05T12:14:39.249271Z","steps":["trace[1128897158] 'process raft request' (duration: 116.822277ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:44.360532Z","caller":"traceutil/trace.go:171","msg":"trace[1829924725] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"130.676852ms","start":"2026-08-05T12:14:44.229821Z","end":"2026-08-05T12:14:44.360498Z","steps":["trace[1829924725] 'process raft request' (duration: 130.495038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:14:44.674094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.016128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T12:14:44.674203Z","caller":"traceutil/trace.go:171","msg":"trace[299433279] transaction","detail":"{read_only:false; response_revision:26383; number_of_response:1; }","duration":"286.723592ms","start":"2026-08-05T12:14:44.387459Z","end":"2026-08-05T12:14:44.674183Z","steps":["trace[299433279] 'process raft request' (duration: 135.449769ms)","trace[299433279] 'compare' (duration: 150.906546ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:44.70566Z","caller":"traceutil/trace.go:171","msg":"trace[972980105] linearizableReadLoop","detail":"{readStateIndex:27250; appliedIndex:27248; }","duration":"242.960097ms","start":"2026-08-05T12:14:44.462674Z","end":"2026-08-05T12:14:44.705634Z","steps":["trace[972980105] 'read index received' (duration: 60.250192ms)","trace[972980105] 'applied index is now lower than readState.Index' (duration: 182.708885ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:44.705695Z","caller":"traceutil/trace.go:171","msg":"trace[1583737352] transaction","detail":"{read_only:false; response_revision:26384; number_of_response:1; }","duration":"306.653508ms","start":"2026-08-05T12:14:44.399018Z","end":"2026-08-05T12:14:44.705671Z","steps":["trace[1583737352] 'process raft request' (duration: 306.427444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:14:44.705809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:14:44.398996Z","time spent":"306.769701ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T12:14:44.705859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.15594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T12:14:44.705877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.24973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T12:14:44.705894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.394721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:44.705926Z","caller":"traceutil/trace.go:171","msg":"trace[480801575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26384; }","duration":"243.296164ms","start":"2026-08-05T12:14:44.462613Z","end":"2026-08-05T12:14:44.705909Z","steps":["trace[480801575] 'agreement among raft nodes before linearized reading' (duration: 243.162102ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:44.70593Z","caller":"traceutil/trace.go:171","msg":"trace[465033530] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:26384; }","duration":"236.362092ms","start":"2026-08-05T12:14:44.469553Z","end":"2026-08-05T12:14:44.705915Z","steps":["trace[465033530] 'agreement among raft nodes before linearized reading' (duration: 236.25246ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:44.705954Z","caller":"traceutil/trace.go:171","msg":"trace[986418187] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26384; }","duration":"211.483763ms","start":"2026-08-05T12:14:44.49445Z","end":"2026-08-05T12:14:44.705934Z","steps":["trace[986418187] 'agreement among raft nodes before linearized reading' (duration: 211.37192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:14:44.706207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.707069ms","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-08-05T12:14:44.706399Z","caller":"traceutil/trace.go:171","msg":"trace[1553289080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26384; }","duration":"235.953114ms","start":"2026-08-05T12:14:44.470378Z","end":"2026-08-05T12:14:44.706332Z","steps":["trace[1553289080] 'agreement among raft nodes before linearized reading' (duration: 235.531995ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:44.830925Z","caller":"traceutil/trace.go:171","msg":"trace[641358239] transaction","detail":"{read_only:false; response_revision:26385; number_of_response:1; }","duration":"119.961603ms","start":"2026-08-05T12:14:44.710937Z","end":"2026-08-05T12:14:44.830899Z","steps":["trace[641358239] 'process raft request' (duration: 119.8148ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:14:44.838399Z","caller":"traceutil/trace.go:171","msg":"trace[1470895418] linearizableReadLoop","detail":"{readStateIndex:27252; appliedIndex:27251; }","duration":"103.655459ms","start":"2026-08-05T12:14:44.734722Z","end":"2026-08-05T12:14:44.838377Z","steps":["trace[1470895418] 'read index received' (duration: 96.282798ms)","trace[1470895418] 'applied index is now lower than readState.Index' (duration: 7.371921ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:14:44.838535Z","caller":"traceutil/trace.go:171","msg":"trace[1996877441] transaction","detail":"{read_only:false; response_revision:26386; number_of_response:1; }","duration":"127.244822ms","start":"2026-08-05T12:14:44.711267Z","end":"2026-08-05T12:14:44.838512Z","steps":["trace[1996877441] 'process raft request' (duration: 127.001617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:14:44.838596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.850183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:14:44.838628Z","caller":"traceutil/trace.go:171","msg":"trace[997080760] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26386; }","duration":"103.902444ms","start":"2026-08-05T12:14:44.734716Z","end":"2026-08-05T12:14:44.838619Z","steps":["trace[997080760] 'agreement among raft nodes before linearized reading' (duration: 103.822733ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:17:55.521319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25353} {"level":"info","ts":"2026-08-05T12:17:55.667683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25353,"took":"141.955943ms","hash":1800118314,"current-db-size-bytes":59486208,"current-db-size":"60 MB","current-db-size-in-use-bytes":38842368,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-08-05T12:17:55.667757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1800118314,"revision":25353,"compact-revision":22081} {"level":"info","ts":"2026-08-05T12:19:18.642171Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T12:19:18.648117Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-05T12:19:18.648223Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-05T12:22:55.529118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28334} {"level":"info","ts":"2026-08-05T12:22:55.682924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28334,"took":"149.208941ms","hash":839684824,"current-db-size-bytes":59486208,"current-db-size":"60 MB","current-db-size-in-use-bytes":32641024,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T12:22:55.683029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":839684824,"revision":28334,"compact-revision":25353} {"level":"info","ts":"2026-08-05T12:27:55.537605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30691} {"level":"info","ts":"2026-08-05T12:27:55.678568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30691,"took":"136.327862ms","hash":969967576,"current-db-size-bytes":59486208,"current-db-size":"60 MB","current-db-size-in-use-bytes":30744576,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T12:27:55.678665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":969967576,"revision":30691,"compact-revision":28334} {"level":"info","ts":"2026-08-05T12:32:55.542934Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32951} {"level":"info","ts":"2026-08-05T12:32:55.690918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32951,"took":"144.3788ms","hash":2893412387,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":30547968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T12:32:55.691053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2893412387,"revision":32951,"compact-revision":30691} {"level":"info","ts":"2026-08-05T12:37:55.548604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35183} {"level":"info","ts":"2026-08-05T12:37:55.702026Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35183,"took":"144.618143ms","hash":435001926,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":32182272,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T12:37:55.702102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":435001926,"revision":35183,"compact-revision":32951} {"level":"info","ts":"2026-08-05T12:39:10.368378Z","caller":"traceutil/trace.go:171","msg":"trace[708171911] transaction","detail":"{read_only:false; response_revision:38074; number_of_response:1; }","duration":"392.87673ms","start":"2026-08-05T12:39:09.975463Z","end":"2026-08-05T12:39:10.36834Z","steps":["trace[708171911] 'process raft request' (duration: 385.634832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:10.368529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.411469ms","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-08-05T12:39:10.368563Z","caller":"traceutil/trace.go:171","msg":"trace[1183030333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:38075; }","duration":"386.477651ms","start":"2026-08-05T12:39:09.982077Z","end":"2026-08-05T12:39:10.368554Z","steps":["trace[1183030333] 'agreement among raft nodes before linearized reading' (duration: 386.352198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:10.368589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:09.982063Z","time spent":"386.519251ms","remote":"127.0.0.1:47572","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-08-05T12:39:10.368526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:09.975434Z","time spent":"393.016793ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:39:10.368378Z","caller":"traceutil/trace.go:171","msg":"trace[85174238] linearizableReadLoop","detail":"{readStateIndex:39265; appliedIndex:39264; }","duration":"386.243836ms","start":"2026-08-05T12:39:09.982113Z","end":"2026-08-05T12:39:10.368357Z","steps":["trace[85174238] 'read index received' (duration: 378.698122ms)","trace[85174238] 'applied index is now lower than readState.Index' (duration: 7.544264ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T12:39:11.250393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12798842913874507535,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T12:39:11.317497Z","caller":"traceutil/trace.go:171","msg":"trace[1174513490] transaction","detail":"{read_only:false; response_revision:38077; number_of_response:1; }","duration":"754.586058ms","start":"2026-08-05T12:39:10.562883Z","end":"2026-08-05T12:39:11.31747Z","steps":["trace[1174513490] 'process raft request' (duration: 754.383554ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:39:11.318382Z","caller":"traceutil/trace.go:171","msg":"trace[791214657] linearizableReadLoop","detail":"{readStateIndex:39271; appliedIndex:39267; }","duration":"568.513583ms","start":"2026-08-05T12:39:10.749843Z","end":"2026-08-05T12:39:11.318356Z","steps":["trace[791214657] 'read index received' (duration: 567.400021ms)","trace[791214657] 'applied index is now lower than readState.Index' (duration: 1.112942ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:39:11.318502Z","caller":"traceutil/trace.go:171","msg":"trace[709981740] transaction","detail":"{read_only:false; response_revision:38078; number_of_response:1; }","duration":"755.299972ms","start":"2026-08-05T12:39:10.563194Z","end":"2026-08-05T12:39:11.318494Z","steps":["trace[709981740] 'process raft request' (duration: 754.978235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.318597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:10.563177Z","time spent":"755.348873ms","remote":"127.0.0.1:47572","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-08-05T12:39:11.318595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:10.562857Z","time spent":"754.815573ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:39:11.318741Z","caller":"traceutil/trace.go:171","msg":"trace[848306875] transaction","detail":"{read_only:false; response_revision:38079; number_of_response:1; }","duration":"609.725739ms","start":"2026-08-05T12:39:10.709008Z","end":"2026-08-05T12:39:11.318734Z","steps":["trace[848306875] 'process raft request' (duration: 609.28051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.318785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:10.708984Z","time spent":"609.77836ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T12:39:11.318847Z","caller":"traceutil/trace.go:171","msg":"trace[561469373] transaction","detail":"{read_only:false; response_revision:38080; number_of_response:1; }","duration":"285.302419ms","start":"2026-08-05T12:39:11.033539Z","end":"2026-08-05T12:39:11.318841Z","steps":["trace[561469373] 'process raft request' (duration: 284.784548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.318951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.107746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:39:11.318987Z","caller":"traceutil/trace.go:171","msg":"trace[220419975] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:38080; }","duration":"569.237428ms","start":"2026-08-05T12:39:10.749742Z","end":"2026-08-05T12:39:11.31898Z","steps":["trace[220419975] 'agreement among raft nodes before linearized reading' (duration: 569.156346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.319008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:10.749722Z","time spent":"569.279439ms","remote":"127.0.0.1:37682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-08-05T12:39:11.319355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.583599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T12:39:11.319463Z","caller":"traceutil/trace.go:171","msg":"trace[537713305] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:38080; }","duration":"481.737793ms","start":"2026-08-05T12:39:10.83769Z","end":"2026-08-05T12:39:11.319428Z","steps":["trace[537713305] 'agreement among raft nodes before linearized reading' (duration: 481.57336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.319421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.073199ms","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-08-05T12:39:11.319558Z","caller":"traceutil/trace.go:171","msg":"trace[153458545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:38080; }","duration":"273.205392ms","start":"2026-08-05T12:39:11.046322Z","end":"2026-08-05T12:39:11.319528Z","steps":["trace[153458545] 'agreement among raft nodes before linearized reading' (duration: 272.949757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.319794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.916283ms","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-08-05T12:39:11.319838Z","caller":"traceutil/trace.go:171","msg":"trace[1947905000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:38080; }","duration":"363.970663ms","start":"2026-08-05T12:39:10.955859Z","end":"2026-08-05T12:39:11.31983Z","steps":["trace[1947905000] 'agreement among raft nodes before linearized reading' (duration: 363.664016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.319865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:10.955854Z","time spent":"364.005535ms","remote":"127.0.0.1:47572","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-08-05T12:39:11.319506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:10.837669Z","time spent":"481.826975ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-08-05T12:39:11.319995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.760571ms","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-08-05T12:39:11.320019Z","caller":"traceutil/trace.go:171","msg":"trace[1135275344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:38080; }","duration":"365.821952ms","start":"2026-08-05T12:39:10.954191Z","end":"2026-08-05T12:39:11.320013Z","steps":["trace[1135275344] 'agreement among raft nodes before linearized reading' (duration: 365.757131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.320035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:10.954176Z","time spent":"365.854752ms","remote":"127.0.0.1:47572","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-08-05T12:39:11.32012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.465285ms","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-08-05T12:39:11.320145Z","caller":"traceutil/trace.go:171","msg":"trace[39085218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:38080; }","duration":"436.525666ms","start":"2026-08-05T12:39:10.883612Z","end":"2026-08-05T12:39:11.320138Z","steps":["trace[39085218] 'agreement among raft nodes before linearized reading' (duration: 436.467284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:39:11.320164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:39:10.883594Z","time spent":"436.565397ms","remote":"127.0.0.1:47572","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":"info","ts":"2026-08-05T12:40:43.870981Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T12:40:43.875671Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-05T12:40:43.875827Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-05T12:42:55.557744Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37479} {"level":"info","ts":"2026-08-05T12:42:55.687786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37479,"took":"123.822327ms","hash":4262342712,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":32522240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T12:42:55.687855Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4262342712,"revision":37479,"compact-revision":35183} {"level":"info","ts":"2026-08-05T12:46:56.532492Z","caller":"traceutil/trace.go:171","msg":"trace[995196007] transaction","detail":"{read_only:false; response_revision:41565; number_of_response:1; }","duration":"252.709851ms","start":"2026-08-05T12:46:56.279748Z","end":"2026-08-05T12:46:56.532458Z","steps":["trace[995196007] 'process raft request' (duration: 252.470586ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T12:46:56.650851Z","caller":"traceutil/trace.go:171","msg":"trace[1486254293] linearizableReadLoop","detail":"{readStateIndex:42857; appliedIndex:42855; }","duration":"370.926977ms","start":"2026-08-05T12:46:56.279895Z","end":"2026-08-05T12:46:56.650822Z","steps":["trace[1486254293] 'read index received' (duration: 252.215741ms)","trace[1486254293] 'applied index is now lower than readState.Index' (duration: 118.709676ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T12:46:56.651406Z","caller":"traceutil/trace.go:171","msg":"trace[1090240420] transaction","detail":"{read_only:false; response_revision:41566; number_of_response:1; }","duration":"371.527989ms","start":"2026-08-05T12:46:56.279854Z","end":"2026-08-05T12:46:56.651382Z","steps":["trace[1090240420] 'process raft request' (duration: 369.038229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:46:56.651538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:46:56.279811Z","time spent":"371.659071ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T12:46:56.652876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.976409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" limit:500 ","response":"range_response_count:201 size:2222692"} {"level":"info","ts":"2026-08-05T12:46:56.652929Z","caller":"traceutil/trace.go:171","msg":"trace[35205253] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:201; response_revision:41566; }","duration":"373.04791ms","start":"2026-08-05T12:46:56.279868Z","end":"2026-08-05T12:46:56.652916Z","steps":["trace[35205253] 'agreement among raft nodes before linearized reading' (duration: 371.171722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T12:46:56.652958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T12:46:56.279796Z","time spent":"373.153552ms","remote":"127.0.0.1:47410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":201,"response size":2222714,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" limit:500 "}