{"level":"warn","ts":"2026-08-10T22:31:30.61777Z","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-10T22:31:30.617966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.48: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.48:2380","--initial-cluster=instance=https://199.19.213.48:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.48:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.48: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-10T22:31:30.618157Z","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-10T22:31:30.618185Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-08-10T22:31:30.618248Z","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-10T22:31:30.619142Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"]} {"level":"info","ts":"2026-08-10T22:31:30.619261Z","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.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.48: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-10T22:31:30.622478Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.940631ms"} {"level":"info","ts":"2026-08-10T22:31:30.628078Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7317567213c7319d","cluster-id":"d22ed1e1757a8527"} {"level":"info","ts":"2026-08-10T22:31:30.628153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T22:31:30.628174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 0"} {"level":"info","ts":"2026-08-10T22:31:30.628185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7317567213c7319d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T22:31:30.628191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 1"} {"level":"info","ts":"2026-08-10T22:31:30.628223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"warn","ts":"2026-08-10T22:31:30.634588Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T22:31:30.636325Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T22:31:30.63788Z","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-10T22:31:30.640016Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7317567213c7319d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T22:31:30.640307Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7317567213c7319d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T22:31:30.641872Z","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-10T22:31:30.642085Z","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-10T22:31:30.642474Z","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-10T22:31:30.644243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"info","ts":"2026-08-10T22:31:30.644548Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","added-peer-id":"7317567213c7319d","added-peer-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-08-10T22:31:30.645999Z","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-10T22:31:30.64621Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-08-10T22:31:30.646267Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-08-10T22:31:30.646332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7317567213c7319d","initial-advertise-peer-urls":["https://199.19.213.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T22:31:30.646407Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T22:31:31.228855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T22:31:31.228963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T22:31:31.228994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgPreVoteResp from 7317567213c7319d at term 1"} {"level":"info","ts":"2026-08-10T22:31:31.229023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became candidate at term 2"} {"level":"info","ts":"2026-08-10T22:31:31.229044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgVoteResp from 7317567213c7319d at term 2"} {"level":"info","ts":"2026-08-10T22:31:31.229064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became leader at term 2"} {"level":"info","ts":"2026-08-10T22:31:31.229082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7317567213c7319d elected leader 7317567213c7319d at term 2"} {"level":"info","ts":"2026-08-10T22:31:31.230433Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:31:31.231457Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:31:31.231455Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7317567213c7319d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.48:2379]}","request-path":"/0/members/7317567213c7319d/attributes","cluster-id":"d22ed1e1757a8527","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T22:31:31.23151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:31:31.23178Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T22:31:31.231839Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T22:31:31.23397Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T22:31:31.234063Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:31:31.234229Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:31:31.234301Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:31:31.235156Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.48:2379"} {"level":"info","ts":"2026-08-10T22:32:11.050586Z","caller":"traceutil/trace.go:171","msg":"trace[2116512375] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"198.372118ms","start":"2026-08-10T22:32:10.852189Z","end":"2026-08-10T22:32:11.050562Z","steps":["trace[2116512375] 'process raft request' (duration: 198.286755ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:11.050747Z","caller":"traceutil/trace.go:171","msg":"trace[1799748304] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"198.512192ms","start":"2026-08-10T22:32:10.852208Z","end":"2026-08-10T22:32:11.050721Z","steps":["trace[1799748304] 'process raft request' (duration: 198.307105ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:11.050587Z","caller":"traceutil/trace.go:171","msg":"trace[1978094946] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"198.541312ms","start":"2026-08-10T22:32:10.852006Z","end":"2026-08-10T22:32:11.050547Z","steps":["trace[1978094946] 'process raft request' (duration: 198.405447ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:11.050563Z","caller":"traceutil/trace.go:171","msg":"trace[1734933538] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"199.422491ms","start":"2026-08-10T22:32:10.851079Z","end":"2026-08-10T22:32:11.050502Z","steps":["trace[1734933538] 'process raft request' (duration: 155.835363ms)","trace[1734933538] 'compare' (duration: 43.232326ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:32:11.05242Z","caller":"traceutil/trace.go:171","msg":"trace[2085849995] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"185.192847ms","start":"2026-08-10T22:32:10.867164Z","end":"2026-08-10T22:32:11.052357Z","steps":["trace[2085849995] 'process raft request' (duration: 184.97939ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:11.052509Z","caller":"traceutil/trace.go:171","msg":"trace[22488300] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"178.540519ms","start":"2026-08-10T22:32:10.87395Z","end":"2026-08-10T22:32:11.052491Z","steps":["trace[22488300] 'process raft request' (duration: 178.278981ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:11.052584Z","caller":"traceutil/trace.go:171","msg":"trace[916111892] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:522; }","duration":"173.007626ms","start":"2026-08-10T22:32:10.879562Z","end":"2026-08-10T22:32:11.05257Z","steps":["trace[916111892] 'read index received' (duration: 127.393366ms)","trace[916111892] 'applied index is now lower than readState.Index' (duration: 45.61355ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:32:11.052644Z","caller":"traceutil/trace.go:171","msg":"trace[1311160893] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"174.958038ms","start":"2026-08-10T22:32:10.877682Z","end":"2026-08-10T22:32:11.05264Z","steps":["trace[1311160893] 'process raft request' (duration: 174.595496ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:11.052732Z","caller":"traceutil/trace.go:171","msg":"trace[2141387352] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"169.483347ms","start":"2026-08-10T22:32:10.883243Z","end":"2026-08-10T22:32:11.052727Z","steps":["trace[2141387352] 'process raft request' (duration: 169.135887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:11.052891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.305995ms","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-10T22:32:11.052954Z","caller":"traceutil/trace.go:171","msg":"trace[1392798492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"173.402288ms","start":"2026-08-10T22:32:10.879539Z","end":"2026-08-10T22:32:11.052941Z","steps":["trace[1392798492] 'agreement among raft nodes before linearized reading' (duration: 173.298595ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:11.053003Z","caller":"traceutil/trace.go:171","msg":"trace[392774752] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"146.340246ms","start":"2026-08-10T22:32:10.906485Z","end":"2026-08-10T22:32:11.052825Z","steps":["trace[392774752] 'process raft request' (duration: 146.044907ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:11.053161Z","caller":"traceutil/trace.go:171","msg":"trace[1940579891] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"163.735588ms","start":"2026-08-10T22:32:10.889091Z","end":"2026-08-10T22:32:11.052826Z","steps":["trace[1940579891] 'process raft request' (duration: 163.355747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:11.056999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.028594ms","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-10T22:32:11.05729Z","caller":"traceutil/trace.go:171","msg":"trace[890645179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"118.320974ms","start":"2026-08-10T22:32:10.938886Z","end":"2026-08-10T22:32:11.057207Z","steps":["trace[890645179] 'agreement among raft nodes before linearized reading' (duration: 117.983624ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:17.245444Z","caller":"traceutil/trace.go:171","msg":"trace[430260890] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"152.955773ms","start":"2026-08-10T22:32:17.092469Z","end":"2026-08-10T22:32:17.245425Z","steps":["trace[430260890] 'process raft request' (duration: 152.877391ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:17.245463Z","caller":"traceutil/trace.go:171","msg":"trace[939325614] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"153.678245ms","start":"2026-08-10T22:32:17.09175Z","end":"2026-08-10T22:32:17.245428Z","steps":["trace[939325614] 'process raft request' (duration: 135.906422ms)","trace[939325614] 'compare' (duration: 17.522456ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:32:17.292602Z","caller":"traceutil/trace.go:171","msg":"trace[2104825647] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"173.740809ms","start":"2026-08-10T22:32:17.1188Z","end":"2026-08-10T22:32:17.29254Z","steps":["trace[2104825647] 'process raft request' (duration: 173.111059ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:17.530663Z","caller":"traceutil/trace.go:171","msg":"trace[717907739] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"212.27274ms","start":"2026-08-10T22:32:17.318351Z","end":"2026-08-10T22:32:17.530624Z","steps":["trace[717907739] 'process raft request' (duration: 175.076241ms)","trace[717907739] 'compare' (duration: 37.086575ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:32:17.696509Z","caller":"traceutil/trace.go:171","msg":"trace[181400315] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"273.392383ms","start":"2026-08-10T22:32:17.422991Z","end":"2026-08-10T22:32:17.696383Z","steps":["trace[181400315] 'read index received' (duration: 70.455393ms)","trace[181400315] 'applied index is now lower than readState.Index' (duration: 202.93392ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:32:17.696841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.817526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:32:17.69692Z","caller":"traceutil/trace.go:171","msg":"trace[1970428097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"273.927559ms","start":"2026-08-10T22:32:17.422954Z","end":"2026-08-10T22:32:17.696882Z","steps":["trace[1970428097] 'agreement among raft nodes before linearized reading' (duration: 273.662441ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:32:17.697092Z","caller":"traceutil/trace.go:171","msg":"trace[1072322447] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"338.062726ms","start":"2026-08-10T22:32:17.358946Z","end":"2026-08-10T22:32:17.697009Z","steps":["trace[1072322447] 'process raft request' (duration: 337.038665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:17.698299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:32:17.358926Z","time spent":"338.299344ms","remote":"127.0.0.1:38558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-10T22:32:18.172368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.451568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-10T22:32:18.17248Z","caller":"traceutil/trace.go:171","msg":"trace[472692353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"355.637603ms","start":"2026-08-10T22:32:17.816814Z","end":"2026-08-10T22:32:18.172452Z","steps":["trace[472692353] 'range keys from in-memory index tree' (duration: 355.192921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:18.172533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:32:17.816782Z","time spent":"355.739717ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-10T22:32:19.447088Z","caller":"traceutil/trace.go:171","msg":"trace[621173836] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"105.089192ms","start":"2026-08-10T22:32:19.341974Z","end":"2026-08-10T22:32:19.447063Z","steps":["trace[621173836] 'process raft request' (duration: 104.865385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:05.539558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.262731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:33:05.539662Z","caller":"traceutil/trace.go:171","msg":"trace[1115519560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1179; }","duration":"116.437956ms","start":"2026-08-10T22:33:05.423206Z","end":"2026-08-10T22:33:05.539644Z","steps":["trace[1115519560] 'range keys from in-memory index tree' (duration: 116.154007ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:51.686663Z","caller":"traceutil/trace.go:171","msg":"trace[446786148] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"106.404914ms","start":"2026-08-10T22:33:51.580229Z","end":"2026-08-10T22:33:51.686634Z","steps":["trace[446786148] 'process raft request' (duration: 106.229179ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:51.833947Z","caller":"traceutil/trace.go:171","msg":"trace[1941439300] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1910; }","duration":"161.064201ms","start":"2026-08-10T22:33:51.672854Z","end":"2026-08-10T22:33:51.833918Z","steps":["trace[1941439300] 'read index received' (duration: 13.647061ms)","trace[1941439300] 'applied index is now lower than readState.Index' (duration: 147.41613ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:33:51.834086Z","caller":"traceutil/trace.go:171","msg":"trace[1704270247] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"164.539589ms","start":"2026-08-10T22:33:51.669533Z","end":"2026-08-10T22:33:51.834073Z","steps":["trace[1704270247] 'process raft request' (duration: 86.771268ms)","trace[1704270247] 'compare' (duration: 77.453321ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:33:51.834695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.828435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c502a709-6ab9-4003-91fc-6a103d44eb27\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-10T22:33:51.834748Z","caller":"traceutil/trace.go:171","msg":"trace[1257586024] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c502a709-6ab9-4003-91fc-6a103d44eb27; range_end:; response_count:1; response_revision:1843; }","duration":"161.915587ms","start":"2026-08-10T22:33:51.672819Z","end":"2026-08-10T22:33:51.834734Z","steps":["trace[1257586024] 'agreement among raft nodes before linearized reading' (duration: 161.750152ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:52.174861Z","caller":"traceutil/trace.go:171","msg":"trace[2118392963] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1912; }","duration":"325.815416ms","start":"2026-08-10T22:33:51.849012Z","end":"2026-08-10T22:33:52.174828Z","steps":["trace[2118392963] 'read index received' (duration: 54.185533ms)","trace[2118392963] 'applied index is now lower than readState.Index' (duration: 271.628323ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:33:52.174893Z","caller":"traceutil/trace.go:171","msg":"trace[293763695] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"328.050205ms","start":"2026-08-10T22:33:51.846781Z","end":"2026-08-10T22:33:52.174831Z","steps":["trace[293763695] 'process raft request' (duration: 322.233946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:52.175071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.041723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-10T22:33:52.175134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.625916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T22:33:52.175151Z","caller":"traceutil/trace.go:171","msg":"trace[1038137984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1845; }","duration":"326.154926ms","start":"2026-08-10T22:33:51.848979Z","end":"2026-08-10T22:33:52.175134Z","steps":["trace[1038137984] 'agreement among raft nodes before linearized reading' (duration: 325.9567ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:52.175174Z","caller":"traceutil/trace.go:171","msg":"trace[1543086401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1845; }","duration":"298.728029ms","start":"2026-08-10T22:33:51.876435Z","end":"2026-08-10T22:33:52.175163Z","steps":["trace[1543086401] 'agreement among raft nodes before linearized reading' (duration: 298.539273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:52.175186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:33:51.848969Z","time spent":"326.208838ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-10T22:33:52.175342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.441185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c502a709-6ab9-4003-91fc-6a103d44eb27\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-10T22:33:52.175402Z","caller":"traceutil/trace.go:171","msg":"trace[1533265762] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c502a709-6ab9-4003-91fc-6a103d44eb27; range_end:; response_count:1; response_revision:1845; }","duration":"279.555168ms","start":"2026-08-10T22:33:51.895833Z","end":"2026-08-10T22:33:52.175388Z","steps":["trace[1533265762] 'agreement among raft nodes before linearized reading' (duration: 279.344311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:52.175396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:33:51.846757Z","time spent":"328.24707ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:05.375992Z","caller":"traceutil/trace.go:171","msg":"trace[49331809] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"128.62542ms","start":"2026-08-10T22:34:05.247346Z","end":"2026-08-10T22:34:05.375971Z","steps":["trace[49331809] 'process raft request' (duration: 128.498716ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:05.567239Z","caller":"traceutil/trace.go:171","msg":"trace[14620614] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"138.9844ms","start":"2026-08-10T22:34:05.428225Z","end":"2026-08-10T22:34:05.567209Z","steps":["trace[14620614] 'process raft request' (duration: 118.018323ms)","trace[14620614] 'compare' (duration: 20.825513ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:35.622276Z","caller":"traceutil/trace.go:171","msg":"trace[1918311666] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"150.226497ms","start":"2026-08-10T22:34:35.472021Z","end":"2026-08-10T22:34:35.622248Z","steps":["trace[1918311666] 'process raft request' (duration: 150.054251ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:36.091216Z","caller":"traceutil/trace.go:171","msg":"trace[1195324383] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"103.918759ms","start":"2026-08-10T22:34:35.987257Z","end":"2026-08-10T22:34:36.091176Z","steps":["trace[1195324383] 'process raft request' (duration: 36.317728ms)","trace[1195324383] 'compare' (duration: 67.480977ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:42.858068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.464766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:34:42.858248Z","caller":"traceutil/trace.go:171","msg":"trace[1009723051] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"354.513243ms","start":"2026-08-10T22:34:42.503688Z","end":"2026-08-10T22:34:42.858201Z","steps":["trace[1009723051] 'process raft request' (duration: 185.487969ms)","trace[1009723051] 'compare' (duration: 168.284531ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:42.858314Z","caller":"traceutil/trace.go:171","msg":"trace[83875127] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2257; }","duration":"320.869178ms","start":"2026-08-10T22:34:42.537411Z","end":"2026-08-10T22:34:42.85828Z","steps":["trace[83875127] 'read index received' (duration: 151.877605ms)","trace[83875127] 'applied index is now lower than readState.Index' (duration: 168.988443ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:42.858329Z","caller":"traceutil/trace.go:171","msg":"trace[372016413] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"316.212863ms","start":"2026-08-10T22:34:42.542091Z","end":"2026-08-10T22:34:42.858304Z","steps":["trace[372016413] 'process raft request' (duration: 316.10981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.858487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:42.542069Z","time spent":"316.364128ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:42.858496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:42.503665Z","time spent":"354.666877ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:42.858564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.855243ms","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-10T22:34:42.858612Z","caller":"traceutil/trace.go:171","msg":"trace[1649457358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"405.920984ms","start":"2026-08-10T22:34:42.452678Z","end":"2026-08-10T22:34:42.858599Z","steps":["trace[1649457358] 'agreement among raft nodes before linearized reading' (duration: 405.740049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.858651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:42.452673Z","time spent":"405.970426ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-10T22:35:38.536844Z","caller":"traceutil/trace.go:171","msg":"trace[1180258094] linearizableReadLoop","detail":"{readStateIndex:2746; appliedIndex:2745; }","duration":"111.462891ms","start":"2026-08-10T22:35:38.425357Z","end":"2026-08-10T22:35:38.53682Z","steps":["trace[1180258094] 'read index received' (duration: 111.398339ms)","trace[1180258094] 'applied index is now lower than readState.Index' (duration: 63.322µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:35:38.536992Z","caller":"traceutil/trace.go:171","msg":"trace[683889890] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"147.552432ms","start":"2026-08-10T22:35:38.389406Z","end":"2026-08-10T22:35:38.536958Z","steps":["trace[683889890] 'process raft request' (duration: 147.278334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:35:38.539117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.692931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:35:38.539324Z","caller":"traceutil/trace.go:171","msg":"trace[1098294530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2647; }","duration":"113.973989ms","start":"2026-08-10T22:35:38.425314Z","end":"2026-08-10T22:35:38.539288Z","steps":["trace[1098294530] 'agreement among raft nodes before linearized reading' (duration: 111.632427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:35:38.539419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.569986ms","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-10T22:35:38.539514Z","caller":"traceutil/trace.go:171","msg":"trace[327865060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2648; }","duration":"100.71248ms","start":"2026-08-10T22:35:38.438777Z","end":"2026-08-10T22:35:38.539489Z","steps":["trace[327865060] 'agreement among raft nodes before linearized reading' (duration: 100.412831ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:36:11.950348Z","caller":"traceutil/trace.go:171","msg":"trace[1318095270] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"111.283824ms","start":"2026-08-10T22:36:11.839028Z","end":"2026-08-10T22:36:11.950312Z","steps":["trace[1318095270] 'process raft request' (duration: 111.115599ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:04.672721Z","caller":"traceutil/trace.go:171","msg":"trace[1694097100] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"122.014223ms","start":"2026-08-10T22:40:04.550675Z","end":"2026-08-10T22:40:04.672689Z","steps":["trace[1694097100] 'process raft request' (duration: 117.656512ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:14.382609Z","caller":"traceutil/trace.go:171","msg":"trace[512610701] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"106.152914ms","start":"2026-08-10T22:40:14.276374Z","end":"2026-08-10T22:40:14.382527Z","steps":["trace[512610701] 'process raft request' (duration: 105.331799ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:26.17336Z","caller":"traceutil/trace.go:171","msg":"trace[1974206413] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"107.843444ms","start":"2026-08-10T22:40:26.065485Z","end":"2026-08-10T22:40:26.173329Z","steps":["trace[1974206413] 'process raft request' (duration: 64.462731ms)","trace[1974206413] 'compare' (duration: 42.917249ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:26.805946Z","caller":"traceutil/trace.go:171","msg":"trace[1077344104] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"100.606505ms","start":"2026-08-10T22:40:26.705307Z","end":"2026-08-10T22:40:26.805914Z","steps":["trace[1077344104] 'process raft request' (duration: 100.384449ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:26.805998Z","caller":"traceutil/trace.go:171","msg":"trace[1969191755] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"100.285196ms","start":"2026-08-10T22:40:26.705674Z","end":"2026-08-10T22:40:26.80596Z","steps":["trace[1969191755] 'process raft request' (duration: 100.158302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:34.543166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.732584ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-10T22:40:34.543338Z","caller":"traceutil/trace.go:171","msg":"trace[957083062] linearizableReadLoop","detail":"{readStateIndex:5693; appliedIndex:5692; }","duration":"121.55341ms","start":"2026-08-10T22:40:34.421738Z","end":"2026-08-10T22:40:34.543292Z","steps":["trace[957083062] 'read index received' (duration: 3.61043ms)","trace[957083062] 'applied index is now lower than readState.Index' (duration: 117.94106ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:40:34.54366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.915401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:34.543751Z","caller":"traceutil/trace.go:171","msg":"trace[1366884991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5490; }","duration":"122.017044ms","start":"2026-08-10T22:40:34.421712Z","end":"2026-08-10T22:40:34.543729Z","steps":["trace[1366884991] 'agreement among raft nodes before linearized reading' (duration: 121.869069ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:35.029052Z","caller":"traceutil/trace.go:171","msg":"trace[1047661344] linearizableReadLoop","detail":"{readStateIndex:5699; appliedIndex:5697; }","duration":"106.098782ms","start":"2026-08-10T22:40:34.922929Z","end":"2026-08-10T22:40:35.029028Z","steps":["trace[1047661344] 'read index received' (duration: 105.35867ms)","trace[1047661344] 'applied index is now lower than readState.Index' (duration: 739.032µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:35.029167Z","caller":"traceutil/trace.go:171","msg":"trace[689937621] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"178.178803ms","start":"2026-08-10T22:40:34.850927Z","end":"2026-08-10T22:40:35.029106Z","steps":["trace[689937621] 'process raft request' (duration: 177.965507ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:35.029195Z","caller":"traceutil/trace.go:171","msg":"trace[686641098] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"180.716539ms","start":"2026-08-10T22:40:34.848432Z","end":"2026-08-10T22:40:35.029148Z","steps":["trace[686641098] 'process raft request' (duration: 179.983737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:35.029378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.420352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-10T22:40:35.02945Z","caller":"traceutil/trace.go:171","msg":"trace[970460744] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5496; }","duration":"106.523895ms","start":"2026-08-10T22:40:34.922882Z","end":"2026-08-10T22:40:35.029406Z","steps":["trace[970460744] 'agreement among raft nodes before linearized reading' (duration: 106.260087ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:31.355082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"info","ts":"2026-08-10T22:41:31.426088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"69.813903ms","hash":2472454699,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":33751040,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-10T22:41:31.426144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2472454699,"revision":2957,"compact-revision":-1} {"level":"info","ts":"2026-08-10T22:46:31.362399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6016} {"level":"info","ts":"2026-08-10T22:46:31.460541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6016,"took":"96.280526ms","hash":334112171,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":21876736,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-10T22:46:31.460633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":334112171,"revision":6016,"compact-revision":2957} {"level":"info","ts":"2026-08-10T22:46:45.608226Z","caller":"traceutil/trace.go:171","msg":"trace[54641826] linearizableReadLoop","detail":"{readStateIndex:8625; appliedIndex:8624; }","duration":"239.328078ms","start":"2026-08-10T22:46:45.368879Z","end":"2026-08-10T22:46:45.608207Z","steps":["trace[54641826] 'read index received' (duration: 239.129562ms)","trace[54641826] 'applied index is now lower than readState.Index' (duration: 197.976µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:46:45.60829Z","caller":"traceutil/trace.go:171","msg":"trace[1710017706] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"353.815096ms","start":"2026-08-10T22:46:45.25445Z","end":"2026-08-10T22:46:45.608266Z","steps":["trace[1710017706] 'process raft request' (duration: 353.61333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:46:45.608349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.333314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:46:45.608376Z","caller":"traceutil/trace.go:171","msg":"trace[1182375677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8331; }","duration":"187.414386ms","start":"2026-08-10T22:46:45.420955Z","end":"2026-08-10T22:46:45.608369Z","steps":["trace[1182375677] 'agreement among raft nodes before linearized reading' (duration: 187.351415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:46:45.608384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.475552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:46:45.608439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:46:45.254414Z","time spent":"353.94956ms","remote":"127.0.0.1:38194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:46:45.608494Z","caller":"traceutil/trace.go:171","msg":"trace[1916985254] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8331; }","duration":"239.629237ms","start":"2026-08-10T22:46:45.368845Z","end":"2026-08-10T22:46:45.608474Z","steps":["trace[1916985254] 'agreement among raft nodes before linearized reading' (duration: 239.440731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:46:45.608926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.228258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T22:46:45.609026Z","caller":"traceutil/trace.go:171","msg":"trace[577258792] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8331; }","duration":"136.378242ms","start":"2026-08-10T22:46:45.472629Z","end":"2026-08-10T22:46:45.609007Z","steps":["trace[577258792] 'agreement among raft nodes before linearized reading' (duration: 135.737113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:46:57.119384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:46:57.119666Z","caller":"traceutil/trace.go:171","msg":"trace[228021284] linearizableReadLoop","detail":"{readStateIndex:8690; appliedIndex:8688; }","duration":"153.833474ms","start":"2026-08-10T22:46:56.965814Z","end":"2026-08-10T22:46:57.119647Z","steps":["trace[228021284] 'read index received' (duration: 50.391075ms)","trace[228021284] 'applied index is now lower than readState.Index' (duration: 103.441069ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:46:57.119688Z","caller":"traceutil/trace.go:171","msg":"trace[979107643] transaction","detail":"{read_only:false; response_revision:8393; number_of_response:1; }","duration":"202.262831ms","start":"2026-08-10T22:46:56.917398Z","end":"2026-08-10T22:46:57.119661Z","steps":["trace[979107643] 'process raft request' (duration: 98.78784ms)","trace[979107643] 'compare' (duration: 102.929005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:46:57.119747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.925897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:46:57.119782Z","caller":"traceutil/trace.go:171","msg":"trace[658416651] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8394; }","duration":"154.02186ms","start":"2026-08-10T22:46:56.96575Z","end":"2026-08-10T22:46:57.119772Z","steps":["trace[658416651] 'agreement among raft nodes before linearized reading' (duration: 153.953948ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:46:57.119759Z","caller":"traceutil/trace.go:171","msg":"trace[1946522953] transaction","detail":"{read_only:false; response_revision:8394; number_of_response:1; }","duration":"181.575253ms","start":"2026-08-10T22:46:56.938112Z","end":"2026-08-10T22:46:57.119688Z","steps":["trace[1946522953] 'process raft request' (duration: 181.447399ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:46:59.069469Z","caller":"traceutil/trace.go:171","msg":"trace[200397209] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"188.238881ms","start":"2026-08-10T22:46:58.881211Z","end":"2026-08-10T22:46:59.06945Z","steps":["trace[200397209] 'process raft request' (duration: 188.125808ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:47:27.001215Z","caller":"traceutil/trace.go:171","msg":"trace[537472230] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"173.033658ms","start":"2026-08-10T22:47:26.82816Z","end":"2026-08-10T22:47:27.001193Z","steps":["trace[537472230] 'process raft request' (duration: 172.913654ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:47:27.20783Z","caller":"traceutil/trace.go:171","msg":"trace[2113102668] linearizableReadLoop","detail":"{readStateIndex:8910; appliedIndex:8909; }","duration":"249.262115ms","start":"2026-08-10T22:47:26.958543Z","end":"2026-08-10T22:47:27.207805Z","steps":["trace[2113102668] 'read index received' (duration: 42.767917ms)","trace[2113102668] 'applied index is now lower than readState.Index' (duration: 206.493087ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:47:27.208054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.498631ms","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-10T22:47:27.208089Z","caller":"traceutil/trace.go:171","msg":"trace[929175394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8606; }","duration":"249.578124ms","start":"2026-08-10T22:47:26.958504Z","end":"2026-08-10T22:47:27.208082Z","steps":["trace[929175394] 'agreement among raft nodes before linearized reading' (duration: 249.392498ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:47:27.208241Z","caller":"traceutil/trace.go:171","msg":"trace[1721291699] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"298.084333ms","start":"2026-08-10T22:47:26.910127Z","end":"2026-08-10T22:47:27.208211Z","steps":["trace[1721291699] 'process raft request' (duration: 233.392761ms)","trace[1721291699] 'compare' (duration: 64.149906ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:47:40.466645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.86537ms","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-10T22:47:40.467014Z","caller":"traceutil/trace.go:171","msg":"trace[497668799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8717; }","duration":"116.014655ms","start":"2026-08-10T22:47:40.350697Z","end":"2026-08-10T22:47:40.466712Z","steps":["trace[497668799] 'range keys from in-memory index tree' (duration: 115.479479ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:47:40.499594Z","caller":"traceutil/trace.go:171","msg":"trace[1613199551] transaction","detail":"{read_only:false; response_revision:8718; number_of_response:1; }","duration":"143.269008ms","start":"2026-08-10T22:47:40.356248Z","end":"2026-08-10T22:47:40.499517Z","steps":["trace[1613199551] 'process raft request' (duration: 142.98536ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:47:40.616868Z","caller":"traceutil/trace.go:171","msg":"trace[952893251] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"145.753783ms","start":"2026-08-10T22:47:40.471076Z","end":"2026-08-10T22:47:40.61683Z","steps":["trace[952893251] 'process raft request' (duration: 126.679743ms)","trace[952893251] 'compare' (duration: 18.846073ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:47:40.616928Z","caller":"traceutil/trace.go:171","msg":"trace[774658917] linearizableReadLoop","detail":"{readStateIndex:9028; appliedIndex:9027; }","duration":"117.486739ms","start":"2026-08-10T22:47:40.499411Z","end":"2026-08-10T22:47:40.616898Z","steps":["trace[774658917] 'read index received' (duration: 98.44674ms)","trace[774658917] 'applied index is now lower than readState.Index' (duration: 19.037799ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:47:40.631404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.100056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:47:40.631564Z","caller":"traceutil/trace.go:171","msg":"trace[1972354432] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:8720; }","duration":"192.265921ms","start":"2026-08-10T22:47:40.439213Z","end":"2026-08-10T22:47:40.631479Z","steps":["trace[1972354432] 'agreement among raft nodes before linearized reading' (duration: 177.782949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:47:40.636694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.466165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:47:40.636775Z","caller":"traceutil/trace.go:171","msg":"trace[1976157811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8721; }","duration":"135.61699ms","start":"2026-08-10T22:47:40.501136Z","end":"2026-08-10T22:47:40.636753Z","steps":["trace[1976157811] 'agreement among raft nodes before linearized reading' (duration: 135.476466ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:47:40.90971Z","caller":"traceutil/trace.go:171","msg":"trace[1161208698] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"137.767084ms","start":"2026-08-10T22:47:40.77192Z","end":"2026-08-10T22:47:40.909687Z","steps":["trace[1161208698] 'process raft request' (duration: 137.646791ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:47:57.526794Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002412.wal"} {"level":"info","ts":"2026-08-10T22:49:23.274589Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7317567213c7319d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T22:49:23.286647Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-10T22:49:23.286786Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-10T22:51:31.371348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8118} {"level":"info","ts":"2026-08-10T22:51:31.431521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8118,"took":"58.011866ms","hash":1049259748,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":29687808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T22:51:31.431579Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1049259748,"revision":8118,"compact-revision":6016} {"level":"info","ts":"2026-08-10T22:56:31.378048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10711} {"level":"info","ts":"2026-08-10T22:56:31.456457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10711,"took":"74.915485ms","hash":992611893,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":27889664,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-10T22:56:31.456502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":992611893,"revision":10711,"compact-revision":8118} {"level":"info","ts":"2026-08-10T22:59:34.119673Z","caller":"traceutil/trace.go:171","msg":"trace[92142480] transaction","detail":"{read_only:false; response_revision:14255; number_of_response:1; }","duration":"218.074558ms","start":"2026-08-10T22:59:33.901548Z","end":"2026-08-10T22:59:34.119623Z","steps":["trace[92142480] 'process raft request' (duration: 217.604304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:00:54.688569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.475613ms","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-10T23:00:54.68866Z","caller":"traceutil/trace.go:171","msg":"trace[1375927738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15039; }","duration":"175.622158ms","start":"2026-08-10T23:00:54.513016Z","end":"2026-08-10T23:00:54.688639Z","steps":["trace[1375927738] 'range keys from in-memory index tree' (duration: 175.299469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:00:55.681563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.021895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T23:00:55.681746Z","caller":"traceutil/trace.go:171","msg":"trace[2135388441] linearizableReadLoop","detail":"{readStateIndex:15559; appliedIndex:15558; }","duration":"259.247048ms","start":"2026-08-10T23:00:55.422464Z","end":"2026-08-10T23:00:55.681711Z","steps":["trace[2135388441] 'read index received' (duration: 111.887093ms)","trace[2135388441] 'applied index is now lower than readState.Index' (duration: 147.358155ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:00:55.681874Z","caller":"traceutil/trace.go:171","msg":"trace[1495380298] transaction","detail":"{read_only:false; response_revision:15046; number_of_response:1; }","duration":"263.288749ms","start":"2026-08-10T23:00:55.418571Z","end":"2026-08-10T23:00:55.68186Z","steps":["trace[1495380298] 'process raft request' (duration: 115.812781ms)","trace[1495380298] 'compare' (duration: 146.84026ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:00:55.682029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.537527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T23:00:55.682303Z","caller":"traceutil/trace.go:171","msg":"trace[31789571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15046; }","duration":"259.766964ms","start":"2026-08-10T23:00:55.422417Z","end":"2026-08-10T23:00:55.682184Z","steps":["trace[31789571] 'agreement among raft nodes before linearized reading' (duration: 259.515437ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:00:55.85262Z","caller":"traceutil/trace.go:171","msg":"trace[840897078] linearizableReadLoop","detail":"{readStateIndex:15561; appliedIndex:15560; }","duration":"170.765044ms","start":"2026-08-10T23:00:55.68182Z","end":"2026-08-10T23:00:55.852585Z","steps":["trace[840897078] 'read index received' (duration: 39.374467ms)","trace[840897078] 'applied index is now lower than readState.Index' (duration: 131.389047ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:00:55.852913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.27196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T23:00:55.853013Z","caller":"traceutil/trace.go:171","msg":"trace[1496048146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15048; }","duration":"169.424185ms","start":"2026-08-10T23:00:55.683571Z","end":"2026-08-10T23:00:55.852995Z","steps":["trace[1496048146] 'agreement among raft nodes before linearized reading' (duration: 169.246039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:00:55.85306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.940224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wh2tw\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-08-10T23:00:55.853133Z","caller":"traceutil/trace.go:171","msg":"trace[1258318782] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wh2tw; range_end:; response_count:1; response_revision:15048; }","duration":"134.054677ms","start":"2026-08-10T23:00:55.719061Z","end":"2026-08-10T23:00:55.853115Z","steps":["trace[1258318782] 'agreement among raft nodes before linearized reading' (duration: 133.857651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:00:55.853158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.790052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wh2tw\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-08-10T23:00:55.853268Z","caller":"traceutil/trace.go:171","msg":"trace[2118152770] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wh2tw; range_end:; response_count:1; response_revision:15048; }","duration":"113.947387ms","start":"2026-08-10T23:00:55.739292Z","end":"2026-08-10T23:00:55.853239Z","steps":["trace[2118152770] 'agreement among raft nodes before linearized reading' (duration: 113.611427ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:00:55.852772Z","caller":"traceutil/trace.go:171","msg":"trace[789151945] transaction","detail":"{read_only:false; response_revision:15048; number_of_response:1; }","duration":"195.009689ms","start":"2026-08-10T23:00:55.657714Z","end":"2026-08-10T23:00:55.852724Z","steps":["trace[789151945] 'process raft request' (duration: 194.138052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:00:55.854469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.285691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737473"} {"level":"info","ts":"2026-08-10T23:00:55.854533Z","caller":"traceutil/trace.go:171","msg":"trace[819328653] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15048; }","duration":"431.353373ms","start":"2026-08-10T23:00:55.423166Z","end":"2026-08-10T23:00:55.854519Z","steps":["trace[819328653] 'agreement among raft nodes before linearized reading' (duration: 429.600741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:00:55.854572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:00:55.423148Z","time spent":"431.412014ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":76,"response size":737496,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-10T23:01:31.384533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12818} {"level":"info","ts":"2026-08-10T23:01:31.460716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12818,"took":"73.834218ms","hash":4096065197,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":30081024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T23:01:31.460768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4096065197,"revision":12818,"compact-revision":10711} {"level":"warn","ts":"2026-08-10T23:03:47.309873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.874085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-k6wzr\" ","response":"range_response_count:1 size:9798"} {"level":"warn","ts":"2026-08-10T23:03:47.310345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.287299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lmsl8\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-10T23:03:47.322111Z","caller":"traceutil/trace.go:171","msg":"trace[770228084] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lmsl8; range_end:; response_count:1; response_revision:16255; }","duration":"766.086981ms","start":"2026-08-10T23:03:46.556012Z","end":"2026-08-10T23:03:47.322099Z","steps":["trace[770228084] 'range keys from in-memory index tree' (duration: 753.884517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:47.322161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:46.556006Z","time spent":"766.137873ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-lmsl8\" "} {"level":"warn","ts":"2026-08-10T23:03:47.310425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.618908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T23:03:47.322217Z","caller":"traceutil/trace.go:171","msg":"trace[241000792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16255; }","duration":"766.429271ms","start":"2026-08-10T23:03:46.555767Z","end":"2026-08-10T23:03:47.322196Z","steps":["trace[241000792] 'range keys from in-memory index tree' (duration: 754.522915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:47.322276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:46.555748Z","time spent":"766.504604ms","remote":"127.0.0.1:38300","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":"info","ts":"2026-08-10T23:03:47.310805Z","caller":"traceutil/trace.go:171","msg":"trace[436789367] transaction","detail":"{read_only:false; response_revision:16256; number_of_response:1; }","duration":"313.207911ms","start":"2026-08-10T23:03:46.997571Z","end":"2026-08-10T23:03:47.310779Z","steps":["trace[436789367] 'process raft request' (duration: 313.072017ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:03:47.322049Z","caller":"traceutil/trace.go:171","msg":"trace[1852142481] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-k6wzr; range_end:; response_count:1; response_revision:16255; }","duration":"662.09428ms","start":"2026-08-10T23:03:46.659927Z","end":"2026-08-10T23:03:47.322022Z","steps":["trace[1852142481] 'range keys from in-memory index tree' (duration: 649.631508ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:03:47.312169Z","caller":"traceutil/trace.go:171","msg":"trace[594445031] transaction","detail":"{read_only:false; response_revision:16257; number_of_response:1; }","duration":"204.407976ms","start":"2026-08-10T23:03:47.107746Z","end":"2026-08-10T23:03:47.312154Z","steps":["trace[594445031] 'process raft request' (duration: 204.312913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:47.322579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:46.659908Z","time spent":"662.658278ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9821,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-k6wzr\" "} {"level":"warn","ts":"2026-08-10T23:03:47.322674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:46.997548Z","time spent":"324.930621ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:03:49.864742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189522904575720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-10T23:03:49.869058Z","caller":"traceutil/trace.go:171","msg":"trace[1599862396] transaction","detail":"{read_only:false; response_revision:16268; number_of_response:1; }","duration":"527.967697ms","start":"2026-08-10T23:03:49.34107Z","end":"2026-08-10T23:03:49.869037Z","steps":["trace[1599862396] 'process raft request' (duration: 527.865384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:49.869381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:49.341046Z","time spent":"528.071021ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:03:49.873823Z","caller":"traceutil/trace.go:171","msg":"trace[284066006] transaction","detail":"{read_only:false; response_revision:16269; number_of_response:1; }","duration":"418.916104ms","start":"2026-08-10T23:03:49.454802Z","end":"2026-08-10T23:03:49.873719Z","steps":["trace[284066006] 'process raft request' (duration: 418.313236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:49.873971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:49.454784Z","time spent":"419.113399ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:03:49.884053Z","caller":"traceutil/trace.go:171","msg":"trace[1655296338] linearizableReadLoop","detail":"{readStateIndex:16827; appliedIndex:16825; }","duration":"536.044729ms","start":"2026-08-10T23:03:49.347983Z","end":"2026-08-10T23:03:49.884028Z","steps":["trace[1655296338] 'read index received' (duration: 521.419971ms)","trace[1655296338] 'applied index is now lower than readState.Index' (duration: 14.623388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:03:49.884261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.273815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lmsl8\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-08-10T23:03:49.884278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.324357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-k6wzr\" ","response":"range_response_count:1 size:9798"} {"level":"info","ts":"2026-08-10T23:03:49.884306Z","caller":"traceutil/trace.go:171","msg":"trace[1858132798] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lmsl8; range_end:; response_count:1; response_revision:16269; }","duration":"536.357137ms","start":"2026-08-10T23:03:49.347935Z","end":"2026-08-10T23:03:49.884293Z","steps":["trace[1858132798] 'agreement among raft nodes before linearized reading' (duration: 536.214373ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:03:49.884312Z","caller":"traceutil/trace.go:171","msg":"trace[173033516] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-k6wzr; range_end:; response_count:1; response_revision:16269; }","duration":"535.456882ms","start":"2026-08-10T23:03:49.34884Z","end":"2026-08-10T23:03:49.884297Z","steps":["trace[173033516] 'agreement among raft nodes before linearized reading' (duration: 535.321167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:49.884332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:49.348819Z","time spent":"535.507002ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9821,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-k6wzr\" "} {"level":"warn","ts":"2026-08-10T23:03:49.884342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:49.347918Z","time spent":"536.407249ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-lmsl8\" "} {"level":"warn","ts":"2026-08-10T23:03:49.88463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.463137ms","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-10T23:03:49.884657Z","caller":"traceutil/trace.go:171","msg":"trace[259396159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16269; }","duration":"329.528949ms","start":"2026-08-10T23:03:49.555121Z","end":"2026-08-10T23:03:49.88465Z","steps":["trace[259396159] 'agreement among raft nodes before linearized reading' (duration: 329.459367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:49.884674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:49.555115Z","time spent":"329.55552ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-10T23:03:49.884764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.747375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T23:03:49.884783Z","caller":"traceutil/trace.go:171","msg":"trace[680522496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16269; }","duration":"462.809337ms","start":"2026-08-10T23:03:49.421968Z","end":"2026-08-10T23:03:49.884777Z","steps":["trace[680522496] 'agreement among raft nodes before linearized reading' (duration: 462.778726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:49.884799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:49.421945Z","time spent":"462.849758ms","remote":"127.0.0.1:38038","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-10T23:03:49.884917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.063545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T23:03:49.884941Z","caller":"traceutil/trace.go:171","msg":"trace[1891113120] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:16269; }","duration":"464.125036ms","start":"2026-08-10T23:03:49.420808Z","end":"2026-08-10T23:03:49.884933Z","steps":["trace[1891113120] 'agreement among raft nodes before linearized reading' (duration: 464.070595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:49.884962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:49.420785Z","time spent":"464.170827ms","remote":"127.0.0.1:38552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-10T23:06:31.390398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15227} {"level":"info","ts":"2026-08-10T23:06:31.480746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15227,"took":"88.086245ms","hash":2627814339,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T23:06:31.480817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2627814339,"revision":15227,"compact-revision":12818} {"level":"info","ts":"2026-08-10T23:07:13.195045Z","caller":"traceutil/trace.go:171","msg":"trace[1218586764] linearizableReadLoop","detail":"{readStateIndex:18420; appliedIndex:18419; }","duration":"133.905856ms","start":"2026-08-10T23:07:13.061115Z","end":"2026-08-10T23:07:13.195021Z","steps":["trace[1218586764] 'read index received' (duration: 133.776643ms)","trace[1218586764] 'applied index is now lower than readState.Index' (duration: 128.383µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:07:13.195145Z","caller":"traceutil/trace.go:171","msg":"trace[957757339] transaction","detail":"{read_only:false; response_revision:17813; number_of_response:1; }","duration":"146.523674ms","start":"2026-08-10T23:07:13.048581Z","end":"2026-08-10T23:07:13.195105Z","steps":["trace[957757339] 'process raft request' (duration: 146.317828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:07:13.195252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.135564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-djq29\" ","response":"range_response_count:1 size:20758"} {"level":"info","ts":"2026-08-10T23:07:13.195527Z","caller":"traceutil/trace.go:171","msg":"trace[1321945333] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-djq29; range_end:; response_count:1; response_revision:17813; }","duration":"134.437692ms","start":"2026-08-10T23:07:13.061054Z","end":"2026-08-10T23:07:13.195492Z","steps":["trace[1321945333] 'agreement among raft nodes before linearized reading' (duration: 134.072481ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:07:14.176814Z","caller":"traceutil/trace.go:171","msg":"trace[979938213] transaction","detail":"{read_only:false; response_revision:17820; number_of_response:1; }","duration":"138.35449ms","start":"2026-08-10T23:07:14.038424Z","end":"2026-08-10T23:07:14.176779Z","steps":["trace[979938213] 'process raft request' (duration: 138.138023ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:10:10.14261Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7317567213c7319d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:10:10.148121Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-10T23:10:10.14826Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-10T23:11:31.396971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17543} {"level":"info","ts":"2026-08-10T23:11:31.489553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17543,"took":"89.605639ms","hash":89271731,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":34291712,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-10T23:11:31.48961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":89271731,"revision":17543,"compact-revision":15227} {"level":"info","ts":"2026-08-10T23:12:59.547714Z","caller":"traceutil/trace.go:171","msg":"trace[372762370] transaction","detail":"{read_only:false; response_revision:20503; number_of_response:1; }","duration":"126.147116ms","start":"2026-08-10T23:12:59.421545Z","end":"2026-08-10T23:12:59.547692Z","steps":["trace[372762370] 'process raft request' (duration: 126.008202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.077181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.305447ms","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-10T23:13:05.077299Z","caller":"traceutil/trace.go:171","msg":"trace[1457564746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20529; }","duration":"147.472063ms","start":"2026-08-10T23:13:04.929797Z","end":"2026-08-10T23:13:05.077269Z","steps":["trace[1457564746] 'range keys from in-memory index tree' (duration: 147.087131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.077641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.511821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-cfn-75df85c8c4-b9k5n.18ca947a39900845\" ","response":"range_response_count:1 size:907"} {"level":"info","ts":"2026-08-10T23:13:05.077868Z","caller":"traceutil/trace.go:171","msg":"trace[1667352456] range","detail":"{range_begin:/registry/events/openstack/heat-cfn-75df85c8c4-b9k5n.18ca947a39900845; range_end:; response_count:1; response_revision:20529; }","duration":"198.699548ms","start":"2026-08-10T23:13:04.879049Z","end":"2026-08-10T23:13:05.077748Z","steps":["trace[1667352456] 'range keys from in-memory index tree' (duration: 198.058248ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:05.428426Z","caller":"traceutil/trace.go:171","msg":"trace[530405896] linearizableReadLoop","detail":"{readStateIndex:21228; appliedIndex:21226; }","duration":"332.967676ms","start":"2026-08-10T23:13:05.095412Z","end":"2026-08-10T23:13:05.42838Z","steps":["trace[530405896] 'read index received' (duration: 46.754053ms)","trace[530405896] 'applied index is now lower than readState.Index' (duration: 286.212733ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:13:05.428818Z","caller":"traceutil/trace.go:171","msg":"trace[940981212] transaction","detail":"{read_only:false; response_revision:20530; number_of_response:1; }","duration":"346.8709ms","start":"2026-08-10T23:13:05.081903Z","end":"2026-08-10T23:13:05.428774Z","steps":["trace[940981212] 'process raft request' (duration: 346.276572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.428983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:13:05.081876Z","time spent":"347.027595ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:13:05.428967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.535302ms","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-10T23:13:05.42906Z","caller":"traceutil/trace.go:171","msg":"trace[1056100625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20530; }","duration":"333.677116ms","start":"2026-08-10T23:13:05.095358Z","end":"2026-08-10T23:13:05.429036Z","steps":["trace[1056100625] 'agreement among raft nodes before linearized reading' (duration: 333.367837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.429115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:13:05.095343Z","time spent":"333.762099ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-10T23:13:05.686474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.652701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:13:05.686878Z","caller":"traceutil/trace.go:171","msg":"trace[1967839023] transaction","detail":"{read_only:false; response_revision:20532; number_of_response:1; }","duration":"253.468307ms","start":"2026-08-10T23:13:05.433396Z","end":"2026-08-10T23:13:05.686864Z","steps":["trace[1967839023] 'process raft request' (duration: 253.407436ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:05.686957Z","caller":"traceutil/trace.go:171","msg":"trace[1053343070] linearizableReadLoop","detail":"{readStateIndex:21229; appliedIndex:21228; }","duration":"258.417825ms","start":"2026-08-10T23:13:05.428511Z","end":"2026-08-10T23:13:05.686929Z","steps":["trace[1053343070] 'read index received' (duration: 128.26725ms)","trace[1053343070] 'applied index is now lower than readState.Index' (duration: 130.148455ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:13:05.686894Z","caller":"traceutil/trace.go:171","msg":"trace[1111702169] transaction","detail":"{read_only:false; response_revision:20531; number_of_response:1; }","duration":"543.34153ms","start":"2026-08-10T23:13:05.143517Z","end":"2026-08-10T23:13:05.686858Z","steps":["trace[1111702169] 'process raft request' (duration: 413.238416ms)","trace[1111702169] 'compare' (duration: 129.551578ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:13:05.687219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:13:05.143495Z","time spent":"543.693121ms","remote":"127.0.0.1:38098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":889,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:13:05.687235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.284925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:13:05.687132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.976691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:13:05.687296Z","caller":"traceutil/trace.go:171","msg":"trace[819758891] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:20532; }","duration":"329.383528ms","start":"2026-08-10T23:13:05.357899Z","end":"2026-08-10T23:13:05.687283Z","steps":["trace[819758891] 'agreement among raft nodes before linearized reading' (duration: 329.255574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.687339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:13:05.357878Z","time spent":"329.45116ms","remote":"127.0.0.1:49368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-10T23:13:05.687393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.03631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T23:13:05.687481Z","caller":"traceutil/trace.go:171","msg":"trace[725842248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20532; }","duration":"590.170374ms","start":"2026-08-10T23:13:05.097288Z","end":"2026-08-10T23:13:05.687458Z","steps":["trace[725842248] 'agreement among raft nodes before linearized reading' (duration: 589.923297ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:05.687376Z","caller":"traceutil/trace.go:171","msg":"trace[1187196045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20532; }","duration":"264.218528ms","start":"2026-08-10T23:13:05.423078Z","end":"2026-08-10T23:13:05.687297Z","steps":["trace[1187196045] 'agreement among raft nodes before linearized reading' (duration: 263.997322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.687529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:13:05.097265Z","time spent":"590.255117ms","remote":"127.0.0.1:38300","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-10T23:13:05.70193Z","caller":"traceutil/trace.go:171","msg":"trace[90236125] transaction","detail":"{read_only:false; response_revision:20533; number_of_response:1; }","duration":"124.621151ms","start":"2026-08-10T23:13:05.577288Z","end":"2026-08-10T23:13:05.701909Z","steps":["trace[90236125] 'process raft request' (duration: 124.413705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.70202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.39976ms","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-10T23:13:05.702115Z","caller":"traceutil/trace.go:171","msg":"trace[465548396] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:20533; }","duration":"236.514233ms","start":"2026-08-10T23:13:05.465578Z","end":"2026-08-10T23:13:05.702093Z","steps":["trace[465548396] 'agreement among raft nodes before linearized reading' (duration: 236.341668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.96259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.750856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:13:05.962703Z","caller":"traceutil/trace.go:171","msg":"trace[1478167177] linearizableReadLoop","detail":"{readStateIndex:21232; appliedIndex:21231; }","duration":"260.797307ms","start":"2026-08-10T23:13:05.701888Z","end":"2026-08-10T23:13:05.962685Z","steps":["trace[1478167177] 'read index received' (duration: 132.896047ms)","trace[1478167177] 'applied index is now lower than readState.Index' (duration: 127.90013ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:13:05.962789Z","caller":"traceutil/trace.go:171","msg":"trace[1555553243] transaction","detail":"{read_only:false; response_revision:20534; number_of_response:1; }","duration":"271.730602ms","start":"2026-08-10T23:13:05.691025Z","end":"2026-08-10T23:13:05.962756Z","steps":["trace[1555553243] 'process raft request' (duration: 143.7301ms)","trace[1555553243] 'compare' (duration: 127.462096ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:13:05.963227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.933628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-api-fd4dd78c6-m7lvl.18ca947a3a057806\" ","response":"range_response_count:1 size:904"} {"level":"info","ts":"2026-08-10T23:13:05.963277Z","caller":"traceutil/trace.go:171","msg":"trace[865827548] range","detail":"{range_begin:/registry/events/openstack/heat-api-fd4dd78c6-m7lvl.18ca947a3a057806; range_end:; response_count:1; response_revision:20534; }","duration":"272.00422ms","start":"2026-08-10T23:13:05.69126Z","end":"2026-08-10T23:13:05.963264Z","steps":["trace[865827548] 'agreement among raft nodes before linearized reading' (duration: 271.840805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:05.963461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.953568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:13:05.963552Z","caller":"traceutil/trace.go:171","msg":"trace[723095101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20534; }","duration":"275.063031ms","start":"2026-08-10T23:13:05.688443Z","end":"2026-08-10T23:13:05.963506Z","steps":["trace[723095101] 'agreement among raft nodes before linearized reading' (duration: 274.288908ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:06.113096Z","caller":"traceutil/trace.go:171","msg":"trace[1613846446] transaction","detail":"{read_only:false; response_revision:20535; number_of_response:1; }","duration":"147.513682ms","start":"2026-08-10T23:13:05.965558Z","end":"2026-08-10T23:13:06.113072Z","steps":["trace[1613846446] 'process raft request' (duration: 141.938155ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:06.123612Z","caller":"traceutil/trace.go:171","msg":"trace[675435833] transaction","detail":"{read_only:false; response_revision:20536; number_of_response:1; }","duration":"155.009446ms","start":"2026-08-10T23:13:05.968585Z","end":"2026-08-10T23:13:06.123594Z","steps":["trace[675435833] 'process raft request' (duration: 154.884153ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:06.578123Z","caller":"traceutil/trace.go:171","msg":"trace[1077247633] transaction","detail":"{read_only:false; response_revision:20538; number_of_response:1; }","duration":"112.071458ms","start":"2026-08-10T23:13:06.466012Z","end":"2026-08-10T23:13:06.578084Z","steps":["trace[1077247633] 'process raft request' (duration: 111.869672ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:31.402811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19928} {"level":"info","ts":"2026-08-10T23:16:31.512288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19928,"took":"106.463472ms","hash":1095637097,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:16:31.512349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1095637097,"revision":19928,"compact-revision":17543} {"level":"info","ts":"2026-08-10T23:16:56.373861Z","caller":"traceutil/trace.go:171","msg":"trace[1296240214] transaction","detail":"{read_only:false; response_revision:22970; number_of_response:1; }","duration":"120.537702ms","start":"2026-08-10T23:16:56.253282Z","end":"2026-08-10T23:16:56.37382Z","steps":["trace[1296240214] 'process raft request' (duration: 120.286454ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:56.377044Z","caller":"traceutil/trace.go:171","msg":"trace[1285359026] transaction","detail":"{read_only:false; response_revision:22971; number_of_response:1; }","duration":"114.396792ms","start":"2026-08-10T23:16:56.26255Z","end":"2026-08-10T23:16:56.376947Z","steps":["trace[1285359026] 'process raft request' (duration: 114.256758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:56.695775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.076765ms","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-10T23:16:56.695867Z","caller":"traceutil/trace.go:171","msg":"trace[290926564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22971; }","duration":"149.212008ms","start":"2026-08-10T23:16:56.546633Z","end":"2026-08-10T23:16:56.695845Z","steps":["trace[290926564] 'range keys from in-memory index tree' (duration: 148.89926ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:56.695962Z","caller":"traceutil/trace.go:171","msg":"trace[71090131] transaction","detail":"{read_only:false; response_revision:22972; number_of_response:1; }","duration":"130.836702ms","start":"2026-08-10T23:16:56.565062Z","end":"2026-08-10T23:16:56.695899Z","steps":["trace[71090131] 'process raft request' (duration: 115.832084ms)","trace[71090131] 'compare' (duration: 14.670878ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:27.668007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.626355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:27.668127Z","caller":"traceutil/trace.go:171","msg":"trace[1950970385] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"189.030251ms","start":"2026-08-10T23:20:27.479076Z","end":"2026-08-10T23:20:27.668106Z","steps":["trace[1950970385] 'process raft request' (duration: 86.1852ms)","trace[1950970385] 'compare' (duration: 102.48355ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:27.99241Z","caller":"traceutil/trace.go:171","msg":"trace[1262007535] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"116.353903ms","start":"2026-08-10T23:20:27.876025Z","end":"2026-08-10T23:20:27.992379Z","steps":["trace[1262007535] 'process raft request' (duration: 104.074497ms)","trace[1262007535] 'compare' (duration: 12.107861ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:28.489717Z","caller":"traceutil/trace.go:171","msg":"trace[370406299] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"371.876782ms","start":"2026-08-10T23:20:28.117801Z","end":"2026-08-10T23:20:28.489678Z","steps":["trace[370406299] 'process raft request' (duration: 371.707797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:28.489939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:28.117751Z","time spent":"372.094139ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:28.489743Z","caller":"traceutil/trace.go:171","msg":"trace[464529227] linearizableReadLoop","detail":"{readStateIndex:25955; appliedIndex:25955; }","duration":"325.679594ms","start":"2026-08-10T23:20:28.16403Z","end":"2026-08-10T23:20:28.48971Z","steps":["trace[464529227] 'read index received' (duration: 325.663043ms)","trace[464529227] 'applied index is now lower than readState.Index' (duration: 13.631µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:28.490141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.081355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T23:20:28.490231Z","caller":"traceutil/trace.go:171","msg":"trace[2040013204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25131; }","duration":"326.224079ms","start":"2026-08-10T23:20:28.163994Z","end":"2026-08-10T23:20:28.490218Z","steps":["trace[2040013204] 'agreement among raft nodes before linearized reading' (duration: 325.89782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:28.490312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:28.163976Z","time spent":"326.314031ms","remote":"127.0.0.1:38300","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-10T23:20:28.514591Z","caller":"traceutil/trace.go:171","msg":"trace[1039949924] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"286.385033ms","start":"2026-08-10T23:20:28.22815Z","end":"2026-08-10T23:20:28.514535Z","steps":["trace[1039949924] 'process raft request' (duration: 286.101635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:28.809624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.822818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:28.809748Z","caller":"traceutil/trace.go:171","msg":"trace[464848039] linearizableReadLoop","detail":"{readStateIndex:25958; appliedIndex:25957; }","duration":"291.743489ms","start":"2026-08-10T23:20:28.517974Z","end":"2026-08-10T23:20:28.809718Z","steps":["trace[464848039] 'read index received' (duration: 45.687195ms)","trace[464848039] 'applied index is now lower than readState.Index' (duration: 246.049704ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:28.809876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.902033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:28.809854Z","caller":"traceutil/trace.go:171","msg":"trace[1234400509] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"316.084405ms","start":"2026-08-10T23:20:28.493741Z","end":"2026-08-10T23:20:28.809825Z","steps":["trace[1234400509] 'process raft request' (duration: 69.976869ms)","trace[1234400509] 'compare' (duration: 245.626242ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:28.809902Z","caller":"traceutil/trace.go:171","msg":"trace[1206027022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25134; }","duration":"293.542361ms","start":"2026-08-10T23:20:28.51635Z","end":"2026-08-10T23:20:28.809893Z","steps":["trace[1206027022] 'agreement among raft nodes before linearized reading' (duration: 293.48948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:28.810001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:28.493716Z","time spent":"316.210699ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:28.810133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.959948ms","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-10T23:20:28.810166Z","caller":"traceutil/trace.go:171","msg":"trace[1283023349] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25134; }","duration":"200.03604ms","start":"2026-08-10T23:20:28.610119Z","end":"2026-08-10T23:20:28.810155Z","steps":["trace[1283023349] 'agreement among raft nodes before linearized reading' (duration: 199.956967ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:28.960147Z","caller":"traceutil/trace.go:171","msg":"trace[2090647993] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"146.081796ms","start":"2026-08-10T23:20:28.814033Z","end":"2026-08-10T23:20:28.960114Z","steps":["trace[2090647993] 'process raft request' (duration: 128.122605ms)","trace[2090647993] 'compare' (duration: 17.815367ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:29.383748Z","caller":"traceutil/trace.go:171","msg":"trace[493392012] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"387.828436ms","start":"2026-08-10T23:20:28.995894Z","end":"2026-08-10T23:20:29.383722Z","steps":["trace[493392012] 'process raft request' (duration: 387.653111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:29.38389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:28.995877Z","time spent":"387.939808ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:29.794777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.262607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:29.795024Z","caller":"traceutil/trace.go:171","msg":"trace[1966692592] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"445.692744ms","start":"2026-08-10T23:20:29.349315Z","end":"2026-08-10T23:20:29.795007Z","steps":["trace[1966692592] 'process raft request' (duration: 445.648313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:29.795096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:29.349296Z","time spent":"445.770975ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:29.795176Z","caller":"traceutil/trace.go:171","msg":"trace[1597205213] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"489.573455ms","start":"2026-08-10T23:20:29.305585Z","end":"2026-08-10T23:20:29.795158Z","steps":["trace[1597205213] 'process raft request' (duration: 489.294956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:29.795255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:29.305562Z","time spent":"489.650098ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:29.795018Z","caller":"traceutil/trace.go:171","msg":"trace[791929555] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"518.418042ms","start":"2026-08-10T23:20:29.276565Z","end":"2026-08-10T23:20:29.794983Z","steps":["trace[791929555] 'process raft request' (duration: 257.827376ms)","trace[791929555] 'compare' (duration: 259.934396ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:29.795515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:29.276537Z","time spent":"518.895846ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:29.810268Z","caller":"traceutil/trace.go:171","msg":"trace[1526710273] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"136.994562ms","start":"2026-08-10T23:20:29.673249Z","end":"2026-08-10T23:20:29.810243Z","steps":["trace[1526710273] 'process raft request' (duration: 136.822637ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:30.1194Z","caller":"traceutil/trace.go:171","msg":"trace[1649392434] linearizableReadLoop","detail":"{readStateIndex:25966; appliedIndex:25964; }","duration":"271.328927ms","start":"2026-08-10T23:20:29.848048Z","end":"2026-08-10T23:20:30.119377Z","steps":["trace[1649392434] 'read index received' (duration: 92.481771ms)","trace[1649392434] 'applied index is now lower than readState.Index' (duration: 178.846296ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:30.120049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.000726ms","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-10T23:20:30.120784Z","caller":"traceutil/trace.go:171","msg":"trace[75757476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25142; }","duration":"124.787759ms","start":"2026-08-10T23:20:29.995987Z","end":"2026-08-10T23:20:30.120775Z","steps":["trace[75757476] 'agreement among raft nodes before linearized reading' (duration: 123.922363ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:30.120038Z","caller":"traceutil/trace.go:171","msg":"trace[1343803427] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"267.523307ms","start":"2026-08-10T23:20:29.85247Z","end":"2026-08-10T23:20:30.119993Z","steps":["trace[1343803427] 'process raft request' (duration: 266.854417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:30.120147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.09715ms","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-10T23:20:30.121233Z","caller":"traceutil/trace.go:171","msg":"trace[1043167045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25142; }","duration":"273.225363ms","start":"2026-08-10T23:20:29.847996Z","end":"2026-08-10T23:20:30.121221Z","steps":["trace[1043167045] 'agreement among raft nodes before linearized reading' (duration: 272.043469ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:30.120701Z","caller":"traceutil/trace.go:171","msg":"trace[564806231] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"273.320075ms","start":"2026-08-10T23:20:29.847363Z","end":"2026-08-10T23:20:30.120683Z","steps":["trace[564806231] 'process raft request' (duration: 271.822991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:30.427534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.076217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:30.427654Z","caller":"traceutil/trace.go:171","msg":"trace[87352465] linearizableReadLoop","detail":"{readStateIndex:25969; appliedIndex:25967; }","duration":"105.448546ms","start":"2026-08-10T23:20:30.322195Z","end":"2026-08-10T23:20:30.427643Z","steps":["trace[87352465] 'read index received' (duration: 48.32088ms)","trace[87352465] 'applied index is now lower than readState.Index' (duration: 57.126706ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:30.427766Z","caller":"traceutil/trace.go:171","msg":"trace[2140157353] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"300.471762ms","start":"2026-08-10T23:20:30.127286Z","end":"2026-08-10T23:20:30.427758Z","steps":["trace[2140157353] 'process raft request' (duration: 300.316477ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:30.427742Z","caller":"traceutil/trace.go:171","msg":"trace[1205771214] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"302.740827ms","start":"2026-08-10T23:20:30.124978Z","end":"2026-08-10T23:20:30.427719Z","steps":["trace[1205771214] 'process raft request' (duration: 88.398524ms)","trace[1205771214] 'compare' (duration: 213.991633ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:30.4278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:30.127277Z","time spent":"300.501682ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:30.42786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:30.124961Z","time spent":"302.825439ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:30.42795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.760106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:30.428159Z","caller":"traceutil/trace.go:171","msg":"trace[1578370611] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:25144; }","duration":"105.972313ms","start":"2026-08-10T23:20:30.322179Z","end":"2026-08-10T23:20:30.428152Z","steps":["trace[1578370611] 'agreement among raft nodes before linearized reading' (duration: 105.731175ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:30.827235Z","caller":"traceutil/trace.go:171","msg":"trace[253379965] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25969; }","duration":"272.341856ms","start":"2026-08-10T23:20:30.55487Z","end":"2026-08-10T23:20:30.827212Z","steps":["trace[253379965] 'read index received' (duration: 13.215792ms)","trace[253379965] 'applied index is now lower than readState.Index' (duration: 259.125064ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:30.827513Z","caller":"traceutil/trace.go:171","msg":"trace[554773994] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"308.504464ms","start":"2026-08-10T23:20:30.518995Z","end":"2026-08-10T23:20:30.8275Z","steps":["trace[554773994] 'process raft request' (duration: 301.173041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:30.827617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:30.518973Z","time spent":"308.576807ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:30.827832Z","caller":"traceutil/trace.go:171","msg":"trace[1630029675] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"298.70188ms","start":"2026-08-10T23:20:30.529097Z","end":"2026-08-10T23:20:30.827798Z","steps":["trace[1630029675] 'process raft request' (duration: 298.071272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:30.828048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.168359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:20:30.828094Z","caller":"traceutil/trace.go:171","msg":"trace[557216090] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25147; }","duration":"273.246902ms","start":"2026-08-10T23:20:30.554835Z","end":"2026-08-10T23:20:30.828082Z","steps":["trace[557216090] 'agreement among raft nodes before linearized reading' (duration: 273.16617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:30.828333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.66808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:30.828389Z","caller":"traceutil/trace.go:171","msg":"trace[1314838168] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25147; }","duration":"188.740082ms","start":"2026-08-10T23:20:30.639622Z","end":"2026-08-10T23:20:30.828362Z","steps":["trace[1314838168] 'agreement among raft nodes before linearized reading' (duration: 188.67514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:31.102556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.303697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:31.102731Z","caller":"traceutil/trace.go:171","msg":"trace[1245862410] linearizableReadLoop","detail":"{readStateIndex:25975; appliedIndex:25973; }","duration":"137.310721ms","start":"2026-08-10T23:20:30.965405Z","end":"2026-08-10T23:20:31.102716Z","steps":["trace[1245862410] 'read index received' (duration: 15.882131ms)","trace[1245862410] 'applied index is now lower than readState.Index' (duration: 121.42787ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:31.102973Z","caller":"traceutil/trace.go:171","msg":"trace[241636830] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"243.19666ms","start":"2026-08-10T23:20:30.859761Z","end":"2026-08-10T23:20:31.102958Z","steps":["trace[241636830] 'process raft request' (duration: 121.42002ms)","trace[241636830] 'compare' (duration: 120.854554ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:31.103143Z","caller":"traceutil/trace.go:171","msg":"trace[1849473537] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"243.20705ms","start":"2026-08-10T23:20:30.859926Z","end":"2026-08-10T23:20:31.103133Z","steps":["trace[1849473537] 'process raft request' (duration: 242.737267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:31.103351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.94741ms","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-10T23:20:31.103393Z","caller":"traceutil/trace.go:171","msg":"trace[152624713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25150; }","duration":"138.024393ms","start":"2026-08-10T23:20:30.965358Z","end":"2026-08-10T23:20:31.103383Z","steps":["trace[152624713] 'agreement among raft nodes before linearized reading' (duration: 137.899729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:31.384233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.687631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:31.384343Z","caller":"traceutil/trace.go:171","msg":"trace[1678828247] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"276.161547ms","start":"2026-08-10T23:20:31.108167Z","end":"2026-08-10T23:20:31.384328Z","steps":["trace[1678828247] 'process raft request' (duration: 127.314961ms)","trace[1678828247] 'compare' (duration: 148.582198ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:31.434944Z","caller":"traceutil/trace.go:171","msg":"trace[986574614] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"324.839119ms","start":"2026-08-10T23:20:31.110077Z","end":"2026-08-10T23:20:31.434916Z","steps":["trace[986574614] 'process raft request' (duration: 324.670094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:31.435128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:31.110065Z","time spent":"324.968012ms","remote":"127.0.0.1:38098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:31.857239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.051171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:31.857363Z","caller":"traceutil/trace.go:171","msg":"trace[1916812730] linearizableReadLoop","detail":"{readStateIndex:25980; appliedIndex:25979; }","duration":"144.213982ms","start":"2026-08-10T23:20:31.713131Z","end":"2026-08-10T23:20:31.857345Z","steps":["trace[1916812730] 'read index received' (duration: 33.919473ms)","trace[1916812730] 'applied index is now lower than readState.Index' (duration: 110.293399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:31.857432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.314745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:31.85747Z","caller":"traceutil/trace.go:171","msg":"trace[18156304] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25155; }","duration":"144.400457ms","start":"2026-08-10T23:20:31.713059Z","end":"2026-08-10T23:20:31.85746Z","steps":["trace[18156304] 'agreement among raft nodes before linearized reading' (duration: 144.332515ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:31.857575Z","caller":"traceutil/trace.go:171","msg":"trace[730124230] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"189.262928ms","start":"2026-08-10T23:20:31.668281Z","end":"2026-08-10T23:20:31.857544Z","steps":["trace[730124230] 'process raft request' (duration: 78.816446ms)","trace[730124230] 'compare' (duration: 109.831625ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:32.140534Z","caller":"traceutil/trace.go:171","msg":"trace[701963022] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"340.74413ms","start":"2026-08-10T23:20:31.799772Z","end":"2026-08-10T23:20:32.140516Z","steps":["trace[701963022] 'process raft request' (duration: 290.3834ms)","trace[701963022] 'compare' (duration: 50.287308ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:32.140715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:31.799742Z","time spent":"340.914975ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:32.240014Z","caller":"traceutil/trace.go:171","msg":"trace[582531803] linearizableReadLoop","detail":"{readStateIndex:25983; appliedIndex:25980; }","duration":"376.606739ms","start":"2026-08-10T23:20:31.863383Z","end":"2026-08-10T23:20:32.239989Z","steps":["trace[582531803] 'read index received' (duration: 226.787466ms)","trace[582531803] 'applied index is now lower than readState.Index' (duration: 149.818453ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:32.240095Z","caller":"traceutil/trace.go:171","msg":"trace[10706724] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"425.182357ms","start":"2026-08-10T23:20:31.814887Z","end":"2026-08-10T23:20:32.24007Z","steps":["trace[10706724] 'process raft request' (duration: 425.063423ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:32.240119Z","caller":"traceutil/trace.go:171","msg":"trace[1486930663] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"439.132712ms","start":"2026-08-10T23:20:31.800934Z","end":"2026-08-10T23:20:32.240067Z","steps":["trace[1486930663] 'process raft request' (duration: 438.885345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:32.240217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:31.814867Z","time spent":"425.27509ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:32.240319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:31.800913Z","time spent":"439.297446ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:32.240162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.768104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:32.24044Z","caller":"traceutil/trace.go:171","msg":"trace[1023945701] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25158; }","duration":"377.081292ms","start":"2026-08-10T23:20:31.863326Z","end":"2026-08-10T23:20:32.240407Z","steps":["trace[1023945701] 'agreement among raft nodes before linearized reading' (duration: 376.779974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:32.240532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:31.863306Z","time spent":"377.198156ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-08-10T23:20:32.366939Z","caller":"traceutil/trace.go:171","msg":"trace[1339721041] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"240.464802ms","start":"2026-08-10T23:20:32.12645Z","end":"2026-08-10T23:20:32.366915Z","steps":["trace[1339721041] 'process raft request' (duration: 149.420072ms)","trace[1339721041] 'compare' (duration: 90.355379ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:32.367132Z","caller":"traceutil/trace.go:171","msg":"trace[1876725709] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"239.6924ms","start":"2026-08-10T23:20:32.127427Z","end":"2026-08-10T23:20:32.36712Z","steps":["trace[1876725709] 'process raft request' (duration: 239.277118ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:32.367137Z","caller":"traceutil/trace.go:171","msg":"trace[316365039] linearizableReadLoop","detail":"{readStateIndex:25985; appliedIndex:25983; }","duration":"127.003423ms","start":"2026-08-10T23:20:32.240085Z","end":"2026-08-10T23:20:32.367088Z","steps":["trace[316365039] 'read index received' (duration: 35.716386ms)","trace[316365039] 'applied index is now lower than readState.Index' (duration: 91.285797ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:32.367361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.00647ms","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-10T23:20:32.367422Z","caller":"traceutil/trace.go:171","msg":"trace[453812449] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25160; }","duration":"278.123134ms","start":"2026-08-10T23:20:32.089284Z","end":"2026-08-10T23:20:32.367407Z","steps":["trace[453812449] 'agreement among raft nodes before linearized reading' (duration: 277.926479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:32.36806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.291187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:32.368124Z","caller":"traceutil/trace.go:171","msg":"trace[741977432] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25160; }","duration":"122.353698ms","start":"2026-08-10T23:20:32.245752Z","end":"2026-08-10T23:20:32.368106Z","steps":["trace[741977432] 'agreement among raft nodes before linearized reading' (duration: 122.262236ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:32.747345Z","caller":"traceutil/trace.go:171","msg":"trace[2040657332] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"312.814389ms","start":"2026-08-10T23:20:32.434507Z","end":"2026-08-10T23:20:32.747322Z","steps":["trace[2040657332] 'process raft request' (duration: 312.659305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:32.74748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:32.434494Z","time spent":"312.915542ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:32.748054Z","caller":"traceutil/trace.go:171","msg":"trace[950045905] linearizableReadLoop","detail":"{readStateIndex:25987; appliedIndex:25985; }","duration":"310.997187ms","start":"2026-08-10T23:20:32.437027Z","end":"2026-08-10T23:20:32.748024Z","steps":["trace[950045905] 'read index received' (duration: 310.133211ms)","trace[950045905] 'applied index is now lower than readState.Index' (duration: 863.166µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:32.748076Z","caller":"traceutil/trace.go:171","msg":"trace[630521071] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"312.077159ms","start":"2026-08-10T23:20:32.435981Z","end":"2026-08-10T23:20:32.748058Z","steps":["trace[630521071] 'process raft request' (duration: 311.903944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:32.748192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:32.435967Z","time spent":"312.160781ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:32.748306Z","caller":"traceutil/trace.go:171","msg":"trace[2104236137] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"173.784399ms","start":"2026-08-10T23:20:32.574513Z","end":"2026-08-10T23:20:32.748298Z","steps":["trace[2104236137] 'process raft request' (duration: 173.47561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:32.748438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.407329ms","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-10T23:20:32.748464Z","caller":"traceutil/trace.go:171","msg":"trace[1400108446] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25163; }","duration":"311.44493ms","start":"2026-08-10T23:20:32.437012Z","end":"2026-08-10T23:20:32.748457Z","steps":["trace[1400108446] 'agreement among raft nodes before linearized reading' (duration: 311.341107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:32.748483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:32.437005Z","time spent":"311.47439ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-10T23:20:33.091077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.885463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:33.091294Z","caller":"traceutil/trace.go:171","msg":"trace[12192516] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"257.430954ms","start":"2026-08-10T23:20:32.833829Z","end":"2026-08-10T23:20:33.09126Z","steps":["trace[12192516] 'process raft request' (duration: 114.254543ms)","trace[12192516] 'compare' (duration: 142.466051ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:33.091384Z","caller":"traceutil/trace.go:171","msg":"trace[549072415] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"257.458135ms","start":"2026-08-10T23:20:32.833894Z","end":"2026-08-10T23:20:33.091352Z","steps":["trace[549072415] 'process raft request' (duration: 257.28984ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:33.091321Z","caller":"traceutil/trace.go:171","msg":"trace[1124877834] linearizableReadLoop","detail":"{readStateIndex:25990; appliedIndex:25988; }","duration":"236.810785ms","start":"2026-08-10T23:20:32.854487Z","end":"2026-08-10T23:20:33.091298Z","steps":["trace[1124877834] 'read index received' (duration: 93.611704ms)","trace[1124877834] 'applied index is now lower than readState.Index' (duration: 143.197541ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:33.091508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.015581ms","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-10T23:20:33.091555Z","caller":"traceutil/trace.go:171","msg":"trace[1743670101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25165; }","duration":"237.091883ms","start":"2026-08-10T23:20:32.854449Z","end":"2026-08-10T23:20:33.09154Z","steps":["trace[1743670101] 'agreement among raft nodes before linearized reading' (duration: 236.908888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:33.091696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.150304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:20:33.091771Z","caller":"traceutil/trace.go:171","msg":"trace[1538397146] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25165; }","duration":"149.292208ms","start":"2026-08-10T23:20:32.942459Z","end":"2026-08-10T23:20:33.091751Z","steps":["trace[1538397146] 'agreement among raft nodes before linearized reading' (duration: 149.116663ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:33.597188Z","caller":"traceutil/trace.go:171","msg":"trace[562011356] linearizableReadLoop","detail":"{readStateIndex:25992; appliedIndex:25991; }","duration":"174.147029ms","start":"2026-08-10T23:20:33.423013Z","end":"2026-08-10T23:20:33.59716Z","steps":["trace[562011356] 'read index received' (duration: 173.920662ms)","trace[562011356] 'applied index is now lower than readState.Index' (duration: 225.127µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:33.597282Z","caller":"traceutil/trace.go:171","msg":"trace[1262959933] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"204.184411ms","start":"2026-08-10T23:20:33.39307Z","end":"2026-08-10T23:20:33.597254Z","steps":["trace[1262959933] 'process raft request' (duration: 203.876912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:33.597349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.325525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:33.597392Z","caller":"traceutil/trace.go:171","msg":"trace[2065830811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25167; }","duration":"174.420867ms","start":"2026-08-10T23:20:33.42296Z","end":"2026-08-10T23:20:33.597381Z","steps":["trace[2065830811] 'agreement among raft nodes before linearized reading' (duration: 174.333515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:33.863647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.140066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:33.863746Z","caller":"traceutil/trace.go:171","msg":"trace[637203657] linearizableReadLoop","detail":"{readStateIndex:25993; appliedIndex:25992; }","duration":"107.355453ms","start":"2026-08-10T23:20:33.756378Z","end":"2026-08-10T23:20:33.863733Z","steps":["trace[637203657] 'read index received' (duration: 74.523µs)","trace[637203657] 'applied index is now lower than readState.Index' (duration: 107.28002ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:33.863828Z","caller":"traceutil/trace.go:171","msg":"trace[485060501] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"262.437529ms","start":"2026-08-10T23:20:33.60136Z","end":"2026-08-10T23:20:33.863798Z","steps":["trace[485060501] 'process raft request' (duration: 105.068787ms)","trace[485060501] 'compare' (duration: 156.981711ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:33.863857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.478936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:20:33.863933Z","caller":"traceutil/trace.go:171","msg":"trace[1414818896] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25168; }","duration":"107.583329ms","start":"2026-08-10T23:20:33.756344Z","end":"2026-08-10T23:20:33.863928Z","steps":["trace[1414818896] 'agreement among raft nodes before linearized reading' (duration: 107.418144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:34.483443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.581415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:34.483973Z","caller":"traceutil/trace.go:171","msg":"trace[1458098912] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"338.802174ms","start":"2026-08-10T23:20:34.145114Z","end":"2026-08-10T23:20:34.483917Z","steps":["trace[1458098912] 'process raft request' (duration: 186.646913ms)","trace[1458098912] 'compare' (duration: 151.41072ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:34.484068Z","caller":"traceutil/trace.go:171","msg":"trace[1623780713] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"238.630129ms","start":"2026-08-10T23:20:34.2454Z","end":"2026-08-10T23:20:34.48403Z","steps":["trace[1623780713] 'process raft request' (duration: 238.159756ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:34.484162Z","caller":"traceutil/trace.go:171","msg":"trace[1808903563] linearizableReadLoop","detail":"{readStateIndex:25997; appliedIndex:25994; }","duration":"203.046637ms","start":"2026-08-10T23:20:34.28109Z","end":"2026-08-10T23:20:34.484137Z","steps":["trace[1808903563] 'read index received' (duration: 50.595677ms)","trace[1808903563] 'applied index is now lower than readState.Index' (duration: 152.44964ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:34.484176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:34.145084Z","time spent":"338.978888ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:34.484346Z","caller":"traceutil/trace.go:171","msg":"trace[1886853205] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"238.892797ms","start":"2026-08-10T23:20:34.245435Z","end":"2026-08-10T23:20:34.484327Z","steps":["trace[1886853205] 'process raft request' (duration: 238.218567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:34.484386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.287584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:34.484441Z","caller":"traceutil/trace.go:171","msg":"trace[1954857406] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25172; }","duration":"203.387986ms","start":"2026-08-10T23:20:34.281036Z","end":"2026-08-10T23:20:34.484424Z","steps":["trace[1954857406] 'agreement among raft nodes before linearized reading' (duration: 203.296364ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:34.783576Z","caller":"traceutil/trace.go:171","msg":"trace[798238410] linearizableReadLoop","detail":"{readStateIndex:25999; appliedIndex:25997; }","duration":"299.341189ms","start":"2026-08-10T23:20:34.484211Z","end":"2026-08-10T23:20:34.783553Z","steps":["trace[798238410] 'read index received' (duration: 282.295735ms)","trace[798238410] 'applied index is now lower than readState.Index' (duration: 17.044534ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:34.783681Z","caller":"traceutil/trace.go:171","msg":"trace[39745981] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"410.954284ms","start":"2026-08-10T23:20:34.372699Z","end":"2026-08-10T23:20:34.783653Z","steps":["trace[39745981] 'process raft request' (duration: 393.796747ms)","trace[39745981] 'compare' (duration: 16.654533ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:34.783809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:34.372669Z","time spent":"411.072508ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:34.783726Z","caller":"traceutil/trace.go:171","msg":"trace[1594235654] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"410.08583ms","start":"2026-08-10T23:20:34.37352Z","end":"2026-08-10T23:20:34.783606Z","steps":["trace[1594235654] 'process raft request' (duration: 409.938436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:34.784141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.196255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.48\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-08-10T23:20:34.78417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:34.373505Z","time spent":"410.581764ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:34.784243Z","caller":"traceutil/trace.go:171","msg":"trace[1182030131] range","detail":"{range_begin:/registry/masterleases/199.19.213.48; range_end:; response_count:1; response_revision:25174; }","duration":"309.325078ms","start":"2026-08-10T23:20:34.474877Z","end":"2026-08-10T23:20:34.784202Z","steps":["trace[1182030131] 'agreement among raft nodes before linearized reading' (duration: 308.790853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:34.784291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:34.474856Z","time spent":"309.425392ms","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.19.213.48\" "} {"level":"info","ts":"2026-08-10T23:20:35.003274Z","caller":"traceutil/trace.go:171","msg":"trace[2026396029] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"196.070545ms","start":"2026-08-10T23:20:34.807174Z","end":"2026-08-10T23:20:35.003244Z","steps":["trace[2026396029] 'process raft request' (duration: 170.859474ms)","trace[2026396029] 'compare' (duration: 25.035336ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:41.837542Z","caller":"traceutil/trace.go:171","msg":"trace[1610088829] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"206.117335ms","start":"2026-08-10T23:20:41.631394Z","end":"2026-08-10T23:20:41.837511Z","steps":["trace[1610088829] 'process raft request' (duration: 205.9291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:42.13351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.994442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:42.133988Z","caller":"traceutil/trace.go:171","msg":"trace[1124599320] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"433.246052ms","start":"2026-08-10T23:20:41.700696Z","end":"2026-08-10T23:20:42.133942Z","steps":["trace[1124599320] 'process raft request' (duration: 245.705324ms)","trace[1124599320] 'compare' (duration: 186.608941ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:42.134046Z","caller":"traceutil/trace.go:171","msg":"trace[1637165170] linearizableReadLoop","detail":"{readStateIndex:26055; appliedIndex:26053; }","duration":"238.32924ms","start":"2026-08-10T23:20:41.895696Z","end":"2026-08-10T23:20:42.134025Z","steps":["trace[1637165170] 'read index received' (duration: 50.803793ms)","trace[1637165170] 'applied index is now lower than readState.Index' (duration: 187.523177ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:42.134083Z","caller":"traceutil/trace.go:171","msg":"trace[481652344] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"266.293771ms","start":"2026-08-10T23:20:41.867753Z","end":"2026-08-10T23:20:42.134047Z","steps":["trace[481652344] 'process raft request' (duration: 265.89739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:42.134114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:41.700671Z","time spent":"433.391426ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:42.134253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.544516ms","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-10T23:20:42.134315Z","caller":"traceutil/trace.go:171","msg":"trace[1574086619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25228; }","duration":"238.645029ms","start":"2026-08-10T23:20:41.895651Z","end":"2026-08-10T23:20:42.134296Z","steps":["trace[1574086619] 'agreement among raft nodes before linearized reading' (duration: 238.441733ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:42.389453Z","caller":"traceutil/trace.go:171","msg":"trace[1688972291] linearizableReadLoop","detail":"{readStateIndex:26056; appliedIndex:26055; }","duration":"121.827261ms","start":"2026-08-10T23:20:42.267604Z","end":"2026-08-10T23:20:42.389432Z","steps":["trace[1688972291] 'read index received' (duration: 119.606958ms)","trace[1688972291] 'applied index is now lower than readState.Index' (duration: 2.218833ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:42.389523Z","caller":"traceutil/trace.go:171","msg":"trace[1545112563] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"251.11571ms","start":"2026-08-10T23:20:42.138377Z","end":"2026-08-10T23:20:42.389493Z","steps":["trace[1545112563] 'process raft request' (duration: 248.773602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:42.389622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.002037ms","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-10T23:20:42.389723Z","caller":"traceutil/trace.go:171","msg":"trace[1188937843] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25229; }","duration":"122.145691ms","start":"2026-08-10T23:20:42.267551Z","end":"2026-08-10T23:20:42.389697Z","steps":["trace[1188937843] 'agreement among raft nodes before linearized reading' (duration: 121.992307ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:42.675154Z","caller":"traceutil/trace.go:171","msg":"trace[717160923] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"168.732653ms","start":"2026-08-10T23:20:42.50639Z","end":"2026-08-10T23:20:42.675122Z","steps":["trace[717160923] 'process raft request' (duration: 166.89249ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:42.984639Z","caller":"traceutil/trace.go:171","msg":"trace[630334931] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"476.067883ms","start":"2026-08-10T23:20:42.508496Z","end":"2026-08-10T23:20:42.984563Z","steps":["trace[630334931] 'process raft request' (duration: 449.011068ms)","trace[630334931] 'compare' (duration: 26.705935ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:42.984739Z","caller":"traceutil/trace.go:171","msg":"trace[557359492] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"161.467871ms","start":"2026-08-10T23:20:42.823245Z","end":"2026-08-10T23:20:42.984713Z","steps":["trace[557359492] 'process raft request' (duration: 161.376689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:42.984818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:42.508466Z","time spent":"476.259339ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:42.984849Z","caller":"traceutil/trace.go:171","msg":"trace[1788428861] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"470.09743ms","start":"2026-08-10T23:20:42.514714Z","end":"2026-08-10T23:20:42.984812Z","steps":["trace[1788428861] 'process raft request' (duration: 469.767181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:42.984934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.177423ms","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-10T23:20:42.984981Z","caller":"traceutil/trace.go:171","msg":"trace[826446605] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"162.393229ms","start":"2026-08-10T23:20:42.822576Z","end":"2026-08-10T23:20:42.984969Z","steps":["trace[826446605] 'process raft request' (duration: 161.969977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:42.985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:42.51469Z","time spent":"470.227334ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:42.985017Z","caller":"traceutil/trace.go:171","msg":"trace[610029680] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"160.621537ms","start":"2026-08-10T23:20:42.824384Z","end":"2026-08-10T23:20:42.985006Z","steps":["trace[610029680] 'process raft request' (duration: 160.294677ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:42.984767Z","caller":"traceutil/trace.go:171","msg":"trace[204952470] linearizableReadLoop","detail":"{readStateIndex:26062; appliedIndex:26057; }","duration":"151.005538ms","start":"2026-08-10T23:20:42.833744Z","end":"2026-08-10T23:20:42.98475Z","steps":["trace[204952470] 'read index received' (duration: 123.744388ms)","trace[204952470] 'applied index is now lower than readState.Index' (duration: 27.26041ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:42.984982Z","caller":"traceutil/trace.go:171","msg":"trace[23144576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25235; }","duration":"151.275626ms","start":"2026-08-10T23:20:42.833698Z","end":"2026-08-10T23:20:42.984973Z","steps":["trace[23144576] 'agreement among raft nodes before linearized reading' (duration: 151.097091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:42.985305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.870906ms","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-10T23:20:42.985354Z","caller":"traceutil/trace.go:171","msg":"trace[1285072978] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25235; }","duration":"119.939948ms","start":"2026-08-10T23:20:42.8654Z","end":"2026-08-10T23:20:42.98534Z","steps":["trace[1285072978] 'agreement among raft nodes before linearized reading' (duration: 119.784684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:42.985569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.375411ms","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-10T23:20:42.985688Z","caller":"traceutil/trace.go:171","msg":"trace[1552362301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25235; }","duration":"150.520494ms","start":"2026-08-10T23:20:42.835136Z","end":"2026-08-10T23:20:42.985656Z","steps":["trace[1552362301] 'agreement among raft nodes before linearized reading' (duration: 150.03489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:43.381693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.530691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:43.381834Z","caller":"traceutil/trace.go:171","msg":"trace[1704488769] linearizableReadLoop","detail":"{readStateIndex:26063; appliedIndex:26062; }","duration":"391.10203ms","start":"2026-08-10T23:20:42.990714Z","end":"2026-08-10T23:20:43.381816Z","steps":["trace[1704488769] 'read index received' (duration: 256.329452ms)","trace[1704488769] 'applied index is now lower than readState.Index' (duration: 134.771448ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:43.381929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.214163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:43.381923Z","caller":"traceutil/trace.go:171","msg":"trace[1038351390] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"391.361787ms","start":"2026-08-10T23:20:42.990521Z","end":"2026-08-10T23:20:43.381883Z","steps":["trace[1038351390] 'process raft request' (duration: 256.556548ms)","trace[1038351390] 'compare' (duration: 134.423217ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:43.381961Z","caller":"traceutil/trace.go:171","msg":"trace[1499913558] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25236; }","duration":"391.263075ms","start":"2026-08-10T23:20:42.990685Z","end":"2026-08-10T23:20:43.381948Z","steps":["trace[1499913558] 'agreement among raft nodes before linearized reading' (duration: 391.200523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:43.382002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:42.990676Z","time spent":"391.314156ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-08-10T23:20:43.382071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:42.990507Z","time spent":"391.485391ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:43.383622Z","caller":"traceutil/trace.go:171","msg":"trace[1632855204] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"391.285254ms","start":"2026-08-10T23:20:42.992315Z","end":"2026-08-10T23:20:43.383601Z","steps":["trace[1632855204] 'process raft request' (duration: 391.024337ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:43.383658Z","caller":"traceutil/trace.go:171","msg":"trace[1274727410] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"262.714647ms","start":"2026-08-10T23:20:43.120917Z","end":"2026-08-10T23:20:43.383631Z","steps":["trace[1274727410] 'process raft request' (duration: 262.589333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:43.383732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:42.992303Z","time spent":"391.384257ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:43.383807Z","caller":"traceutil/trace.go:171","msg":"trace[1417315602] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"242.056808ms","start":"2026-08-10T23:20:43.141738Z","end":"2026-08-10T23:20:43.383794Z","steps":["trace[1417315602] 'process raft request' (duration: 241.828521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:43.384191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.624841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T23:20:43.384278Z","caller":"traceutil/trace.go:171","msg":"trace[2029742613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25239; }","duration":"225.762975ms","start":"2026-08-10T23:20:43.158496Z","end":"2026-08-10T23:20:43.384259Z","steps":["trace[2029742613] 'agreement among raft nodes before linearized reading' (duration: 225.521198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:43.384344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.396906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-10T23:20:43.384422Z","caller":"traceutil/trace.go:171","msg":"trace[1128127579] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25239; }","duration":"393.513549ms","start":"2026-08-10T23:20:42.990886Z","end":"2026-08-10T23:20:43.3844Z","steps":["trace[1128127579] 'agreement among raft nodes before linearized reading' (duration: 392.883681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:43.384469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:42.990881Z","time spent":"393.575511ms","remote":"127.0.0.1:38194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18253,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-10T23:20:50.58848Z","caller":"traceutil/trace.go:171","msg":"trace[1938417257] linearizableReadLoop","detail":"{readStateIndex:26172; appliedIndex:26171; }","duration":"180.754831ms","start":"2026-08-10T23:20:50.407703Z","end":"2026-08-10T23:20:50.588458Z","steps":["trace[1938417257] 'read index received' (duration: 180.547185ms)","trace[1938417257] 'applied index is now lower than readState.Index' (duration: 206.816µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:50.588764Z","caller":"traceutil/trace.go:171","msg":"trace[855821127] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"227.525986ms","start":"2026-08-10T23:20:50.361226Z","end":"2026-08-10T23:20:50.588752Z","steps":["trace[855821127] 'process raft request' (duration: 227.086064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:50.589023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.308156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b2ceea4-4985-472e-a1e2-66161b5f3b90\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-10T23:20:50.589058Z","caller":"traceutil/trace.go:171","msg":"trace[1547249288] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b2ceea4-4985-472e-a1e2-66161b5f3b90; range_end:; response_count:1; response_revision:25342; }","duration":"181.376779ms","start":"2026-08-10T23:20:50.40767Z","end":"2026-08-10T23:20:50.589046Z","steps":["trace[1547249288] 'agreement among raft nodes before linearized reading' (duration: 181.247745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:50.589243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.929549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:20:50.589263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.837909ms","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-10T23:20:50.589308Z","caller":"traceutil/trace.go:171","msg":"trace[1471525392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25342; }","duration":"164.902701ms","start":"2026-08-10T23:20:50.424395Z","end":"2026-08-10T23:20:50.589298Z","steps":["trace[1471525392] 'agreement among raft nodes before linearized reading' (duration: 164.795408ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:50.589318Z","caller":"traceutil/trace.go:171","msg":"trace[2103106815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25342; }","duration":"167.047603ms","start":"2026-08-10T23:20:50.422247Z","end":"2026-08-10T23:20:50.589295Z","steps":["trace[2103106815] 'agreement among raft nodes before linearized reading' (duration: 166.918659ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:50.775193Z","caller":"traceutil/trace.go:171","msg":"trace[330158041] linearizableReadLoop","detail":"{readStateIndex:26173; appliedIndex:26172; }","duration":"157.054094ms","start":"2026-08-10T23:20:50.618109Z","end":"2026-08-10T23:20:50.775163Z","steps":["trace[330158041] 'read index received' (duration: 132.834461ms)","trace[330158041] 'applied index is now lower than readState.Index' (duration: 24.218552ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:50.775326Z","caller":"traceutil/trace.go:171","msg":"trace[2121142455] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"181.202745ms","start":"2026-08-10T23:20:50.594105Z","end":"2026-08-10T23:20:50.775307Z","steps":["trace[2121142455] 'process raft request' (duration: 156.806187ms)","trace[2121142455] 'compare' (duration: 23.821171ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:50.775551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.414864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:20:50.775641Z","caller":"traceutil/trace.go:171","msg":"trace[2037614392] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25343; }","duration":"157.553488ms","start":"2026-08-10T23:20:50.618065Z","end":"2026-08-10T23:20:50.775618Z","steps":["trace[2037614392] 'agreement among raft nodes before linearized reading' (duration: 157.377593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.04611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.786603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:51.046217Z","caller":"traceutil/trace.go:171","msg":"trace[526892201] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"340.77057ms","start":"2026-08-10T23:20:50.705426Z","end":"2026-08-10T23:20:51.046196Z","steps":["trace[526892201] 'process raft request' (duration: 180.812742ms)","trace[526892201] 'compare' (duration: 159.649939ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:51.046318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:50.705403Z","time spent":"340.859932ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:51.046446Z","caller":"traceutil/trace.go:171","msg":"trace[1389033187] linearizableReadLoop","detail":"{readStateIndex:26174; appliedIndex:26173; }","duration":"271.175983ms","start":"2026-08-10T23:20:50.775264Z","end":"2026-08-10T23:20:51.04644Z","steps":["trace[1389033187] 'read index received' (duration: 110.925226ms)","trace[1389033187] 'applied index is now lower than readState.Index' (duration: 160.250117ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:51.046541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.718181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b2ceea4-4985-472e-a1e2-66161b5f3b90\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-10T23:20:51.046564Z","caller":"traceutil/trace.go:171","msg":"trace[1136555668] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b2ceea4-4985-472e-a1e2-66161b5f3b90; range_end:; response_count:1; response_revision:25345; }","duration":"339.766491ms","start":"2026-08-10T23:20:50.70679Z","end":"2026-08-10T23:20:51.046557Z","steps":["trace[1136555668] 'agreement among raft nodes before linearized reading' (duration: 339.686709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.046578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:50.706775Z","time spent":"339.800563ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b2ceea4-4985-472e-a1e2-66161b5f3b90\" "} {"level":"info","ts":"2026-08-10T23:20:51.282066Z","caller":"traceutil/trace.go:171","msg":"trace[728529567] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"265.610401ms","start":"2026-08-10T23:20:51.016436Z","end":"2026-08-10T23:20:51.282047Z","steps":["trace[728529567] 'process raft request' (duration: 175.54036ms)","trace[728529567] 'compare' (duration: 89.511704ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:51.282255Z","caller":"traceutil/trace.go:171","msg":"trace[1081707469] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"265.651083ms","start":"2026-08-10T23:20:51.016596Z","end":"2026-08-10T23:20:51.282247Z","steps":["trace[1081707469] 'process raft request' (duration: 265.381235ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:51.282513Z","caller":"traceutil/trace.go:171","msg":"trace[1449815198] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"265.795357ms","start":"2026-08-10T23:20:51.016702Z","end":"2026-08-10T23:20:51.282497Z","steps":["trace[1449815198] 'process raft request' (duration: 265.516959ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:51.282587Z","caller":"traceutil/trace.go:171","msg":"trace[1904363186] linearizableReadLoop","detail":"{readStateIndex:26179; appliedIndex:26175; }","duration":"236.118306ms","start":"2026-08-10T23:20:51.04646Z","end":"2026-08-10T23:20:51.282579Z","steps":["trace[1904363186] 'read index received' (duration: 145.5272ms)","trace[1904363186] 'applied index is now lower than readState.Index' (duration: 90.590566ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:20:51.28266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.907331ms","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-10T23:20:51.282686Z","caller":"traceutil/trace.go:171","msg":"trace[717554021] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25349; }","duration":"283.985373ms","start":"2026-08-10T23:20:50.998693Z","end":"2026-08-10T23:20:51.282679Z","steps":["trace[717554021] 'agreement among raft nodes before linearized reading' (duration: 283.918391ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:51.282815Z","caller":"traceutil/trace.go:171","msg":"trace[730899447] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"253.4693ms","start":"2026-08-10T23:20:51.029339Z","end":"2026-08-10T23:20:51.282808Z","steps":["trace[730899447] 'process raft request' (duration: 253.099519ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:51.567301Z","caller":"traceutil/trace.go:171","msg":"trace[350013938] linearizableReadLoop","detail":"{readStateIndex:26180; appliedIndex:26179; }","duration":"171.720918ms","start":"2026-08-10T23:20:51.395558Z","end":"2026-08-10T23:20:51.567279Z","steps":["trace[350013938] 'read index received' (duration: 110.128792ms)","trace[350013938] 'applied index is now lower than readState.Index' (duration: 61.591246ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:51.567313Z","caller":"traceutil/trace.go:171","msg":"trace[1722394622] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"515.370003ms","start":"2026-08-10T23:20:51.0519Z","end":"2026-08-10T23:20:51.56727Z","steps":["trace[1722394622] 'process raft request' (duration: 453.796508ms)","trace[1722394622] 'compare' (duration: 61.40983ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:51.567525Z","caller":"traceutil/trace.go:171","msg":"trace[69123992] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"158.208448ms","start":"2026-08-10T23:20:51.409305Z","end":"2026-08-10T23:20:51.567514Z","steps":["trace[69123992] 'process raft request' (duration: 157.924549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.567535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:51.051882Z","time spent":"515.571909ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:51.567581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.003698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:51.567644Z","caller":"traceutil/trace.go:171","msg":"trace[908043710] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25351; }","duration":"172.111191ms","start":"2026-08-10T23:20:51.395519Z","end":"2026-08-10T23:20:51.56763Z","steps":["trace[908043710] 'agreement among raft nodes before linearized reading' (duration: 171.911495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.567739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.755426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:20:51.567772Z","caller":"traceutil/trace.go:171","msg":"trace[1682098418] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"157.968061ms","start":"2026-08-10T23:20:51.409792Z","end":"2026-08-10T23:20:51.56776Z","steps":["trace[1682098418] 'process raft request' (duration: 157.678623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.567779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.665502ms","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-10T23:20:51.567803Z","caller":"traceutil/trace.go:171","msg":"trace[1711437853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25352; }","duration":"146.828518ms","start":"2026-08-10T23:20:51.42096Z","end":"2026-08-10T23:20:51.567788Z","steps":["trace[1711437853] 'agreement among raft nodes before linearized reading' (duration: 146.733595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.567803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.015524ms","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-10T23:20:51.567815Z","caller":"traceutil/trace.go:171","msg":"trace[248893088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25352; }","duration":"156.739514ms","start":"2026-08-10T23:20:51.411066Z","end":"2026-08-10T23:20:51.567806Z","steps":["trace[248893088] 'agreement among raft nodes before linearized reading' (duration: 156.59829ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:51.567845Z","caller":"traceutil/trace.go:171","msg":"trace[1028862630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25352; }","duration":"156.070695ms","start":"2026-08-10T23:20:51.411763Z","end":"2026-08-10T23:20:51.567834Z","steps":["trace[1028862630] 'agreement among raft nodes before linearized reading' (duration: 155.963352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.567895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.512717ms","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-10T23:20:51.567976Z","caller":"traceutil/trace.go:171","msg":"trace[16760418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25352; }","duration":"147.627271ms","start":"2026-08-10T23:20:51.42033Z","end":"2026-08-10T23:20:51.567957Z","steps":["trace[16760418] 'agreement among raft nodes before linearized reading' (duration: 147.388314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.817889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.973407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:20:51.817983Z","caller":"traceutil/trace.go:171","msg":"trace[1800937955] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"246.184608ms","start":"2026-08-10T23:20:51.571782Z","end":"2026-08-10T23:20:51.817966Z","steps":["trace[1800937955] 'process raft request' (duration: 105.060837ms)","trace[1800937955] 'compare' (duration: 140.769801ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:51.930354Z","caller":"traceutil/trace.go:171","msg":"trace[1973815806] linearizableReadLoop","detail":"{readStateIndex:26185; appliedIndex:26182; }","duration":"334.682083ms","start":"2026-08-10T23:20:51.595649Z","end":"2026-08-10T23:20:51.930331Z","steps":["trace[1973815806] 'read index received' (duration: 81.115742ms)","trace[1973815806] 'applied index is now lower than readState.Index' (duration: 253.565311ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:51.930428Z","caller":"traceutil/trace.go:171","msg":"trace[1632932486] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"358.156444ms","start":"2026-08-10T23:20:51.572231Z","end":"2026-08-10T23:20:51.930387Z","steps":["trace[1632932486] 'process raft request' (duration: 345.458447ms)","trace[1632932486] 'compare' (duration: 12.470951ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:51.930466Z","caller":"traceutil/trace.go:171","msg":"trace[644632959] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"357.292829ms","start":"2026-08-10T23:20:51.573143Z","end":"2026-08-10T23:20:51.930436Z","steps":["trace[644632959] 'process raft request' (duration: 357.135354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.930577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b2ceea4-4985-472e-a1e2-66161b5f3b90\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-10T23:20:51.930626Z","caller":"traceutil/trace.go:171","msg":"trace[1561560658] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b2ceea4-4985-472e-a1e2-66161b5f3b90; range_end:; response_count:1; response_revision:25355; }","duration":"335.016213ms","start":"2026-08-10T23:20:51.595597Z","end":"2026-08-10T23:20:51.930613Z","steps":["trace[1561560658] 'agreement among raft nodes before linearized reading' (duration: 334.858968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:51.930653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:51.595577Z","time spent":"335.070645ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b2ceea4-4985-472e-a1e2-66161b5f3b90\" "} {"level":"warn","ts":"2026-08-10T23:20:51.930637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:51.573133Z","time spent":"357.391741ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:20:51.930624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:20:51.572218Z","time spent":"358.300148ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:20:53.466779Z","caller":"traceutil/trace.go:171","msg":"trace[392878720] linearizableReadLoop","detail":"{readStateIndex:26195; appliedIndex:26191; }","duration":"152.741229ms","start":"2026-08-10T23:20:53.314015Z","end":"2026-08-10T23:20:53.466756Z","steps":["trace[392878720] 'read index received' (duration: 9.680951ms)","trace[392878720] 'applied index is now lower than readState.Index' (duration: 143.059528ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:53.466962Z","caller":"traceutil/trace.go:171","msg":"trace[452527319] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"178.085704ms","start":"2026-08-10T23:20:53.288862Z","end":"2026-08-10T23:20:53.466947Z","steps":["trace[452527319] 'process raft request' (duration: 177.799285ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:53.46683Z","caller":"traceutil/trace.go:171","msg":"trace[29410793] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"178.722352ms","start":"2026-08-10T23:20:53.288078Z","end":"2026-08-10T23:20:53.466801Z","steps":["trace[29410793] 'process raft request' (duration: 178.410982ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:53.467212Z","caller":"traceutil/trace.go:171","msg":"trace[1740972355] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"177.766595ms","start":"2026-08-10T23:20:53.289434Z","end":"2026-08-10T23:20:53.467201Z","steps":["trace[1740972355] 'process raft request' (duration: 177.271011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:20:53.467276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.346926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:20:53.467296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.286124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:20:53.467312Z","caller":"traceutil/trace.go:171","msg":"trace[2094538508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25365; }","duration":"103.432139ms","start":"2026-08-10T23:20:53.363871Z","end":"2026-08-10T23:20:53.467303Z","steps":["trace[2094538508] 'agreement among raft nodes before linearized reading' (duration: 103.345426ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:53.467318Z","caller":"traceutil/trace.go:171","msg":"trace[496054858] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25365; }","duration":"153.353966ms","start":"2026-08-10T23:20:53.313957Z","end":"2026-08-10T23:20:53.467311Z","steps":["trace[496054858] 'agreement among raft nodes before linearized reading' (duration: 153.305855ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:56.152489Z","caller":"traceutil/trace.go:171","msg":"trace[954490544] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"170.516696ms","start":"2026-08-10T23:20:55.981949Z","end":"2026-08-10T23:20:56.152466Z","steps":["trace[954490544] 'process raft request' (duration: 170.473115ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:56.15257Z","caller":"traceutil/trace.go:171","msg":"trace[42678590] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"171.392331ms","start":"2026-08-10T23:20:55.981143Z","end":"2026-08-10T23:20:56.152535Z","steps":["trace[42678590] 'process raft request' (duration: 96.307363ms)","trace[42678590] 'compare' (duration: 74.851871ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:57.692664Z","caller":"traceutil/trace.go:171","msg":"trace[1856337870] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"106.604402ms","start":"2026-08-10T23:20:57.586025Z","end":"2026-08-10T23:20:57.692629Z","steps":["trace[1856337870] 'process raft request' (duration: 105.48264ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:00.084388Z","caller":"traceutil/trace.go:171","msg":"trace[440523940] linearizableReadLoop","detail":"{readStateIndex:26272; appliedIndex:26270; }","duration":"144.155902ms","start":"2026-08-10T23:20:59.940209Z","end":"2026-08-10T23:21:00.084365Z","steps":["trace[440523940] 'read index received' (duration: 47.440337ms)","trace[440523940] 'applied index is now lower than readState.Index' (duration: 96.714425ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:00.08473Z","caller":"traceutil/trace.go:171","msg":"trace[1603284746] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"145.117839ms","start":"2026-08-10T23:20:59.939588Z","end":"2026-08-10T23:21:00.084706Z","steps":["trace[1603284746] 'process raft request' (duration: 144.622445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:00.085088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.865452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-10T23:21:00.085122Z","caller":"traceutil/trace.go:171","msg":"trace[228732702] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25440; }","duration":"144.960555ms","start":"2026-08-10T23:20:59.940152Z","end":"2026-08-10T23:21:00.085112Z","steps":["trace[228732702] 'agreement among raft nodes before linearized reading' (duration: 144.80934ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:15.275462Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006716.wal"} {"level":"info","ts":"2026-08-10T23:21:21.914474Z","caller":"traceutil/trace.go:171","msg":"trace[2100073082] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"208.864748ms","start":"2026-08-10T23:21:21.70559Z","end":"2026-08-10T23:21:21.914455Z","steps":["trace[2100073082] 'process raft request' (duration: 208.734604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:22.227225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.161371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:21:22.227334Z","caller":"traceutil/trace.go:171","msg":"trace[194401013] linearizableReadLoop","detail":"{readStateIndex:26455; appliedIndex:26454; }","duration":"145.257293ms","start":"2026-08-10T23:21:22.082058Z","end":"2026-08-10T23:21:22.227315Z","steps":["trace[194401013] 'read index received' (duration: 45.441µs)","trace[194401013] 'applied index is now lower than readState.Index' (duration: 145.210502ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:22.227488Z","caller":"traceutil/trace.go:171","msg":"trace[1751096945] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"458.315464ms","start":"2026-08-10T23:21:21.769134Z","end":"2026-08-10T23:21:22.227449Z","steps":["trace[1751096945] 'process raft request' (duration: 181.815034ms)","trace[1751096945] 'compare' (duration: 275.780489ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:21:22.227657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.606534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-10T23:21:22.227666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:21:21.769111Z","time spent":"458.468669ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:21:22.227884Z","caller":"traceutil/trace.go:171","msg":"trace[2129744916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25619; }","duration":"145.882551ms","start":"2026-08-10T23:21:22.08199Z","end":"2026-08-10T23:21:22.227872Z","steps":["trace[2129744916] 'agreement among raft nodes before linearized reading' (duration: 145.564542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:22.485505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.709101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:21:22.485645Z","caller":"traceutil/trace.go:171","msg":"trace[489865753] linearizableReadLoop","detail":"{readStateIndex:26458; appliedIndex:26456; }","duration":"186.516319ms","start":"2026-08-10T23:21:22.299117Z","end":"2026-08-10T23:21:22.485633Z","steps":["trace[489865753] 'read index received' (duration: 65.756677ms)","trace[489865753] 'applied index is now lower than readState.Index' (duration: 120.759122ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:22.485702Z","caller":"traceutil/trace.go:171","msg":"trace[1667594427] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"244.394918ms","start":"2026-08-10T23:21:22.2413Z","end":"2026-08-10T23:21:22.485695Z","steps":["trace[1667594427] 'process raft request' (duration: 244.276295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:22.485852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.744986ms","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-10T23:21:22.486167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.376021ms","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-10T23:21:22.486247Z","caller":"traceutil/trace.go:171","msg":"trace[700488156] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"254.289865ms","start":"2026-08-10T23:21:22.231562Z","end":"2026-08-10T23:21:22.485852Z","steps":["trace[700488156] 'process raft request' (duration: 133.161431ms)","trace[700488156] 'compare' (duration: 120.463995ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:22.486285Z","caller":"traceutil/trace.go:171","msg":"trace[1596509655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25622; }","duration":"181.523444ms","start":"2026-08-10T23:21:22.304735Z","end":"2026-08-10T23:21:22.486258Z","steps":["trace[1596509655] 'agreement among raft nodes before linearized reading' (duration: 181.167014ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:22.486356Z","caller":"traceutil/trace.go:171","msg":"trace[1147570572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25622; }","duration":"186.800447ms","start":"2026-08-10T23:21:22.299066Z","end":"2026-08-10T23:21:22.485866Z","steps":["trace[1147570572] 'agreement among raft nodes before linearized reading' (duration: 186.706714ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:22.643301Z","caller":"traceutil/trace.go:171","msg":"trace[1208471964] linearizableReadLoop","detail":"{readStateIndex:26460; appliedIndex:26458; }","duration":"142.510414ms","start":"2026-08-10T23:21:22.500762Z","end":"2026-08-10T23:21:22.643272Z","steps":["trace[1208471964] 'read index received' (duration: 55.897511ms)","trace[1208471964] 'applied index is now lower than readState.Index' (duration: 86.611463ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:22.643662Z","caller":"traceutil/trace.go:171","msg":"trace[557777878] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"153.906874ms","start":"2026-08-10T23:21:22.489746Z","end":"2026-08-10T23:21:22.643653Z","steps":["trace[557777878] 'process raft request' (duration: 153.328137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:22.643859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.116131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:21:22.643891Z","caller":"traceutil/trace.go:171","msg":"trace[1523588172] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25624; }","duration":"143.164343ms","start":"2026-08-10T23:21:22.500718Z","end":"2026-08-10T23:21:22.643882Z","steps":["trace[1523588172] 'agreement among raft nodes before linearized reading' (duration: 143.098981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:23.370791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.44643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:21:23.3709Z","caller":"traceutil/trace.go:171","msg":"trace[1895802613] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"135.301134ms","start":"2026-08-10T23:21:23.235578Z","end":"2026-08-10T23:21:23.370879Z","steps":["trace[1895802613] 'process raft request' (duration: 31.682409ms)","trace[1895802613] 'compare' (duration: 103.314506ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:23.672683Z","caller":"traceutil/trace.go:171","msg":"trace[950821603] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"112.158904ms","start":"2026-08-10T23:21:23.560493Z","end":"2026-08-10T23:21:23.672652Z","steps":["trace[950821603] 'process raft request' (duration: 111.959858ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:23.779352Z","caller":"traceutil/trace.go:171","msg":"trace[2017736451] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"214.195903ms","start":"2026-08-10T23:21:23.565132Z","end":"2026-08-10T23:21:23.779328Z","steps":["trace[2017736451] 'process raft request' (duration: 144.050228ms)","trace[2017736451] 'compare' (duration: 69.96245ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:23.77945Z","caller":"traceutil/trace.go:171","msg":"trace[573140945] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"208.199139ms","start":"2026-08-10T23:21:23.571225Z","end":"2026-08-10T23:21:23.779424Z","steps":["trace[573140945] 'process raft request' (duration: 208.038394ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:24.626867Z","caller":"traceutil/trace.go:171","msg":"trace[1335148462] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"108.617241ms","start":"2026-08-10T23:21:24.518228Z","end":"2026-08-10T23:21:24.626845Z","steps":["trace[1335148462] 'process raft request' (duration: 31.74137ms)","trace[1335148462] 'compare' (duration: 76.782138ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:24.628815Z","caller":"traceutil/trace.go:171","msg":"trace[280622685] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"105.839629ms","start":"2026-08-10T23:21:24.522947Z","end":"2026-08-10T23:21:24.628787Z","steps":["trace[280622685] 'process raft request' (duration: 105.651964ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:24.628817Z","caller":"traceutil/trace.go:171","msg":"trace[592939468] transaction","detail":"{read_only:false; response_revision:25637; number_of_response:1; }","duration":"105.787017ms","start":"2026-08-10T23:21:24.523005Z","end":"2026-08-10T23:21:24.628792Z","steps":["trace[592939468] 'process raft request' (duration: 105.711075ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:25.392423Z","caller":"traceutil/trace.go:171","msg":"trace[669022759] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"140.800244ms","start":"2026-08-10T23:21:25.251599Z","end":"2026-08-10T23:21:25.392399Z","steps":["trace[669022759] 'process raft request' (duration: 63.590404ms)","trace[669022759] 'compare' (duration: 77.105517ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:21:25.670611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.294175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:21:25.670776Z","caller":"traceutil/trace.go:171","msg":"trace[616518710] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"238.492028ms","start":"2026-08-10T23:21:25.432249Z","end":"2026-08-10T23:21:25.670741Z","steps":["trace[616518710] 'process raft request' (duration: 103.991756ms)","trace[616518710] 'compare' (duration: 134.09019ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:21:25.894658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.800113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:21:25.894962Z","caller":"traceutil/trace.go:171","msg":"trace[1903155638] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"217.066926ms","start":"2026-08-10T23:21:25.677867Z","end":"2026-08-10T23:21:25.894934Z","steps":["trace[1903155638] 'process raft request' (duration: 113.897863ms)","trace[1903155638] 'compare' (duration: 102.549475ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:25.895266Z","caller":"traceutil/trace.go:171","msg":"trace[352143924] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"111.339389ms","start":"2026-08-10T23:21:25.783898Z","end":"2026-08-10T23:21:25.895238Z","steps":["trace[352143924] 'process raft request' (duration: 110.948348ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:25.895402Z","caller":"traceutil/trace.go:171","msg":"trace[1978606944] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"110.439163ms","start":"2026-08-10T23:21:25.784943Z","end":"2026-08-10T23:21:25.895382Z","steps":["trace[1978606944] 'process raft request' (duration: 110.274519ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:26.117978Z","caller":"traceutil/trace.go:171","msg":"trace[924370701] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"144.312747ms","start":"2026-08-10T23:21:25.97364Z","end":"2026-08-10T23:21:26.117953Z","steps":["trace[924370701] 'process raft request' (duration: 73.039249ms)","trace[924370701] 'compare' (duration: 70.903007ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:26.386551Z","caller":"traceutil/trace.go:171","msg":"trace[1140952288] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"130.816724ms","start":"2026-08-10T23:21:26.255695Z","end":"2026-08-10T23:21:26.386512Z","steps":["trace[1140952288] 'process raft request' (duration: 57.787646ms)","trace[1140952288] 'compare' (duration: 72.862034ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:26.838002Z","caller":"traceutil/trace.go:171","msg":"trace[465074068] linearizableReadLoop","detail":"{readStateIndex:26488; appliedIndex:26487; }","duration":"130.935878ms","start":"2026-08-10T23:21:26.707038Z","end":"2026-08-10T23:21:26.837974Z","steps":["trace[465074068] 'read index received' (duration: 95.884861ms)","trace[465074068] 'applied index is now lower than readState.Index' (duration: 35.049627ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:21:26.838274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.224167ms","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-10T23:21:26.838318Z","caller":"traceutil/trace.go:171","msg":"trace[946142039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25651; }","duration":"131.317709ms","start":"2026-08-10T23:21:26.706986Z","end":"2026-08-10T23:21:26.838304Z","steps":["trace[946142039] 'agreement among raft nodes before linearized reading' (duration: 131.138464ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:26.838112Z","caller":"traceutil/trace.go:171","msg":"trace[898287606] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"200.905408ms","start":"2026-08-10T23:21:26.637166Z","end":"2026-08-10T23:21:26.838071Z","steps":["trace[898287606] 'process raft request' (duration: 165.806389ms)","trace[898287606] 'compare' (duration: 34.642466ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:31.535144Z","caller":"traceutil/trace.go:171","msg":"trace[1466223077] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"128.281181ms","start":"2026-08-10T23:21:31.406835Z","end":"2026-08-10T23:21:31.535117Z","steps":["trace[1466223077] 'process raft request' (duration: 128.127427ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:31.549506Z","caller":"traceutil/trace.go:171","msg":"trace[1300519472] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"136.585772ms","start":"2026-08-10T23:21:31.412889Z","end":"2026-08-10T23:21:31.549474Z","steps":["trace[1300519472] 'process raft request' (duration: 136.15819ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:31.551366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22223} {"level":"info","ts":"2026-08-10T23:21:31.657412Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22223,"took":"101.175274ms","hash":3756545248,"current-db-size-bytes":57561088,"current-db-size":"58 MB","current-db-size-in-use-bytes":45326336,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-10T23:21:31.657529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3756545248,"revision":22223,"compact-revision":19928} {"level":"warn","ts":"2026-08-10T23:21:37.276394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.775473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:21:37.276709Z","caller":"traceutil/trace.go:171","msg":"trace[1163854945] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"265.377437ms","start":"2026-08-10T23:21:37.011282Z","end":"2026-08-10T23:21:37.276659Z","steps":["trace[1163854945] 'process raft request' (duration: 74.161561ms)","trace[1163854945] 'compare' (duration: 190.433304ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:37.276766Z","caller":"traceutil/trace.go:171","msg":"trace[675792370] linearizableReadLoop","detail":"{readStateIndex:26577; appliedIndex:26575; }","duration":"189.129446ms","start":"2026-08-10T23:21:37.08761Z","end":"2026-08-10T23:21:37.276739Z","steps":["trace[675792370] 'read index received' (duration: 183.05843ms)","trace[675792370] 'applied index is now lower than readState.Index' (duration: 6.069716ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:37.277016Z","caller":"traceutil/trace.go:171","msg":"trace[1453935279] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"264.406359ms","start":"2026-08-10T23:21:37.012591Z","end":"2026-08-10T23:21:37.276997Z","steps":["trace[1453935279] 'process raft request' (duration: 264.001557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:37.277229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.595149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:21:37.277281Z","caller":"traceutil/trace.go:171","msg":"trace[1679776394] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25737; }","duration":"189.722983ms","start":"2026-08-10T23:21:37.087542Z","end":"2026-08-10T23:21:37.277265Z","steps":["trace[1679776394] 'agreement among raft nodes before linearized reading' (duration: 189.584309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:37.286348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.217364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:21:37.286412Z","caller":"traceutil/trace.go:171","msg":"trace[1030417767] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25739; }","duration":"193.321237ms","start":"2026-08-10T23:21:37.093073Z","end":"2026-08-10T23:21:37.286395Z","steps":["trace[1030417767] 'agreement among raft nodes before linearized reading' (duration: 193.183893ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:37.286777Z","caller":"traceutil/trace.go:171","msg":"trace[1311174257] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"113.579015ms","start":"2026-08-10T23:21:37.173184Z","end":"2026-08-10T23:21:37.286763Z","steps":["trace[1311174257] 'process raft request' (duration: 112.927526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:37.287222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.793579ms","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-10T23:21:37.287269Z","caller":"traceutil/trace.go:171","msg":"trace[1143431961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25739; }","duration":"104.873473ms","start":"2026-08-10T23:21:37.182385Z","end":"2026-08-10T23:21:37.287258Z","steps":["trace[1143431961] 'agreement among raft nodes before linearized reading' (duration: 104.713368ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:37.487367Z","caller":"traceutil/trace.go:171","msg":"trace[813938816] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"191.951737ms","start":"2026-08-10T23:21:37.295388Z","end":"2026-08-10T23:21:37.487339Z","steps":["trace[813938816] 'process raft request' (duration: 137.177069ms)","trace[813938816] 'compare' (duration: 54.288104ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:21:43.315569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.992027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-08-10T23:21:43.315648Z","caller":"traceutil/trace.go:171","msg":"trace[1524390543] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25782; }","duration":"175.1126ms","start":"2026-08-10T23:21:43.140516Z","end":"2026-08-10T23:21:43.315629Z","steps":["trace[1524390543] 'range keys from in-memory index tree' (duration: 174.828371ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:43.440346Z","caller":"traceutil/trace.go:171","msg":"trace[1600483838] linearizableReadLoop","detail":"{readStateIndex:26624; appliedIndex:26623; }","duration":"119.586008ms","start":"2026-08-10T23:21:43.320733Z","end":"2026-08-10T23:21:43.440319Z","steps":["trace[1600483838] 'read index received' (duration: 119.471795ms)","trace[1600483838] 'applied index is now lower than readState.Index' (duration: 113.013µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:43.440428Z","caller":"traceutil/trace.go:171","msg":"trace[912846845] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"121.688509ms","start":"2026-08-10T23:21:43.3187Z","end":"2026-08-10T23:21:43.440389Z","steps":["trace[912846845] 'process raft request' (duration: 121.436312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:43.440578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.820595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fdbc2ccc-e288-4ec9-b9fa-b26d412bd19c\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-08-10T23:21:43.440621Z","caller":"traceutil/trace.go:171","msg":"trace[1589123446] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fdbc2ccc-e288-4ec9-b9fa-b26d412bd19c; range_end:; response_count:1; response_revision:25783; }","duration":"119.894697ms","start":"2026-08-10T23:21:43.320713Z","end":"2026-08-10T23:21:43.440608Z","steps":["trace[1589123446] 'agreement among raft nodes before linearized reading' (duration: 119.734573ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:43.488144Z","caller":"traceutil/trace.go:171","msg":"trace[666355656] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"166.729615ms","start":"2026-08-10T23:21:43.32139Z","end":"2026-08-10T23:21:43.48812Z","steps":["trace[666355656] 'process raft request' (duration: 166.518889ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:43.488422Z","caller":"traceutil/trace.go:171","msg":"trace[1470361181] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"149.322762ms","start":"2026-08-10T23:21:43.339053Z","end":"2026-08-10T23:21:43.488376Z","steps":["trace[1470361181] 'process raft request' (duration: 148.991381ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:43.655132Z","caller":"traceutil/trace.go:171","msg":"trace[1228720882] linearizableReadLoop","detail":"{readStateIndex:26627; appliedIndex:26626; }","duration":"114.594094ms","start":"2026-08-10T23:21:43.540516Z","end":"2026-08-10T23:21:43.65511Z","steps":["trace[1228720882] 'read index received' (duration: 46.547111ms)","trace[1228720882] 'applied index is now lower than readState.Index' (duration: 68.046183ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:43.655197Z","caller":"traceutil/trace.go:171","msg":"trace[25055055] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"141.68328ms","start":"2026-08-10T23:21:43.513473Z","end":"2026-08-10T23:21:43.655157Z","steps":["trace[25055055] 'process raft request' (duration: 73.587115ms)","trace[25055055] 'compare' (duration: 67.94998ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:21:43.655355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.828621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-08-10T23:21:43.655392Z","caller":"traceutil/trace.go:171","msg":"trace[1450685128] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25786; }","duration":"114.927413ms","start":"2026-08-10T23:21:43.540453Z","end":"2026-08-10T23:21:43.655381Z","steps":["trace[1450685128] 'agreement among raft nodes before linearized reading' (duration: 114.776979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:43.727154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.066719ms","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-10T23:21:43.727246Z","caller":"traceutil/trace.go:171","msg":"trace[2013859106] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25787; }","duration":"154.212952ms","start":"2026-08-10T23:21:43.573012Z","end":"2026-08-10T23:21:43.727225Z","steps":["trace[2013859106] 'agreement among raft nodes before linearized reading' (duration: 154.031667ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:43.851533Z","caller":"traceutil/trace.go:171","msg":"trace[21591314] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"115.087668ms","start":"2026-08-10T23:21:43.736417Z","end":"2026-08-10T23:21:43.851505Z","steps":["trace[21591314] 'process raft request' (duration: 114.884622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:21:44.161338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.202987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:21:44.161568Z","caller":"traceutil/trace.go:171","msg":"trace[269793001] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"179.222388ms","start":"2026-08-10T23:21:43.982327Z","end":"2026-08-10T23:21:44.16155Z","steps":["trace[269793001] 'process raft request' (duration: 179.175257ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:21:44.161893Z","caller":"traceutil/trace.go:171","msg":"trace[2055419173] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"197.87677ms","start":"2026-08-10T23:21:43.963987Z","end":"2026-08-10T23:21:44.161864Z","steps":["trace[2055419173] 'process raft request' (duration: 47.068286ms)","trace[2055419173] 'compare' (duration: 149.817016ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:21:44.161941Z","caller":"traceutil/trace.go:171","msg":"trace[1617869192] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"179.701062ms","start":"2026-08-10T23:21:43.98223Z","end":"2026-08-10T23:21:44.161931Z","steps":["trace[1617869192] 'process raft request' (duration: 179.202128ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:26:31.558842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25685} {"level":"info","ts":"2026-08-10T23:26:31.731514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25685,"took":"168.125515ms","hash":3891305320,"current-db-size-bytes":57982976,"current-db-size":"58 MB","current-db-size-in-use-bytes":36433920,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-10T23:26:31.73157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3891305320,"revision":25685,"compact-revision":22223} {"level":"info","ts":"2026-08-10T23:27:39.697822Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7317567213c7319d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:27:39.702235Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-10T23:27:39.702315Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-10T23:31:31.56932Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28576} {"level":"info","ts":"2026-08-10T23:31:31.706228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28576,"took":"132.678406ms","hash":1537192027,"current-db-size-bytes":57982976,"current-db-size":"58 MB","current-db-size-in-use-bytes":30871552,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:31:31.706309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1537192027,"revision":28576,"compact-revision":25685} {"level":"info","ts":"2026-08-10T23:36:31.576909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30798} {"level":"info","ts":"2026-08-10T23:36:31.69983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30798,"took":"115.175557ms","hash":37425867,"current-db-size-bytes":57982976,"current-db-size":"58 MB","current-db-size-in-use-bytes":31399936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:36:31.699904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":37425867,"revision":30798,"compact-revision":28576} {"level":"info","ts":"2026-08-10T23:41:31.587929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33062} {"level":"info","ts":"2026-08-10T23:41:31.745025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33062,"took":"151.501035ms","hash":1245282656,"current-db-size-bytes":59027456,"current-db-size":"59 MB","current-db-size-in-use-bytes":31064064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:41:31.745099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245282656,"revision":33062,"compact-revision":30798} {"level":"info","ts":"2026-08-10T23:46:31.595715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35362} {"level":"info","ts":"2026-08-10T23:46:31.767933Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35362,"took":"163.768917ms","hash":1134717667,"current-db-size-bytes":59027456,"current-db-size":"59 MB","current-db-size-in-use-bytes":32129024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T23:46:31.768019Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1134717667,"revision":35362,"compact-revision":33062} {"level":"info","ts":"2026-08-10T23:49:10.40966Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7317567213c7319d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:49:10.415623Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-10T23:49:10.415708Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-10T23:51:31.602491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37592} {"level":"info","ts":"2026-08-10T23:51:31.720529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37592,"took":"113.62798ms","hash":4094103680,"current-db-size-bytes":59027456,"current-db-size":"59 MB","current-db-size-in-use-bytes":32083968,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T23:51:31.720606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4094103680,"revision":37592,"compact-revision":35362}