{"level":"warn","ts":"2026-09-23T03:18:37.77616Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-23T03:18:37.776291Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.220:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.220:2380","--initial-cluster=instance=https://162.253.55.220:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.220:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.220:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-23T03:18:37.776396Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-23T03:18:37.776418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-09-23T03:18:37.776457Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-23T03:18:37.777347Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"]} {"level":"info","ts":"2026-09-23T03:18:37.777455Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.220:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-23T03:18:37.782178Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.419686ms"} {"level":"info","ts":"2026-09-23T03:18:37.78977Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c62f064ba268721","cluster-id":"65b58f2bbdfe5647"} {"level":"info","ts":"2026-09-23T03:18:37.789941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=()"} {"level":"info","ts":"2026-09-23T03:18:37.790101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 0"} {"level":"info","ts":"2026-09-23T03:18:37.790162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c62f064ba268721 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-23T03:18:37.790194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 1"} {"level":"info","ts":"2026-09-23T03:18:37.790333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"warn","ts":"2026-09-23T03:18:37.796712Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-23T03:18:37.800519Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-23T03:18:37.80275Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-23T03:18:37.806114Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c62f064ba268721","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-23T03:18:37.806356Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c62f064ba268721","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-23T03:18:37.806583Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-23T03:18:37.806695Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-23T03:18:37.806711Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-23T03:18:37.808245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"info","ts":"2026-09-23T03:18:37.808472Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","added-peer-id":"4c62f064ba268721","added-peer-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-09-23T03:18:37.810058Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-23T03:18:37.810257Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-09-23T03:18:37.810392Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-09-23T03:18:37.810459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-23T03:18:37.810371Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c62f064ba268721","initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-23T03:18:37.890894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 is starting a new election at term 1"} {"level":"info","ts":"2026-09-23T03:18:37.890959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-23T03:18:37.890993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgPreVoteResp from 4c62f064ba268721 at term 1"} {"level":"info","ts":"2026-09-23T03:18:37.891013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became candidate at term 2"} {"level":"info","ts":"2026-09-23T03:18:37.891023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgVoteResp from 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-09-23T03:18:37.891038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became leader at term 2"} {"level":"info","ts":"2026-09-23T03:18:37.891052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c62f064ba268721 elected leader 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-09-23T03:18:37.893603Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T03:18:37.894793Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c62f064ba268721","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.220:2379]}","request-path":"/0/members/4c62f064ba268721/attributes","cluster-id":"65b58f2bbdfe5647","publish-timeout":"7s"} {"level":"info","ts":"2026-09-23T03:18:37.894805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T03:18:37.894843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T03:18:37.895167Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-23T03:18:37.895199Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-23T03:18:37.89557Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T03:18:37.895787Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T03:18:37.895936Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T03:18:37.89748Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.220:2379"} {"level":"info","ts":"2026-09-23T03:18:37.898656Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-23T03:18:55.212174Z","caller":"traceutil/trace.go:171","msg":"trace[598696382] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"204.091894ms","start":"2026-09-23T03:18:55.008068Z","end":"2026-09-23T03:18:55.21216Z","steps":["trace[598696382] 'read index received' (duration: 204.012072ms)","trace[598696382] 'applied index is now lower than readState.Index' (duration: 79.272µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:18:55.212236Z","caller":"traceutil/trace.go:171","msg":"trace[1327043684] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"279.519923ms","start":"2026-09-23T03:18:54.932687Z","end":"2026-09-23T03:18:55.212207Z","steps":["trace[1327043684] 'process raft request' (duration: 279.386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:18:55.212283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.205046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-23T03:18:55.212325Z","caller":"traceutil/trace.go:171","msg":"trace[1530398118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"204.309609ms","start":"2026-09-23T03:18:55.00801Z","end":"2026-09-23T03:18:55.21232Z","steps":["trace[1530398118] 'agreement among raft nodes before linearized reading' (duration: 204.226137ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:19:20.946983Z","caller":"traceutil/trace.go:171","msg":"trace[1845093147] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"195.371214ms","start":"2026-09-23T03:19:20.75158Z","end":"2026-09-23T03:19:20.946951Z","steps":["trace[1845093147] 'process raft request' (duration: 194.752149ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:19:21.26886Z","caller":"traceutil/trace.go:171","msg":"trace[1096364378] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"245.195382ms","start":"2026-09-23T03:19:21.023588Z","end":"2026-09-23T03:19:21.268783Z","steps":["trace[1096364378] 'process raft request' (duration: 232.750453ms)","trace[1096364378] 'compare' (duration: 11.963397ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:19:37.824713Z","caller":"traceutil/trace.go:171","msg":"trace[2085121709] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"252.350064ms","start":"2026-09-23T03:19:37.572341Z","end":"2026-09-23T03:19:37.824691Z","steps":["trace[2085121709] 'process raft request' (duration: 252.20505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:20:09.245782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.688552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T03:20:09.2459Z","caller":"traceutil/trace.go:171","msg":"trace[2101359268] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1160; }","duration":"112.876156ms","start":"2026-09-23T03:20:09.133005Z","end":"2026-09-23T03:20:09.245881Z","steps":["trace[2101359268] 'count revisions from in-memory index tree' (duration: 112.529998ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:20:59.551225Z","caller":"traceutil/trace.go:171","msg":"trace[2139229385] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"208.82943ms","start":"2026-09-23T03:20:59.342368Z","end":"2026-09-23T03:20:59.551197Z","steps":["trace[2139229385] 'process raft request' (duration: 208.653956ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:20:59.698916Z","caller":"traceutil/trace.go:171","msg":"trace[1832706808] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"139.622522ms","start":"2026-09-23T03:20:59.559259Z","end":"2026-09-23T03:20:59.698882Z","steps":["trace[1832706808] 'process raft request' (duration: 108.734397ms)","trace[1832706808] 'compare' (duration: 30.727882ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:21:12.643251Z","caller":"traceutil/trace.go:171","msg":"trace[1178812094] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"322.603819ms","start":"2026-09-23T03:21:12.320617Z","end":"2026-09-23T03:21:12.643221Z","steps":["trace[1178812094] 'process raft request' (duration: 322.370123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:21:12.644177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:21:12.320599Z","time spent":"322.754572ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5918,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T03:21:12.648314Z","caller":"traceutil/trace.go:171","msg":"trace[1670740110] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"196.891708ms","start":"2026-09-23T03:21:12.451398Z","end":"2026-09-23T03:21:12.648289Z","steps":["trace[1670740110] 'process raft request' (duration: 196.606541ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:44.400027Z","caller":"traceutil/trace.go:171","msg":"trace[1839034446] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"120.679174ms","start":"2026-09-23T03:21:44.279324Z","end":"2026-09-23T03:21:44.400003Z","steps":["trace[1839034446] 'process raft request' (duration: 120.544401ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:44.417239Z","caller":"traceutil/trace.go:171","msg":"trace[1697723733] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2226; }","duration":"129.520121ms","start":"2026-09-23T03:21:44.287679Z","end":"2026-09-23T03:21:44.4172Z","steps":["trace[1697723733] 'read index received' (duration: 112.319898ms)","trace[1697723733] 'applied index is now lower than readState.Index' (duration: 17.198763ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:21:44.417409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.707686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:21:44.417389Z","caller":"traceutil/trace.go:171","msg":"trace[272793239] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"137.910837ms","start":"2026-09-23T03:21:44.279425Z","end":"2026-09-23T03:21:44.417335Z","steps":["trace[272793239] 'process raft request' (duration: 137.62643ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:44.417449Z","caller":"traceutil/trace.go:171","msg":"trace[1415719008] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2145; }","duration":"129.805718ms","start":"2026-09-23T03:21:44.287628Z","end":"2026-09-23T03:21:44.417433Z","steps":["trace[1415719008] 'agreement among raft nodes before linearized reading' (duration: 129.721176ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:50.663488Z","caller":"traceutil/trace.go:171","msg":"trace[1565334751] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"132.770817ms","start":"2026-09-23T03:21:50.530668Z","end":"2026-09-23T03:21:50.663439Z","steps":["trace[1565334751] 'process raft request' (duration: 129.951491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:23:21.598127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.870748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T03:23:21.598272Z","caller":"traceutil/trace.go:171","msg":"trace[1973158320] linearizableReadLoop","detail":"{readStateIndex:2977; appliedIndex:2976; }","duration":"121.533614ms","start":"2026-09-23T03:23:21.476717Z","end":"2026-09-23T03:23:21.598251Z","steps":["trace[1973158320] 'read index received' (duration: 102.782µs)","trace[1973158320] 'applied index is now lower than readState.Index' (duration: 121.429362ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:23:21.598305Z","caller":"traceutil/trace.go:171","msg":"trace[1745242097] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"172.968063ms","start":"2026-09-23T03:23:21.425294Z","end":"2026-09-23T03:23:21.598262Z","steps":["trace[1745242097] 'process raft request' (duration: 46.759637ms)","trace[1745242097] 'compare' (duration: 125.765686ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:23:21.598466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.75198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-23T03:23:21.598522Z","caller":"traceutil/trace.go:171","msg":"trace[45219940] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:1; response_revision:2867; }","duration":"121.854322ms","start":"2026-09-23T03:23:21.476654Z","end":"2026-09-23T03:23:21.598508Z","steps":["trace[45219940] 'agreement among raft nodes before linearized reading' (duration: 121.658088ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:23:21.733898Z","caller":"traceutil/trace.go:171","msg":"trace[513906472] linearizableReadLoop","detail":"{readStateIndex:2979; appliedIndex:2977; }","duration":"112.58328ms","start":"2026-09-23T03:23:21.621287Z","end":"2026-09-23T03:23:21.73387Z","steps":["trace[513906472] 'read index received' (duration: 97.701084ms)","trace[513906472] 'applied index is now lower than readState.Index' (duration: 14.880976ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:23:21.734181Z","caller":"traceutil/trace.go:171","msg":"trace[672202197] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"123.611904ms","start":"2026-09-23T03:23:21.610535Z","end":"2026-09-23T03:23:21.734147Z","steps":["trace[672202197] 'process raft request' (duration: 123.123162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:23:21.734174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.859456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-23T03:23:21.734391Z","caller":"traceutil/trace.go:171","msg":"trace[427286558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2869; }","duration":"113.036091ms","start":"2026-09-23T03:23:21.621252Z","end":"2026-09-23T03:23:21.734288Z","steps":["trace[427286558] 'agreement among raft nodes before linearized reading' (duration: 112.755804ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:26:43.520238Z","caller":"traceutil/trace.go:171","msg":"trace[737563096] transaction","detail":"{read_only:false; response_revision:4126; number_of_response:1; }","duration":"123.820923ms","start":"2026-09-23T03:26:43.396379Z","end":"2026-09-23T03:26:43.5202Z","steps":["trace[737563096] 'process raft request' (duration: 123.667909ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:17.768403Z","caller":"traceutil/trace.go:171","msg":"trace[459216794] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"110.609519ms","start":"2026-09-23T03:28:17.657744Z","end":"2026-09-23T03:28:17.768353Z","steps":["trace[459216794] 'process raft request' (duration: 70.817103ms)","trace[459216794] 'compare' (duration: 39.455148ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:20.787056Z","caller":"traceutil/trace.go:171","msg":"trace[654073532] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"120.006622ms","start":"2026-09-23T03:28:20.667023Z","end":"2026-09-23T03:28:20.78703Z","steps":["trace[654073532] 'process raft request' (duration: 119.854959ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:20.981328Z","caller":"traceutil/trace.go:171","msg":"trace[1817679944] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"185.611612ms","start":"2026-09-23T03:28:20.795685Z","end":"2026-09-23T03:28:20.981296Z","steps":["trace[1817679944] 'process raft request' (duration: 126.487056ms)","trace[1817679944] 'compare' (duration: 58.963032ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:20.993203Z","caller":"traceutil/trace.go:171","msg":"trace[856795734] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"193.910069ms","start":"2026-09-23T03:28:20.799262Z","end":"2026-09-23T03:28:20.993172Z","steps":["trace[856795734] 'process raft request' (duration: 193.735975ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:20.993638Z","caller":"traceutil/trace.go:171","msg":"trace[1574380394] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"109.882351ms","start":"2026-09-23T03:28:20.883735Z","end":"2026-09-23T03:28:20.993617Z","steps":["trace[1574380394] 'process raft request' (duration: 109.655626ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:20.993758Z","caller":"traceutil/trace.go:171","msg":"trace[492539041] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"114.540752ms","start":"2026-09-23T03:28:20.879198Z","end":"2026-09-23T03:28:20.993739Z","steps":["trace[492539041] 'process raft request' (duration: 113.930378ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:25.101783Z","caller":"traceutil/trace.go:171","msg":"trace[901675000] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"100.333554ms","start":"2026-09-23T03:28:25.001395Z","end":"2026-09-23T03:28:25.101729Z","steps":["trace[901675000] 'process raft request' (duration: 30.668848ms)","trace[901675000] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:553; } (duration: 69.097392ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:37.83673Z","caller":"traceutil/trace.go:171","msg":"trace[1840055254] linearizableReadLoop","detail":"{readStateIndex:5858; appliedIndex:5857; }","duration":"162.239196ms","start":"2026-09-23T03:28:37.674442Z","end":"2026-09-23T03:28:37.836682Z","steps":["trace[1840055254] 'read index received' (duration: 161.98789ms)","trace[1840055254] 'applied index is now lower than readState.Index' (duration: 249.826µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:37.83679Z","caller":"traceutil/trace.go:171","msg":"trace[141425135] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"245.793152ms","start":"2026-09-23T03:28:37.59097Z","end":"2026-09-23T03:28:37.836763Z","steps":["trace[141425135] 'process raft request' (duration: 245.530546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:28:37.836962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.498632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-4zfmq\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-09-23T03:28:37.837028Z","caller":"traceutil/trace.go:171","msg":"trace[2144744762] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-4zfmq; range_end:; response_count:1; response_revision:5643; }","duration":"162.616275ms","start":"2026-09-23T03:28:37.674397Z","end":"2026-09-23T03:28:37.837013Z","steps":["trace[2144744762] 'agreement among raft nodes before linearized reading' (duration: 162.424391ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:38.345108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-09-23T03:28:38.445517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"98.754367ms","hash":4274490661,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":31465472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T03:28:38.445591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4274490661,"revision":2959,"compact-revision":-1} {"level":"info","ts":"2026-09-23T03:28:54.681491Z","caller":"traceutil/trace.go:171","msg":"trace[1235371082] linearizableReadLoop","detail":"{readStateIndex:6138; appliedIndex:6137; }","duration":"109.700536ms","start":"2026-09-23T03:28:54.571757Z","end":"2026-09-23T03:28:54.681457Z","steps":["trace[1235371082] 'read index received' (duration: 49.470174ms)","trace[1235371082] 'applied index is now lower than readState.Index' (duration: 60.228722ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:54.681513Z","caller":"traceutil/trace.go:171","msg":"trace[1679023542] transaction","detail":"{read_only:false; response_revision:5916; number_of_response:1; }","duration":"142.44542ms","start":"2026-09-23T03:28:54.539019Z","end":"2026-09-23T03:28:54.681464Z","steps":["trace[1679023542] 'process raft request' (duration: 82.207928ms)","trace[1679023542] 'compare' (duration: 60.066318ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:28:54.681892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.094336ms","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-09-23T03:28:54.682012Z","caller":"traceutil/trace.go:171","msg":"trace[65647000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5916; }","duration":"110.317132ms","start":"2026-09-23T03:28:54.571668Z","end":"2026-09-23T03:28:54.681985Z","steps":["trace[65647000] 'agreement among raft nodes before linearized reading' (duration: 109.945433ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:54.826012Z","caller":"traceutil/trace.go:171","msg":"trace[1071314215] linearizableReadLoop","detail":"{readStateIndex:6139; appliedIndex:6138; }","duration":"144.394057ms","start":"2026-09-23T03:28:54.681589Z","end":"2026-09-23T03:28:54.825983Z","steps":["trace[1071314215] 'read index received' (duration: 130.850623ms)","trace[1071314215] 'applied index is now lower than readState.Index' (duration: 13.542014ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:54.826049Z","caller":"traceutil/trace.go:171","msg":"trace[941722729] transaction","detail":"{read_only:false; response_revision:5917; number_of_response:1; }","duration":"147.071351ms","start":"2026-09-23T03:28:54.678919Z","end":"2026-09-23T03:28:54.825991Z","steps":["trace[941722729] 'process raft request' (duration: 133.500456ms)","trace[941722729] 'compare' (duration: 13.3594ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:54.826192Z","caller":"traceutil/trace.go:171","msg":"trace[821382066] transaction","detail":"{read_only:false; response_revision:5918; number_of_response:1; }","duration":"139.701814ms","start":"2026-09-23T03:28:54.68646Z","end":"2026-09-23T03:28:54.826162Z","steps":["trace[821382066] 'process raft request' (duration: 139.481829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:28:54.826311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.631992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:28:54.826367Z","caller":"traceutil/trace.go:171","msg":"trace[1034262489] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5918; }","duration":"149.755175ms","start":"2026-09-23T03:28:54.676596Z","end":"2026-09-23T03:28:54.826351Z","steps":["trace[1034262489] 'agreement among raft nodes before linearized reading' (duration: 149.460748ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:29:57.663902Z","caller":"traceutil/trace.go:171","msg":"trace[17102528] linearizableReadLoop","detail":"{readStateIndex:6671; appliedIndex:6670; }","duration":"142.238625ms","start":"2026-09-23T03:29:57.521628Z","end":"2026-09-23T03:29:57.663866Z","steps":["trace[17102528] 'read index received' (duration: 142.120853ms)","trace[17102528] 'applied index is now lower than readState.Index' (duration: 116.142µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:29:57.664074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.423189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:29:57.66411Z","caller":"traceutil/trace.go:171","msg":"trace[1745003179] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:6434; }","duration":"142.492941ms","start":"2026-09-23T03:29:57.521603Z","end":"2026-09-23T03:29:57.664096Z","steps":["trace[1745003179] 'agreement among raft nodes before linearized reading' (duration: 142.390838ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:29:57.664081Z","caller":"traceutil/trace.go:171","msg":"trace[740019092] transaction","detail":"{read_only:false; response_revision:6434; number_of_response:1; }","duration":"148.416974ms","start":"2026-09-23T03:29:57.515626Z","end":"2026-09-23T03:29:57.664043Z","steps":["trace[740019092] 'process raft request' (duration: 148.038105ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:29:57.672055Z","caller":"traceutil/trace.go:171","msg":"trace[149610096] transaction","detail":"{read_only:false; response_revision:6435; number_of_response:1; }","duration":"138.051295ms","start":"2026-09-23T03:29:57.53397Z","end":"2026-09-23T03:29:57.672021Z","steps":["trace[149610096] 'process raft request' (duration: 137.86086ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:33:38.352413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5654} {"level":"info","ts":"2026-09-23T03:33:38.448032Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5654,"took":"94.080089ms","hash":4191946100,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":26767360,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-23T03:33:38.448122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4191946100,"revision":5654,"compact-revision":2959} {"level":"info","ts":"2026-09-23T03:35:14.600445Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000235f.wal"} {"level":"info","ts":"2026-09-23T03:35:30.723077Z","caller":"traceutil/trace.go:171","msg":"trace[722928454] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"203.55951ms","start":"2026-09-23T03:35:30.519476Z","end":"2026-09-23T03:35:30.723035Z","steps":["trace[722928454] 'process raft request' (duration: 203.261373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:36:01.116026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.82545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-23T03:36:01.116143Z","caller":"traceutil/trace.go:171","msg":"trace[750377150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9047; }","duration":"230.004224ms","start":"2026-09-23T03:36:00.886114Z","end":"2026-09-23T03:36:01.116118Z","steps":["trace[750377150] 'range keys from in-memory index tree' (duration: 229.560374ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:36:05.338242Z","caller":"traceutil/trace.go:171","msg":"trace[362977696] transaction","detail":"{read_only:false; response_revision:9071; number_of_response:1; }","duration":"113.191788ms","start":"2026-09-23T03:36:05.225021Z","end":"2026-09-23T03:36:05.338213Z","steps":["trace[362977696] 'process raft request' (duration: 113.000454ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:36:05.563002Z","caller":"traceutil/trace.go:171","msg":"trace[1564345043] linearizableReadLoop","detail":"{readStateIndex:9400; appliedIndex:9398; }","duration":"275.851111ms","start":"2026-09-23T03:36:05.287121Z","end":"2026-09-23T03:36:05.562972Z","steps":["trace[1564345043] 'read index received' (duration: 50.958939ms)","trace[1564345043] 'applied index is now lower than readState.Index' (duration: 224.890992ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:36:05.563088Z","caller":"traceutil/trace.go:171","msg":"trace[1807092343] transaction","detail":"{read_only:false; response_revision:9072; number_of_response:1; }","duration":"311.372452ms","start":"2026-09-23T03:36:05.251615Z","end":"2026-09-23T03:36:05.562988Z","steps":["trace[1807092343] 'process raft request' (duration: 213.148151ms)","trace[1807092343] 'compare' (duration: 97.722779ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:36:05.563229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.075177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:36:05.563264Z","caller":"traceutil/trace.go:171","msg":"trace[1344624348] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:9073; }","duration":"276.175829ms","start":"2026-09-23T03:36:05.287078Z","end":"2026-09-23T03:36:05.563254Z","steps":["trace[1344624348] 'agreement among raft nodes before linearized reading' (duration: 276.079627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:36:05.563301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:36:05.251573Z","time spent":"311.610377ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T03:36:05.563142Z","caller":"traceutil/trace.go:171","msg":"trace[2117569908] transaction","detail":"{read_only:false; response_revision:9073; number_of_response:1; }","duration":"228.55943ms","start":"2026-09-23T03:36:05.334571Z","end":"2026-09-23T03:36:05.56313Z","steps":["trace[2117569908] 'process raft request' (duration: 228.283684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:36:05.563426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.554434ms","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-09-23T03:36:05.56351Z","caller":"traceutil/trace.go:171","msg":"trace[398992927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9073; }","duration":"120.685598ms","start":"2026-09-23T03:36:05.442802Z","end":"2026-09-23T03:36:05.563488Z","steps":["trace[398992927] 'agreement among raft nodes before linearized reading' (duration: 120.471723ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:36:15.353876Z","caller":"traceutil/trace.go:171","msg":"trace[578263831] transaction","detail":"{read_only:false; response_revision:9124; number_of_response:1; }","duration":"136.849564ms","start":"2026-09-23T03:36:15.216999Z","end":"2026-09-23T03:36:15.353849Z","steps":["trace[578263831] 'process raft request' (duration: 136.6779ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:36:15.830076Z","caller":"traceutil/trace.go:171","msg":"trace[850051447] transaction","detail":"{read_only:false; response_revision:9129; number_of_response:1; }","duration":"133.001893ms","start":"2026-09-23T03:36:15.697038Z","end":"2026-09-23T03:36:15.83004Z","steps":["trace[850051447] 'process raft request' (duration: 84.262147ms)","trace[850051447] 'compare' (duration: 48.119201ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:37:15.215532Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T03:37:15.222329Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-23T03:37:15.22249Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-23T03:38:38.360165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7982} {"level":"info","ts":"2026-09-23T03:38:38.436397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7982,"took":"72.197647ms","hash":4081380750,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":28360704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T03:38:38.436504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4081380750,"revision":7982,"compact-revision":5654} {"level":"info","ts":"2026-09-23T03:43:07.057344Z","caller":"traceutil/trace.go:171","msg":"trace[1802932681] transaction","detail":"{read_only:false; response_revision:12325; number_of_response:1; }","duration":"161.314669ms","start":"2026-09-23T03:43:06.896001Z","end":"2026-09-23T03:43:07.057316Z","steps":["trace[1802932681] 'process raft request' (duration: 161.017362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:43:07.057476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.971212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T03:43:07.057309Z","caller":"traceutil/trace.go:171","msg":"trace[967848225] linearizableReadLoop","detail":"{readStateIndex:12759; appliedIndex:12758; }","duration":"100.795278ms","start":"2026-09-23T03:43:06.956474Z","end":"2026-09-23T03:43:07.05727Z","steps":["trace[967848225] 'read index received' (duration: 100.379588ms)","trace[967848225] 'applied index is now lower than readState.Index' (duration: 414.17µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:43:07.057531Z","caller":"traceutil/trace.go:171","msg":"trace[276682898] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:12325; }","duration":"101.117766ms","start":"2026-09-23T03:43:06.956397Z","end":"2026-09-23T03:43:07.057514Z","steps":["trace[276682898] 'agreement among raft nodes before linearized reading' (duration: 100.991673ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:43:26.371648Z","caller":"traceutil/trace.go:171","msg":"trace[732516551] linearizableReadLoop","detail":"{readStateIndex:12872; appliedIndex:12871; }","duration":"111.997065ms","start":"2026-09-23T03:43:26.259605Z","end":"2026-09-23T03:43:26.371602Z","steps":["trace[732516551] 'read index received' (duration: 111.77865ms)","trace[732516551] 'applied index is now lower than readState.Index' (duration: 217.515µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:43:26.371723Z","caller":"traceutil/trace.go:171","msg":"trace[958891816] transaction","detail":"{read_only:false; response_revision:12435; number_of_response:1; }","duration":"125.481409ms","start":"2026-09-23T03:43:26.246193Z","end":"2026-09-23T03:43:26.371675Z","steps":["trace[958891816] 'process raft request' (duration: 125.230183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:43:26.371886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.261302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-23T03:43:26.371982Z","caller":"traceutil/trace.go:171","msg":"trace[721540212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12435; }","duration":"112.416906ms","start":"2026-09-23T03:43:26.25955Z","end":"2026-09-23T03:43:26.371967Z","steps":["trace[721540212] 'agreement among raft nodes before linearized reading' (duration: 112.190281ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:43:26.504771Z","caller":"traceutil/trace.go:171","msg":"trace[366011250] transaction","detail":"{read_only:false; response_revision:12436; number_of_response:1; }","duration":"128.279737ms","start":"2026-09-23T03:43:26.376469Z","end":"2026-09-23T03:43:26.504749Z","steps":["trace[366011250] 'process raft request' (duration: 114.118667ms)","trace[366011250] 'compare' (duration: 13.847373ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:43:38.367669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10358} {"level":"info","ts":"2026-09-23T03:43:38.456484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10358,"took":"85.368978ms","hash":340754981,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":25731072,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-23T03:43:38.456556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":340754981,"revision":10358,"compact-revision":7982} {"level":"info","ts":"2026-09-23T03:48:38.375659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12524} {"level":"info","ts":"2026-09-23T03:48:38.463029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12524,"took":"83.969826ms","hash":3653943778,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":27721728,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T03:48:38.463109Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3653943778,"revision":12524,"compact-revision":10358} {"level":"warn","ts":"2026-09-23T03:50:17.192428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.861329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:50:17.19253Z","caller":"traceutil/trace.go:171","msg":"trace[309082992] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:15141; }","duration":"104.063233ms","start":"2026-09-23T03:50:17.088447Z","end":"2026-09-23T03:50:17.19251Z","steps":["trace[309082992] 'count revisions from in-memory index tree' (duration: 103.753127ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:50:26.485363Z","caller":"traceutil/trace.go:171","msg":"trace[152041201] linearizableReadLoop","detail":"{readStateIndex:15741; appliedIndex:15740; }","duration":"261.478042ms","start":"2026-09-23T03:50:26.223854Z","end":"2026-09-23T03:50:26.485332Z","steps":["trace[152041201] 'read index received' (duration: 261.178155ms)","trace[152041201] 'applied index is now lower than readState.Index' (duration: 298.557µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:50:26.485568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.671126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:50:26.485627Z","caller":"traceutil/trace.go:171","msg":"trace[486615132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15203; }","duration":"261.81397ms","start":"2026-09-23T03:50:26.223797Z","end":"2026-09-23T03:50:26.485611Z","steps":["trace[486615132] 'agreement among raft nodes before linearized reading' (duration: 261.673066ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:50:26.48546Z","caller":"traceutil/trace.go:171","msg":"trace[815017692] transaction","detail":"{read_only:false; response_revision:15203; number_of_response:1; }","duration":"292.53098ms","start":"2026-09-23T03:50:26.192876Z","end":"2026-09-23T03:50:26.485407Z","steps":["trace[815017692] 'process raft request' (duration: 292.155751ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:50:26.486103Z","caller":"traceutil/trace.go:171","msg":"trace[46503752] transaction","detail":"{read_only:false; response_revision:15204; number_of_response:1; }","duration":"123.052561ms","start":"2026-09-23T03:50:26.363023Z","end":"2026-09-23T03:50:26.486076Z","steps":["trace[46503752] 'process raft request' (duration: 122.971639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:26.488168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.778768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-23T03:50:26.488256Z","caller":"traceutil/trace.go:171","msg":"trace[304283910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15204; }","duration":"199.927371ms","start":"2026-09-23T03:50:26.288316Z","end":"2026-09-23T03:50:26.488243Z","steps":["trace[304283910] 'agreement among raft nodes before linearized reading' (duration: 199.713956ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:50:51.517571Z","caller":"traceutil/trace.go:171","msg":"trace[951650411] linearizableReadLoop","detail":"{readStateIndex:16075; appliedIndex:16074; }","duration":"321.106567ms","start":"2026-09-23T03:50:51.19644Z","end":"2026-09-23T03:50:51.517547Z","steps":["trace[951650411] 'read index received' (duration: 321.061756ms)","trace[951650411] 'applied index is now lower than readState.Index' (duration: 43.621µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:50:51.517631Z","caller":"traceutil/trace.go:171","msg":"trace[457082731] transaction","detail":"{read_only:false; response_revision:15525; number_of_response:1; }","duration":"348.480966ms","start":"2026-09-23T03:50:51.169114Z","end":"2026-09-23T03:50:51.517595Z","steps":["trace[457082731] 'process raft request' (duration: 348.265821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:51.517858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:50:51.169089Z","time spent":"348.642969ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T03:50:51.518184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.730952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-xpft4\" ","response":"range_response_count:1 size:20644"} {"level":"info","ts":"2026-09-23T03:50:51.51824Z","caller":"traceutil/trace.go:171","msg":"trace[1615349660] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-xpft4; range_end:; response_count:1; response_revision:15525; }","duration":"321.862325ms","start":"2026-09-23T03:50:51.196364Z","end":"2026-09-23T03:50:51.518226Z","steps":["trace[1615349660] 'agreement among raft nodes before linearized reading' (duration: 321.327692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:51.518448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:50:51.196335Z","time spent":"321.933307ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20666,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-xpft4\" "} {"level":"info","ts":"2026-09-23T03:50:51.529514Z","caller":"traceutil/trace.go:171","msg":"trace[711781062] transaction","detail":"{read_only:false; response_revision:15527; number_of_response:1; }","duration":"175.876213ms","start":"2026-09-23T03:50:51.353622Z","end":"2026-09-23T03:50:51.529498Z","steps":["trace[711781062] 'process raft request' (duration: 175.78672ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:50:51.529815Z","caller":"traceutil/trace.go:171","msg":"trace[902154066] transaction","detail":"{read_only:false; response_revision:15526; number_of_response:1; }","duration":"176.284652ms","start":"2026-09-23T03:50:51.353482Z","end":"2026-09-23T03:50:51.529767Z","steps":["trace[902154066] 'process raft request' (duration: 175.78069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:51.53017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.318225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-23T03:50:51.530182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.597237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-23T03:50:51.530225Z","caller":"traceutil/trace.go:171","msg":"trace[2089915063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15529; }","duration":"312.448279ms","start":"2026-09-23T03:50:51.217763Z","end":"2026-09-23T03:50:51.530212Z","steps":["trace[2089915063] 'agreement among raft nodes before linearized reading' (duration: 312.298635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:51.530218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.563361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-23T03:50:51.53026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:50:51.217733Z","time spent":"312.519401ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-23T03:50:51.530313Z","caller":"traceutil/trace.go:171","msg":"trace[1653525187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15529; }","duration":"307.683305ms","start":"2026-09-23T03:50:51.222603Z","end":"2026-09-23T03:50:51.530286Z","steps":["trace[1653525187] 'agreement among raft nodes before linearized reading' (duration: 307.50079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:51.53036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:50:51.222593Z","time spent":"307.759556ms","remote":"127.0.0.1:42844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T03:50:51.530229Z","caller":"traceutil/trace.go:171","msg":"trace[1198426095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15529; }","duration":"313.794361ms","start":"2026-09-23T03:50:51.216424Z","end":"2026-09-23T03:50:51.530218Z","steps":["trace[1198426095] 'agreement among raft nodes before linearized reading' (duration: 313.643397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:51.530759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:50:51.216409Z","time spent":"314.336985ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-23T03:50:51.530816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.432054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-23T03:50:51.530915Z","caller":"traceutil/trace.go:171","msg":"trace[1772175351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15529; }","duration":"306.584018ms","start":"2026-09-23T03:50:51.224304Z","end":"2026-09-23T03:50:51.530888Z","steps":["trace[1772175351] 'agreement among raft nodes before linearized reading' (duration: 305.755908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:51.530958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:50:51.224296Z","time spent":"306.6519ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-23T03:52:18.737634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.332563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:52:18.737741Z","caller":"traceutil/trace.go:171","msg":"trace[1758697001] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16343; }","duration":"117.483057ms","start":"2026-09-23T03:52:18.620236Z","end":"2026-09-23T03:52:18.737719Z","steps":["trace[1758697001] 'range keys from in-memory index tree' (duration: 117.243501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:52:18.737874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.41582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"warn","ts":"2026-09-23T03:52:18.73791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.893974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-23T03:52:18.737953Z","caller":"traceutil/trace.go:171","msg":"trace[1646444526] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16343; }","duration":"118.575993ms","start":"2026-09-23T03:52:18.619359Z","end":"2026-09-23T03:52:18.737935Z","steps":["trace[1646444526] 'range keys from in-memory index tree' (duration: 118.214815ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:52:18.737953Z","caller":"traceutil/trace.go:171","msg":"trace[694646946] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16343; }","duration":"104.979076ms","start":"2026-09-23T03:52:18.632959Z","end":"2026-09-23T03:52:18.737938Z","steps":["trace[694646946] 'range keys from in-memory index tree' (duration: 104.767791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:52:18.737925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.446111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-23T03:52:18.738029Z","caller":"traceutil/trace.go:171","msg":"trace[7905627] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16343; }","duration":"101.603375ms","start":"2026-09-23T03:52:18.636398Z","end":"2026-09-23T03:52:18.738002Z","steps":["trace[7905627] 'range keys from in-memory index tree' (duration: 101.224936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:52:18.738052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.230595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:52:18.738088Z","caller":"traceutil/trace.go:171","msg":"trace[182791967] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16343; }","duration":"118.336568ms","start":"2026-09-23T03:52:18.619739Z","end":"2026-09-23T03:52:18.738076Z","steps":["trace[182791967] 'range keys from in-memory index tree' (duration: 118.036791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:52:18.738348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.477631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-23T03:52:18.738378Z","caller":"traceutil/trace.go:171","msg":"trace[1160897983] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16343; }","duration":"118.553343ms","start":"2026-09-23T03:52:18.619816Z","end":"2026-09-23T03:52:18.73837Z","steps":["trace[1160897983] 'range keys from in-memory index tree' (duration: 118.220495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:52:18.738729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.337564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737312"} {"level":"info","ts":"2026-09-23T03:52:18.738759Z","caller":"traceutil/trace.go:171","msg":"trace[1385462319] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16343; }","duration":"159.397356ms","start":"2026-09-23T03:52:18.579354Z","end":"2026-09-23T03:52:18.738751Z","steps":["trace[1385462319] 'range keys from in-memory index tree' (duration: 158.75661ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:52:19.180309Z","caller":"traceutil/trace.go:171","msg":"trace[2045990148] linearizableReadLoop","detail":"{readStateIndex:16918; appliedIndex:16917; }","duration":"149.651901ms","start":"2026-09-23T03:52:19.030611Z","end":"2026-09-23T03:52:19.180263Z","steps":["trace[2045990148] 'read index received' (duration: 123.844359ms)","trace[2045990148] 'applied index is now lower than readState.Index' (duration: 25.806272ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:52:19.180323Z","caller":"traceutil/trace.go:171","msg":"trace[806660058] transaction","detail":"{read_only:false; response_revision:16345; number_of_response:1; }","duration":"165.066922ms","start":"2026-09-23T03:52:19.015213Z","end":"2026-09-23T03:52:19.18028Z","steps":["trace[806660058] 'process raft request' (duration: 139.378924ms)","trace[806660058] 'compare' (duration: 25.484183ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:52:19.180434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.798625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:52:19.18053Z","caller":"traceutil/trace.go:171","msg":"trace[297887065] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16345; }","duration":"149.953119ms","start":"2026-09-23T03:52:19.030557Z","end":"2026-09-23T03:52:19.18051Z","steps":["trace[297887065] 'agreement among raft nodes before linearized reading' (duration: 149.804895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:52:19.18073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.950588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-23T03:52:19.180798Z","caller":"traceutil/trace.go:171","msg":"trace[1116096535] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16345; }","duration":"150.085291ms","start":"2026-09-23T03:52:19.030697Z","end":"2026-09-23T03:52:19.180782Z","steps":["trace[1116096535] 'agreement among raft nodes before linearized reading' (duration: 149.836115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:52:19.181109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.620415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-23T03:52:19.181153Z","caller":"traceutil/trace.go:171","msg":"trace[172562609] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16345; }","duration":"145.685086ms","start":"2026-09-23T03:52:19.035455Z","end":"2026-09-23T03:52:19.18114Z","steps":["trace[172562609] 'agreement among raft nodes before linearized reading' (duration: 145.565873ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:53:38.386734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14548} {"level":"info","ts":"2026-09-23T03:53:38.468501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14548,"took":"78.901209ms","hash":3982993409,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":30720000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T03:53:38.468654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3982993409,"revision":14548,"compact-revision":12524} {"level":"info","ts":"2026-09-23T03:58:38.395031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16851} {"level":"info","ts":"2026-09-23T03:58:38.50037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16851,"took":"101.50015ms","hash":2236306561,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":31612928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T03:58:38.500549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2236306561,"revision":16851,"compact-revision":14548} {"level":"info","ts":"2026-09-23T03:58:55.656773Z","caller":"traceutil/trace.go:171","msg":"trace[1455848660] transaction","detail":"{read_only:false; response_revision:19178; number_of_response:1; }","duration":"110.133837ms","start":"2026-09-23T03:58:55.546608Z","end":"2026-09-23T03:58:55.656742Z","steps":["trace[1455848660] 'process raft request' (duration: 109.920422ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:58:56.104572Z","caller":"traceutil/trace.go:171","msg":"trace[972935101] transaction","detail":"{read_only:false; response_revision:19182; number_of_response:1; }","duration":"407.957935ms","start":"2026-09-23T03:58:55.696584Z","end":"2026-09-23T03:58:56.104542Z","steps":["trace[972935101] 'process raft request' (duration: 407.792891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:58:56.104743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:58:55.696545Z","time spent":"408.119329ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T03:59:04.138753Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T03:59:04.145501Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-23T03:59:04.145606Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-23T04:03:38.402307Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19050} {"level":"info","ts":"2026-09-23T04:03:38.503303Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19050,"took":"97.539934ms","hash":4207116136,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":33710080,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-23T04:03:38.503403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4207116136,"revision":19050,"compact-revision":16851} {"level":"info","ts":"2026-09-23T04:05:31.361404Z","caller":"traceutil/trace.go:171","msg":"trace[1939432943] transaction","detail":"{read_only:false; response_revision:22263; number_of_response:1; }","duration":"129.711466ms","start":"2026-09-23T04:05:31.231644Z","end":"2026-09-23T04:05:31.361356Z","steps":["trace[1939432943] 'process raft request' (duration: 129.308517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:05:31.681695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.712449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:05:31.682206Z","caller":"traceutil/trace.go:171","msg":"trace[1879382977] transaction","detail":"{read_only:false; response_revision:22264; number_of_response:1; }","duration":"391.826893ms","start":"2026-09-23T04:05:31.29029Z","end":"2026-09-23T04:05:31.682117Z","steps":["trace[1879382977] 'process raft request' (duration: 203.595821ms)","trace[1879382977] 'compare' (duration: 186.994612ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:05:31.682379Z","caller":"traceutil/trace.go:171","msg":"trace[1042237880] transaction","detail":"{read_only:false; response_revision:22265; number_of_response:1; }","duration":"234.312819ms","start":"2026-09-23T04:05:31.448049Z","end":"2026-09-23T04:05:31.682362Z","steps":["trace[1042237880] 'process raft request' (duration: 233.899679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:05:31.682494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:05:31.29027Z","time spent":"392.075109ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:05:31.682762Z","caller":"traceutil/trace.go:171","msg":"trace[1565113827] transaction","detail":"{read_only:false; response_revision:22266; number_of_response:1; }","duration":"234.225927ms","start":"2026-09-23T04:05:31.448463Z","end":"2026-09-23T04:05:31.682689Z","steps":["trace[1565113827] 'process raft request' (duration: 233.741955ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:05:31.683412Z","caller":"traceutil/trace.go:171","msg":"trace[170025786] transaction","detail":"{read_only:false; response_revision:22267; number_of_response:1; }","duration":"195.317082ms","start":"2026-09-23T04:05:31.488052Z","end":"2026-09-23T04:05:31.683369Z","steps":["trace[170025786] 'process raft request' (duration: 194.507733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:05:31.684716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.741261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-09-23T04:05:31.684778Z","caller":"traceutil/trace.go:171","msg":"trace[1448931936] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22267; }","duration":"299.846804ms","start":"2026-09-23T04:05:31.384911Z","end":"2026-09-23T04:05:31.684758Z","steps":["trace[1448931936] 'agreement among raft nodes before linearized reading' (duration: 299.740441ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:05:31.682072Z","caller":"traceutil/trace.go:171","msg":"trace[1649823923] linearizableReadLoop","detail":"{readStateIndex:23035; appliedIndex:23034; }","duration":"297.059147ms","start":"2026-09-23T04:05:31.384989Z","end":"2026-09-23T04:05:31.682048Z","steps":["trace[1649823923] 'read index received' (duration: 108.912306ms)","trace[1649823923] 'applied index is now lower than readState.Index' (duration: 188.14566ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:05:31.895169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.735538ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-09-23T04:05:31.895393Z","caller":"traceutil/trace.go:171","msg":"trace[878739703] linearizableReadLoop","detail":"{readStateIndex:23039; appliedIndex:23038; }","duration":"205.743593ms","start":"2026-09-23T04:05:31.689598Z","end":"2026-09-23T04:05:31.895341Z","steps":["trace[878739703] 'read index received' (duration: 102.583444ms)","trace[878739703] 'applied index is now lower than readState.Index' (duration: 103.158339ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:05:31.896459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.826779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7df40bf6-cf3a-4274-b3a6-45f991bd2ebf\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-09-23T04:05:31.896579Z","caller":"traceutil/trace.go:171","msg":"trace[1436979895] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7df40bf6-cf3a-4274-b3a6-45f991bd2ebf; range_end:; response_count:1; response_revision:22267; }","duration":"207.012324ms","start":"2026-09-23T04:05:31.689536Z","end":"2026-09-23T04:05:31.896548Z","steps":["trace[1436979895] 'agreement among raft nodes before linearized reading' (duration: 206.531482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:05:31.89664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.520181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-23T04:05:31.896727Z","caller":"traceutil/trace.go:171","msg":"trace[1529023061] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22267; }","duration":"206.635315ms","start":"2026-09-23T04:05:31.690075Z","end":"2026-09-23T04:05:31.89671Z","steps":["trace[1529023061] 'agreement among raft nodes before linearized reading' (duration: 206.290476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:05:32.619661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.324323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T04:05:32.619759Z","caller":"traceutil/trace.go:171","msg":"trace[1080618618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22269; }","duration":"215.484557ms","start":"2026-09-23T04:05:32.404247Z","end":"2026-09-23T04:05:32.619731Z","steps":["trace[1080618618] 'range keys from in-memory index tree' (duration: 215.116338ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:05:32.620382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.0733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:05:32.620441Z","caller":"traceutil/trace.go:171","msg":"trace[425227131] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:22269; }","duration":"119.182543ms","start":"2026-09-23T04:05:32.50124Z","end":"2026-09-23T04:05:32.620422Z","steps":["trace[425227131] 'count revisions from in-memory index tree' (duration: 118.960948ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:05:32.804241Z","caller":"traceutil/trace.go:171","msg":"trace[1648979946] transaction","detail":"{read_only:false; response_revision:22270; number_of_response:1; }","duration":"167.307549ms","start":"2026-09-23T04:05:32.636903Z","end":"2026-09-23T04:05:32.804211Z","steps":["trace[1648979946] 'process raft request' (duration: 167.107895ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:08:38.410435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21484} {"level":"info","ts":"2026-09-23T04:08:38.545147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21484,"took":"129.750337ms","hash":3239012519,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":30892032,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T04:08:38.545269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3239012519,"revision":21484,"compact-revision":19050} {"level":"info","ts":"2026-09-23T04:09:49.613601Z","caller":"traceutil/trace.go:171","msg":"trace[296420555] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"115.514415ms","start":"2026-09-23T04:09:49.498051Z","end":"2026-09-23T04:09:49.613566Z","steps":["trace[296420555] 'process raft request' (duration: 115.28555ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:10:33.951575Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065f7.wal"} {"level":"info","ts":"2026-09-23T04:11:46.293025Z","caller":"traceutil/trace.go:171","msg":"trace[803636523] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"100.435722ms","start":"2026-09-23T04:11:46.192479Z","end":"2026-09-23T04:11:46.292915Z","steps":["trace[803636523] 'process raft request' (duration: 99.913439ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:38.421233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23680} {"level":"info","ts":"2026-09-23T04:13:38.557256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23680,"took":"129.330745ms","hash":1316054802,"current-db-size-bytes":58335232,"current-db-size":"58 MB","current-db-size-in-use-bytes":44900352,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-23T04:13:38.557313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1316054802,"revision":23680,"compact-revision":21484} {"level":"info","ts":"2026-09-23T04:13:44.979381Z","caller":"traceutil/trace.go:171","msg":"trace[496494244] transaction","detail":"{read_only:false; response_revision:27109; number_of_response:1; }","duration":"196.677531ms","start":"2026-09-23T04:13:44.782659Z","end":"2026-09-23T04:13:44.979337Z","steps":["trace[496494244] 'process raft request' (duration: 121.387384ms)","trace[496494244] 'compare' (duration: 75.080842ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:45.247898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.67708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:13:45.248087Z","caller":"traceutil/trace.go:171","msg":"trace[295822801] transaction","detail":"{read_only:false; response_revision:27110; number_of_response:1; }","duration":"324.542041ms","start":"2026-09-23T04:13:44.923521Z","end":"2026-09-23T04:13:45.248063Z","steps":["trace[295822801] 'process raft request' (duration: 195.576305ms)","trace[295822801] 'compare' (duration: 128.392672ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:45.248166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:44.923489Z","time spent":"324.642433ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:45.963408Z","caller":"traceutil/trace.go:171","msg":"trace[1815482685] transaction","detail":"{read_only:false; response_revision:27112; number_of_response:1; }","duration":"293.044316ms","start":"2026-09-23T04:13:45.670336Z","end":"2026-09-23T04:13:45.96338Z","steps":["trace[1815482685] 'process raft request' (duration: 292.77554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:46.218998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.80579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:13:46.219292Z","caller":"traceutil/trace.go:171","msg":"trace[2068319811] linearizableReadLoop","detail":"{readStateIndex:28022; appliedIndex:28020; }","duration":"279.337897ms","start":"2026-09-23T04:13:45.939933Z","end":"2026-09-23T04:13:46.219271Z","steps":["trace[2068319811] 'read index received' (duration: 24.1628ms)","trace[2068319811] 'applied index is now lower than readState.Index' (duration: 255.151506ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:46.219282Z","caller":"traceutil/trace.go:171","msg":"trace[495864173] transaction","detail":"{read_only:false; response_revision:27113; number_of_response:1; }","duration":"326.865547ms","start":"2026-09-23T04:13:45.892355Z","end":"2026-09-23T04:13:46.219221Z","steps":["trace[495864173] 'process raft request' (duration: 200.733949ms)","trace[495864173] 'compare' (duration: 125.251467ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:46.219484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:45.892324Z","time spent":"327.044552ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:46.219545Z","caller":"traceutil/trace.go:171","msg":"trace[143485951] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"306.024327ms","start":"2026-09-23T04:13:45.913508Z","end":"2026-09-23T04:13:46.219532Z","steps":["trace[143485951] 'process raft request' (duration: 305.601367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:46.219621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:45.913471Z","time spent":"306.104219ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:46.219813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.878469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T04:13:46.219856Z","caller":"traceutil/trace.go:171","msg":"trace[930617028] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:27115; }","duration":"279.981122ms","start":"2026-09-23T04:13:45.939862Z","end":"2026-09-23T04:13:46.219843Z","steps":["trace[930617028] 'agreement among raft nodes before linearized reading' (duration: 279.89203ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:46.219985Z","caller":"traceutil/trace.go:171","msg":"trace[2032905681] transaction","detail":"{read_only:false; response_revision:27115; number_of_response:1; }","duration":"271.98188ms","start":"2026-09-23T04:13:45.947989Z","end":"2026-09-23T04:13:46.21997Z","steps":["trace[2032905681] 'process raft request' (duration: 271.202992ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:46.231937Z","caller":"traceutil/trace.go:171","msg":"trace[865961911] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"240.648268ms","start":"2026-09-23T04:13:45.991264Z","end":"2026-09-23T04:13:46.231912Z","steps":["trace[865961911] 'process raft request' (duration: 240.287279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:46.231969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.0679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T04:13:46.232084Z","caller":"traceutil/trace.go:171","msg":"trace[794427640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27116; }","duration":"284.223044ms","start":"2026-09-23T04:13:45.947852Z","end":"2026-09-23T04:13:46.232076Z","steps":["trace[794427640] 'agreement among raft nodes before linearized reading' (duration: 284.024609ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:46.385377Z","caller":"traceutil/trace.go:171","msg":"trace[1719837046] linearizableReadLoop","detail":"{readStateIndex:28025; appliedIndex:28024; }","duration":"132.588954ms","start":"2026-09-23T04:13:46.25276Z","end":"2026-09-23T04:13:46.385349Z","steps":["trace[1719837046] 'read index received' (duration: 127.696486ms)","trace[1719837046] 'applied index is now lower than readState.Index' (duration: 4.890688ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:46.385704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.219565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-23T04:13:46.385713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.930272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-23T04:13:46.385773Z","caller":"traceutil/trace.go:171","msg":"trace[1208877403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27117; }","duration":"127.328697ms","start":"2026-09-23T04:13:46.258422Z","end":"2026-09-23T04:13:46.385751Z","steps":["trace[1208877403] 'agreement among raft nodes before linearized reading' (duration: 127.125732ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:46.385777Z","caller":"traceutil/trace.go:171","msg":"trace[574753127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27117; }","duration":"133.065845ms","start":"2026-09-23T04:13:46.252696Z","end":"2026-09-23T04:13:46.385762Z","steps":["trace[574753127] 'agreement among raft nodes before linearized reading' (duration: 132.84657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:46.386144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.12293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"info","ts":"2026-09-23T04:13:46.386242Z","caller":"traceutil/trace.go:171","msg":"trace[1840018631] transaction","detail":"{read_only:false; response_revision:27117; number_of_response:1; }","duration":"149.063589ms","start":"2026-09-23T04:13:46.236399Z","end":"2026-09-23T04:13:46.385462Z","steps":["trace[1840018631] 'process raft request' (duration: 144.154721ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:46.386335Z","caller":"traceutil/trace.go:171","msg":"trace[838620562] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:27117; }","duration":"124.407937ms","start":"2026-09-23T04:13:46.261818Z","end":"2026-09-23T04:13:46.386226Z","steps":["trace[838620562] 'agreement among raft nodes before linearized reading' (duration: 124.007657ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:46.669129Z","caller":"traceutil/trace.go:171","msg":"trace[1186843228] transaction","detail":"{read_only:false; response_revision:27118; number_of_response:1; }","duration":"276.959669ms","start":"2026-09-23T04:13:46.392125Z","end":"2026-09-23T04:13:46.669085Z","steps":["trace[1186843228] 'process raft request' (duration: 239.974311ms)","trace[1186843228] 'compare' (duration: 36.811514ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:46.879189Z","caller":"traceutil/trace.go:171","msg":"trace[921826996] linearizableReadLoop","detail":"{readStateIndex:28029; appliedIndex:28025; }","duration":"485.031675ms","start":"2026-09-23T04:13:46.394121Z","end":"2026-09-23T04:13:46.879153Z","steps":["trace[921826996] 'read index received' (duration: 238.108026ms)","trace[921826996] 'applied index is now lower than readState.Index' (duration: 246.922059ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:46.879289Z","caller":"traceutil/trace.go:171","msg":"trace[644021960] transaction","detail":"{read_only:false; response_revision:27121; number_of_response:1; }","duration":"486.225384ms","start":"2026-09-23T04:13:46.393053Z","end":"2026-09-23T04:13:46.879278Z","steps":["trace[644021960] 'process raft request' (duration: 486.044559ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:46.879303Z","caller":"traceutil/trace.go:171","msg":"trace[610766670] transaction","detail":"{read_only:false; response_revision:27120; number_of_response:1; }","duration":"486.420049ms","start":"2026-09-23T04:13:46.392809Z","end":"2026-09-23T04:13:46.879229Z","steps":["trace[610766670] 'process raft request' (duration: 486.224254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:46.879403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:46.393043Z","time spent":"486.275255ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:46.879554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:46.392773Z","time spent":"486.626224ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:46.879578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.448345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"info","ts":"2026-09-23T04:13:46.879675Z","caller":"traceutil/trace.go:171","msg":"trace[846492163] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:27121; }","duration":"485.576788ms","start":"2026-09-23T04:13:46.394084Z","end":"2026-09-23T04:13:46.879661Z","steps":["trace[846492163] 'agreement among raft nodes before linearized reading' (duration: 485.217159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:46.879717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:46.394075Z","time spent":"485.630989ms","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11811,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-09-23T04:13:46.879183Z","caller":"traceutil/trace.go:171","msg":"trace[1973463842] transaction","detail":"{read_only:false; response_revision:27119; number_of_response:1; }","duration":"486.449379ms","start":"2026-09-23T04:13:46.392698Z","end":"2026-09-23T04:13:46.879147Z","steps":["trace[1973463842] 'process raft request' (duration: 392.423601ms)","trace[1973463842] 'compare' (duration: 93.529426ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:46.880086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:46.392667Z","time spent":"487.221397ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:46.897751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.762793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:46.897829Z","caller":"traceutil/trace.go:171","msg":"trace[1052645211] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:27122; }","duration":"490.876076ms","start":"2026-09-23T04:13:46.406927Z","end":"2026-09-23T04:13:46.897803Z","steps":["trace[1052645211] 'agreement among raft nodes before linearized reading' (duration: 490.714362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:46.897871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:46.406911Z","time spent":"490.950737ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-09-23T04:13:46.897811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.137129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:46.898275Z","caller":"traceutil/trace.go:171","msg":"trace[1448126742] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:27122; }","duration":"181.505768ms","start":"2026-09-23T04:13:46.716596Z","end":"2026-09-23T04:13:46.898102Z","steps":["trace[1448126742] 'agreement among raft nodes before linearized reading' (duration: 181.115898ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:52.375573Z","caller":"traceutil/trace.go:171","msg":"trace[1632820397] transaction","detail":"{read_only:false; response_revision:27158; number_of_response:1; }","duration":"134.392217ms","start":"2026-09-23T04:13:52.241136Z","end":"2026-09-23T04:13:52.375528Z","steps":["trace[1632820397] 'process raft request' (duration: 133.500825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:52.647445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.945142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:13:52.647683Z","caller":"traceutil/trace.go:171","msg":"trace[411936719] transaction","detail":"{read_only:false; response_revision:27159; number_of_response:1; }","duration":"404.217395ms","start":"2026-09-23T04:13:52.243432Z","end":"2026-09-23T04:13:52.64765Z","steps":["trace[411936719] 'process raft request' (duration: 225.950335ms)","trace[411936719] 'compare' (duration: 177.670626ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:52.647795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:52.243408Z","time spent":"404.336327ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:52.647706Z","caller":"traceutil/trace.go:171","msg":"trace[720012621] linearizableReadLoop","detail":"{readStateIndex:28071; appliedIndex:28068; }","duration":"233.563078ms","start":"2026-09-23T04:13:52.414127Z","end":"2026-09-23T04:13:52.64769Z","steps":["trace[720012621] 'read index received' (duration: 55.175735ms)","trace[720012621] 'applied index is now lower than readState.Index' (duration: 178.386473ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:52.647957Z","caller":"traceutil/trace.go:171","msg":"trace[1749233082] transaction","detail":"{read_only:false; response_revision:27160; number_of_response:1; }","duration":"397.857142ms","start":"2026-09-23T04:13:52.250068Z","end":"2026-09-23T04:13:52.647925Z","steps":["trace[1749233082] 'process raft request' (duration: 397.492043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:52.647897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.780413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T04:13:52.647956Z","caller":"traceutil/trace.go:171","msg":"trace[1920848971] transaction","detail":"{read_only:false; response_revision:27161; number_of_response:1; }","duration":"391.145052ms","start":"2026-09-23T04:13:52.25677Z","end":"2026-09-23T04:13:52.647915Z","steps":["trace[1920848971] 'process raft request' (duration: 390.875055ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:52.648067Z","caller":"traceutil/trace.go:171","msg":"trace[293651461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27161; }","duration":"234.010649ms","start":"2026-09-23T04:13:52.414043Z","end":"2026-09-23T04:13:52.648053Z","steps":["trace[293651461] 'agreement among raft nodes before linearized reading' (duration: 233.689671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:52.648195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:52.256732Z","time spent":"391.350606ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:52.648225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.252278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:13:52.648192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:52.250053Z","time spent":"398.007116ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:52.648281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.482831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:52.648351Z","caller":"traceutil/trace.go:171","msg":"trace[1460320399] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:27161; }","duration":"230.415372ms","start":"2026-09-23T04:13:52.417907Z","end":"2026-09-23T04:13:52.648322Z","steps":["trace[1460320399] 'agreement among raft nodes before linearized reading' (duration: 230.166436ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:52.648369Z","caller":"traceutil/trace.go:171","msg":"trace[1122193203] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:27161; }","duration":"157.663325ms","start":"2026-09-23T04:13:52.490686Z","end":"2026-09-23T04:13:52.648349Z","steps":["trace[1122193203] 'agreement among raft nodes before linearized reading' (duration: 157.489561ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:52.884885Z","caller":"traceutil/trace.go:171","msg":"trace[1972672843] transaction","detail":"{read_only:false; response_revision:27163; number_of_response:1; }","duration":"184.47405ms","start":"2026-09-23T04:13:52.700356Z","end":"2026-09-23T04:13:52.88483Z","steps":["trace[1972672843] 'process raft request' (duration: 146.865707ms)","trace[1972672843] 'compare' (duration: 37.376747ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:53.022909Z","caller":"traceutil/trace.go:171","msg":"trace[1807222534] transaction","detail":"{read_only:false; response_revision:27164; number_of_response:1; }","duration":"109.167101ms","start":"2026-09-23T04:13:52.913711Z","end":"2026-09-23T04:13:53.022878Z","steps":["trace[1807222534] 'process raft request' (duration: 106.335713ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:53.329975Z","caller":"traceutil/trace.go:171","msg":"trace[1910480069] transaction","detail":"{read_only:false; response_revision:27166; number_of_response:1; }","duration":"415.2954ms","start":"2026-09-23T04:13:52.914653Z","end":"2026-09-23T04:13:53.329948Z","steps":["trace[1910480069] 'process raft request' (duration: 415.159467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:53.330178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:52.914617Z","time spent":"415.476035ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:53.330071Z","caller":"traceutil/trace.go:171","msg":"trace[1299457073] transaction","detail":"{read_only:false; response_revision:27167; number_of_response:1; }","duration":"404.366908ms","start":"2026-09-23T04:13:52.925664Z","end":"2026-09-23T04:13:53.330031Z","steps":["trace[1299457073] 'process raft request' (duration: 404.242545ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:53.330685Z","caller":"traceutil/trace.go:171","msg":"trace[102864432] transaction","detail":"{read_only:false; response_revision:27165; number_of_response:1; }","duration":"416.209043ms","start":"2026-09-23T04:13:52.914434Z","end":"2026-09-23T04:13:53.330643Z","steps":["trace[102864432] 'process raft request' (duration: 405.109207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:53.330738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:52.92565Z","time spent":"404.968823ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:53.330929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:52.914417Z","time spent":"416.374657ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:53.340879Z","caller":"traceutil/trace.go:171","msg":"trace[1175288516] linearizableReadLoop","detail":"{readStateIndex:28078; appliedIndex:28074; }","duration":"117.683975ms","start":"2026-09-23T04:13:53.22317Z","end":"2026-09-23T04:13:53.340854Z","steps":["trace[1175288516] 'read index received' (duration: 96.395314ms)","trace[1175288516] 'applied index is now lower than readState.Index' (duration: 21.287711ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:53.341154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.967472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:53.341208Z","caller":"traceutil/trace.go:171","msg":"trace[1969522938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27170; }","duration":"118.092965ms","start":"2026-09-23T04:13:53.223102Z","end":"2026-09-23T04:13:53.341195Z","steps":["trace[1969522938] 'agreement among raft nodes before linearized reading' (duration: 117.846919ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:53.341681Z","caller":"traceutil/trace.go:171","msg":"trace[642088703] transaction","detail":"{read_only:false; response_revision:27168; number_of_response:1; }","duration":"313.425175ms","start":"2026-09-23T04:13:53.028242Z","end":"2026-09-23T04:13:53.341668Z","steps":["trace[642088703] 'process raft request' (duration: 312.40444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:53.341787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:53.028202Z","time spent":"313.510527ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:54.384405Z","caller":"traceutil/trace.go:171","msg":"trace[1747687789] transaction","detail":"{read_only:false; response_revision:27171; number_of_response:1; }","duration":"151.336254ms","start":"2026-09-23T04:13:54.233047Z","end":"2026-09-23T04:13:54.384383Z","steps":["trace[1747687789] 'process raft request' (duration: 150.696838ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:54.558472Z","caller":"traceutil/trace.go:171","msg":"trace[1045485817] transaction","detail":"{read_only:false; response_revision:27172; number_of_response:1; }","duration":"173.397373ms","start":"2026-09-23T04:13:54.385047Z","end":"2026-09-23T04:13:54.558445Z","steps":["trace[1045485817] 'process raft request' (duration: 146.440956ms)","trace[1045485817] 'compare' (duration: 26.709072ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:54.814638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.515681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:13:54.814785Z","caller":"traceutil/trace.go:171","msg":"trace[1583639603] transaction","detail":"{read_only:false; response_revision:27173; number_of_response:1; }","duration":"161.031896ms","start":"2026-09-23T04:13:54.653725Z","end":"2026-09-23T04:13:54.814757Z","steps":["trace[1583639603] 'process raft request' (duration: 33.282299ms)","trace[1583639603] 'compare' (duration: 127.342107ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:54.964861Z","caller":"traceutil/trace.go:171","msg":"trace[1906282110] transaction","detail":"{read_only:false; response_revision:27174; number_of_response:1; }","duration":"307.587205ms","start":"2026-09-23T04:13:54.657212Z","end":"2026-09-23T04:13:54.964799Z","steps":["trace[1906282110] 'process raft request' (duration: 278.556378ms)","trace[1906282110] 'compare' (duration: 28.76792ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:54.964991Z","caller":"traceutil/trace.go:171","msg":"trace[210590030] transaction","detail":"{read_only:false; response_revision:27175; number_of_response:1; }","duration":"307.302558ms","start":"2026-09-23T04:13:54.657641Z","end":"2026-09-23T04:13:54.964943Z","steps":["trace[210590030] 'process raft request' (duration: 307.071513ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:54.964849Z","caller":"traceutil/trace.go:171","msg":"trace[1679312080] linearizableReadLoop","detail":"{readStateIndex:28085; appliedIndex:28082; }","duration":"301.749385ms","start":"2026-09-23T04:13:54.663071Z","end":"2026-09-23T04:13:54.964821Z","steps":["trace[1679312080] 'read index received' (duration: 23.952106ms)","trace[1679312080] 'applied index is now lower than readState.Index' (duration: 277.796319ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:54.965157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:54.657624Z","time spent":"307.416601ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:54.965157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:54.65717Z","time spent":"307.858351ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:54.965502Z","caller":"traceutil/trace.go:171","msg":"trace[1889295960] transaction","detail":"{read_only:false; response_revision:27176; number_of_response:1; }","duration":"166.014196ms","start":"2026-09-23T04:13:54.79947Z","end":"2026-09-23T04:13:54.965484Z","steps":["trace[1889295960] 'process raft request' (duration: 165.316949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:54.965523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.65795ms","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":"warn","ts":"2026-09-23T04:13:54.965553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.901591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-g9kp5.18d7d737279dd146\" ","response":"range_response_count:1 size:1395"} {"level":"info","ts":"2026-09-23T04:13:54.965618Z","caller":"traceutil/trace.go:171","msg":"trace[1120476395] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:27176; }","duration":"178.784303ms","start":"2026-09-23T04:13:54.786807Z","end":"2026-09-23T04:13:54.965591Z","steps":["trace[1120476395] 'agreement among raft nodes before linearized reading' (duration: 178.569878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:54.965575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.489463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-23T04:13:54.96571Z","caller":"traceutil/trace.go:171","msg":"trace[772243629] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27176; }","duration":"302.707788ms","start":"2026-09-23T04:13:54.662986Z","end":"2026-09-23T04:13:54.965693Z","steps":["trace[772243629] 'agreement among raft nodes before linearized reading' (duration: 301.95607ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:54.965647Z","caller":"traceutil/trace.go:171","msg":"trace[1935895846] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-g9kp5.18d7d737279dd146; range_end:; response_count:1; response_revision:27176; }","duration":"143.054394ms","start":"2026-09-23T04:13:54.822565Z","end":"2026-09-23T04:13:54.965619Z","steps":["trace[1935895846] 'agreement among raft nodes before linearized reading' (duration: 142.801378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:54.965835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:54.662952Z","time spent":"302.869271ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-23T04:13:54.966032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.474964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T04:13:54.966178Z","caller":"traceutil/trace.go:171","msg":"trace[1905989456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27176; }","duration":"270.664639ms","start":"2026-09-23T04:13:54.695502Z","end":"2026-09-23T04:13:54.966166Z","steps":["trace[1905989456] 'agreement among raft nodes before linearized reading' (duration: 269.830349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.330364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.147638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:13:55.330527Z","caller":"traceutil/trace.go:171","msg":"trace[2068762617] linearizableReadLoop","detail":"{readStateIndex:28089; appliedIndex:28087; }","duration":"304.174553ms","start":"2026-09-23T04:13:55.026336Z","end":"2026-09-23T04:13:55.330511Z","steps":["trace[2068762617] 'read index received' (duration: 90.742339ms)","trace[2068762617] 'applied index is now lower than readState.Index' (duration: 213.431484ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:55.330762Z","caller":"traceutil/trace.go:171","msg":"trace[1710954561] transaction","detail":"{read_only:false; response_revision:27178; number_of_response:1; }","duration":"359.04242ms","start":"2026-09-23T04:13:54.971672Z","end":"2026-09-23T04:13:55.330714Z","steps":["trace[1710954561] 'process raft request' (duration: 145.438341ms)","trace[1710954561] 'compare' (duration: 212.845391ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:55.330777Z","caller":"traceutil/trace.go:171","msg":"trace[1633803311] transaction","detail":"{read_only:false; response_revision:27179; number_of_response:1; }","duration":"358.792184ms","start":"2026-09-23T04:13:54.971931Z","end":"2026-09-23T04:13:55.330723Z","steps":["trace[1633803311] 'process raft request' (duration: 358.526968ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.330834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.6359ms","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-09-23T04:13:55.330864Z","caller":"traceutil/trace.go:171","msg":"trace[134188104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27179; }","duration":"303.676791ms","start":"2026-09-23T04:13:55.02718Z","end":"2026-09-23T04:13:55.330856Z","steps":["trace[134188104] 'agreement among raft nodes before linearized reading' (duration: 303.534578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.330886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.02717Z","time spent":"303.711302ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T04:13:55.330909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.543872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"warn","ts":"2026-09-23T04:13:55.33096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:54.971644Z","time spent":"359.209694ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:55.330981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:54.971911Z","time spent":"358.960538ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:55.330976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.021732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T04:13:55.330989Z","caller":"traceutil/trace.go:171","msg":"trace[1808851776] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27179; }","duration":"304.680645ms","start":"2026-09-23T04:13:55.026285Z","end":"2026-09-23T04:13:55.330966Z","steps":["trace[1808851776] 'agreement among raft nodes before linearized reading' (duration: 304.385488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.330954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.825019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:55.331034Z","caller":"traceutil/trace.go:171","msg":"trace[2107171689] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:27179; }","duration":"175.174526ms","start":"2026-09-23T04:13:55.155847Z","end":"2026-09-23T04:13:55.331021Z","steps":["trace[2107171689] 'agreement among raft nodes before linearized reading' (duration: 174.957771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.331049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.026266Z","time spent":"304.774888ms","remote":"127.0.0.1:43038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19049,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-23T04:13:55.331077Z","caller":"traceutil/trace.go:171","msg":"trace[587842143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27179; }","duration":"108.033454ms","start":"2026-09-23T04:13:55.223014Z","end":"2026-09-23T04:13:55.331048Z","steps":["trace[587842143] 'agreement among raft nodes before linearized reading' (duration: 107.785448ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:55.511247Z","caller":"traceutil/trace.go:171","msg":"trace[580029945] transaction","detail":"{read_only:false; response_revision:27180; number_of_response:1; }","duration":"165.259277ms","start":"2026-09-23T04:13:55.345942Z","end":"2026-09-23T04:13:55.511201Z","steps":["trace[580029945] 'process raft request' (duration: 139.826417ms)","trace[580029945] 'compare' (duration: 25.243866ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:55.808595Z","caller":"traceutil/trace.go:171","msg":"trace[1127749214] transaction","detail":"{read_only:false; response_revision:27181; number_of_response:1; }","duration":"462.461923ms","start":"2026-09-23T04:13:55.346093Z","end":"2026-09-23T04:13:55.808555Z","steps":["trace[1127749214] 'process raft request' (duration: 415.810922ms)","trace[1127749214] 'compare' (duration: 45.966454ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:55.809048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.346073Z","time spent":"462.647597ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:55.809087Z","caller":"traceutil/trace.go:171","msg":"trace[316219599] transaction","detail":"{read_only:false; response_revision:27182; number_of_response:1; }","duration":"461.97893ms","start":"2026-09-23T04:13:55.347065Z","end":"2026-09-23T04:13:55.809044Z","steps":["trace[316219599] 'process raft request' (duration: 461.412397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.809315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.347036Z","time spent":"462.138084ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:55.809242Z","caller":"traceutil/trace.go:171","msg":"trace[2084037986] transaction","detail":"{read_only:false; response_revision:27183; number_of_response:1; }","duration":"460.729831ms","start":"2026-09-23T04:13:55.348461Z","end":"2026-09-23T04:13:55.809191Z","steps":["trace[2084037986] 'process raft request' (duration: 460.395903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.809532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.348437Z","time spent":"460.949966ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:55.809842Z","caller":"traceutil/trace.go:171","msg":"trace[915314041] transaction","detail":"{read_only:false; response_revision:27184; number_of_response:1; }","duration":"460.494955ms","start":"2026-09-23T04:13:55.349306Z","end":"2026-09-23T04:13:55.8098Z","steps":["trace[915314041] 'process raft request' (duration: 459.809919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.809964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.349294Z","time spent":"460.610608ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:55.810603Z","caller":"traceutil/trace.go:171","msg":"trace[434784363] linearizableReadLoop","detail":"{readStateIndex:28091; appliedIndex:28089; }","duration":"464.242645ms","start":"2026-09-23T04:13:55.346322Z","end":"2026-09-23T04:13:55.810564Z","steps":["trace[434784363] 'read index received' (duration: 139.595731ms)","trace[434784363] 'applied index is now lower than readState.Index' (duration: 324.644064ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:55.810737Z","caller":"traceutil/trace.go:171","msg":"trace[689706608] transaction","detail":"{read_only:false; response_revision:27185; number_of_response:1; }","duration":"461.288854ms","start":"2026-09-23T04:13:55.349402Z","end":"2026-09-23T04:13:55.810691Z","steps":["trace[689706608] 'process raft request' (duration: 459.975032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.810946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.34939Z","time spent":"461.451058ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:55.810978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.619374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-23T04:13:55.811031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.685563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:13:55.811051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.607995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:13:55.811049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.130516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-23T04:13:55.810984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.095827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-23T04:13:55.811132Z","caller":"traceutil/trace.go:171","msg":"trace[1429609526] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:27185; }","duration":"110.699687ms","start":"2026-09-23T04:13:55.700416Z","end":"2026-09-23T04:13:55.811116Z","steps":["trace[1429609526] 'agreement among raft nodes before linearized reading' (duration: 110.546663ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:55.811138Z","caller":"traceutil/trace.go:171","msg":"trace[1019920458] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27185; }","duration":"391.871228ms","start":"2026-09-23T04:13:55.419238Z","end":"2026-09-23T04:13:55.811109Z","steps":["trace[1019920458] 'agreement among raft nodes before linearized reading' (duration: 391.665473ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:55.811157Z","caller":"traceutil/trace.go:171","msg":"trace[1761017692] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:27185; }","duration":"443.337993ms","start":"2026-09-23T04:13:55.367806Z","end":"2026-09-23T04:13:55.811144Z","steps":["trace[1761017692] 'agreement among raft nodes before linearized reading' (duration: 442.970424ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:55.811079Z","caller":"traceutil/trace.go:171","msg":"trace[334796207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27185; }","duration":"464.761828ms","start":"2026-09-23T04:13:55.346299Z","end":"2026-09-23T04:13:55.811061Z","steps":["trace[334796207] 'agreement among raft nodes before linearized reading' (duration: 464.468351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.811199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.419207Z","time spent":"391.97881ms","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-23T04:13:55.811239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.36778Z","time spent":"443.409925ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"warn","ts":"2026-09-23T04:13:55.811284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.34629Z","time spent":"464.927051ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-23T04:13:55.811155Z","caller":"traceutil/trace.go:171","msg":"trace[829826186] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:27185; }","duration":"430.306ms","start":"2026-09-23T04:13:55.380821Z","end":"2026-09-23T04:13:55.811127Z","steps":["trace[829826186] 'agreement among raft nodes before linearized reading' (duration: 430.106525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:55.811426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:55.380796Z","time spent":"430.610528ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-09-23T04:13:56.061704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.71749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-23T04:13:56.061806Z","caller":"traceutil/trace.go:171","msg":"trace[1842101380] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:27186; }","duration":"143.893315ms","start":"2026-09-23T04:13:55.917887Z","end":"2026-09-23T04:13:56.06178Z","steps":["trace[1842101380] 'range keys from in-memory index tree' (duration: 143.486335ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:56.106199Z","caller":"traceutil/trace.go:171","msg":"trace[1345823331] transaction","detail":"{read_only:false; response_revision:27187; number_of_response:1; }","duration":"117.718627ms","start":"2026-09-23T04:13:55.988353Z","end":"2026-09-23T04:13:56.106071Z","steps":["trace[1345823331] 'process raft request' (duration: 117.226865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:56.421723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.637301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:56.421855Z","caller":"traceutil/trace.go:171","msg":"trace[271150625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27187; }","duration":"196.780234ms","start":"2026-09-23T04:13:56.225036Z","end":"2026-09-23T04:13:56.421817Z","steps":["trace[271150625] 'range keys from in-memory index tree' (duration: 196.481707ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:56.673606Z","caller":"traceutil/trace.go:171","msg":"trace[1886686427] transaction","detail":"{read_only:false; response_revision:27188; number_of_response:1; }","duration":"109.52251ms","start":"2026-09-23T04:13:56.564043Z","end":"2026-09-23T04:13:56.673566Z","steps":["trace[1886686427] 'process raft request' (duration: 109.277314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:57.197834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.588534ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-23T04:13:57.198498Z","caller":"traceutil/trace.go:171","msg":"trace[486941550] linearizableReadLoop","detail":"{readStateIndex:28102; appliedIndex:28099; }","duration":"151.680492ms","start":"2026-09-23T04:13:57.046792Z","end":"2026-09-23T04:13:57.198473Z","steps":["trace[486941550] 'read index received' (duration: 115.062383ms)","trace[486941550] 'applied index is now lower than readState.Index' (duration: 36.616639ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:57.198634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.814155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:57.198671Z","caller":"traceutil/trace.go:171","msg":"trace[1175799169] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:27191; }","duration":"151.922568ms","start":"2026-09-23T04:13:57.046737Z","end":"2026-09-23T04:13:57.19866Z","steps":["trace[1175799169] 'agreement among raft nodes before linearized reading' (duration: 151.810095ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:57.198917Z","caller":"traceutil/trace.go:171","msg":"trace[1729670418] transaction","detail":"{read_only:false; response_revision:27190; number_of_response:1; }","duration":"226.306284ms","start":"2026-09-23T04:13:56.972601Z","end":"2026-09-23T04:13:57.198907Z","steps":["trace[1729670418] 'process raft request' (duration: 225.368681ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:57.199015Z","caller":"traceutil/trace.go:171","msg":"trace[1372599863] transaction","detail":"{read_only:false; response_revision:27191; number_of_response:1; }","duration":"226.386685ms","start":"2026-09-23T04:13:56.972589Z","end":"2026-09-23T04:13:57.198976Z","steps":["trace[1372599863] 'process raft request' (duration: 225.794021ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:06.11343Z","caller":"traceutil/trace.go:171","msg":"trace[738011401] transaction","detail":"{read_only:false; response_revision:27255; number_of_response:1; }","duration":"259.400578ms","start":"2026-09-23T04:14:05.853988Z","end":"2026-09-23T04:14:06.113388Z","steps":["trace[738011401] 'process raft request' (duration: 259.098521ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:06.535773Z","caller":"traceutil/trace.go:171","msg":"trace[2055006294] linearizableReadLoop","detail":"{readStateIndex:28170; appliedIndex:28166; }","duration":"668.814176ms","start":"2026-09-23T04:14:05.866921Z","end":"2026-09-23T04:14:06.535735Z","steps":["trace[2055006294] 'read index received' (duration: 246.299043ms)","trace[2055006294] 'applied index is now lower than readState.Index' (duration: 422.513713ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:06.536107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.151275ms","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-09-23T04:14:06.536152Z","caller":"traceutil/trace.go:171","msg":"trace[970709482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27258; }","duration":"669.260978ms","start":"2026-09-23T04:14:05.866877Z","end":"2026-09-23T04:14:06.536138Z","steps":["trace[970709482] 'agreement among raft nodes before linearized reading' (duration: 669.011892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:06.536189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:05.866863Z","time spent":"669.317339ms","remote":"127.0.0.1:43136","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-09-23T04:14:06.53677Z","caller":"traceutil/trace.go:171","msg":"trace[1329501706] transaction","detail":"{read_only:false; response_revision:27258; number_of_response:1; }","duration":"671.03936ms","start":"2026-09-23T04:14:05.865717Z","end":"2026-09-23T04:14:06.536756Z","steps":["trace[1329501706] 'process raft request' (duration: 669.973174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:06.5369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:05.865679Z","time spent":"671.126162ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:14:06.536793Z","caller":"traceutil/trace.go:171","msg":"trace[1266699183] transaction","detail":"{read_only:false; response_revision:27256; number_of_response:1; }","duration":"679.186386ms","start":"2026-09-23T04:14:05.857378Z","end":"2026-09-23T04:14:06.536564Z","steps":["trace[1266699183] 'process raft request' (duration: 677.998178ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:06.536844Z","caller":"traceutil/trace.go:171","msg":"trace[1902806941] transaction","detail":"{read_only:false; response_revision:27257; number_of_response:1; }","duration":"674.543554ms","start":"2026-09-23T04:14:05.861993Z","end":"2026-09-23T04:14:06.536537Z","steps":["trace[1902806941] 'process raft request' (duration: 673.617562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:06.537198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:05.861956Z","time spent":"675.095478ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:14:06.537238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:05.857341Z","time spent":"679.77459ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:14:07.052794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.607265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:14:07.053017Z","caller":"traceutil/trace.go:171","msg":"trace[1667086475] transaction","detail":"{read_only:false; response_revision:27259; number_of_response:1; }","duration":"928.365068ms","start":"2026-09-23T04:14:06.124592Z","end":"2026-09-23T04:14:07.052957Z","steps":["trace[1667086475] 'process raft request' (duration: 660.472666ms)","trace[1667086475] 'compare' (duration: 267.321048ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:07.053197Z","caller":"traceutil/trace.go:171","msg":"trace[62612813] transaction","detail":"{read_only:false; response_revision:27261; number_of_response:1; }","duration":"325.088525ms","start":"2026-09-23T04:14:06.728083Z","end":"2026-09-23T04:14:07.053171Z","steps":["trace[62612813] 'process raft request' (duration: 325.029444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.053217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.124559Z","time spent":"928.534042ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:14:07.053993Z","caller":"traceutil/trace.go:171","msg":"trace[1019966469] transaction","detail":"{read_only:false; response_revision:27260; number_of_response:1; }","duration":"510.408244ms","start":"2026-09-23T04:14:06.54356Z","end":"2026-09-23T04:14:07.053968Z","steps":["trace[1019966469] 'process raft request' (duration: 509.40158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.054064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.586762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:14:07.054095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.543516Z","time spent":"510.525287ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:14:07.054081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.01442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:07.054162Z","caller":"traceutil/trace.go:171","msg":"trace[2127040079] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:27261; }","duration":"708.755345ms","start":"2026-09-23T04:14:06.345379Z","end":"2026-09-23T04:14:07.054134Z","steps":["trace[2127040079] 'agreement among raft nodes before linearized reading' (duration: 708.567441ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:07.054175Z","caller":"traceutil/trace.go:171","msg":"trace[992038496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27261; }","duration":"831.164104ms","start":"2026-09-23T04:14:06.22299Z","end":"2026-09-23T04:14:07.054154Z","steps":["trace[992038496] 'agreement among raft nodes before linearized reading' (duration: 831.02332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.054171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.186105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-09-23T04:14:07.054217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.345342Z","time spent":"708.863668ms","remote":"127.0.0.1:46590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-09-23T04:14:07.054231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.995569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:14:07.05422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.222963Z","time spent":"831.246106ms","remote":"127.0.0.1:42844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T04:14:07.054285Z","caller":"traceutil/trace.go:171","msg":"trace[1109894971] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27261; }","duration":"811.303667ms","start":"2026-09-23T04:14:06.242942Z","end":"2026-09-23T04:14:07.054245Z","steps":["trace[1109894971] 'agreement among raft nodes before linearized reading' (duration: 811.01282ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:07.054328Z","caller":"traceutil/trace.go:171","msg":"trace[649142253] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:27261; }","duration":"691.148382ms","start":"2026-09-23T04:14:06.363154Z","end":"2026-09-23T04:14:07.054302Z","steps":["trace[649142253] 'agreement among raft nodes before linearized reading' (duration: 690.963538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.054353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.242931Z","time spent":"811.41089ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-23T04:14:07.054383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.363118Z","time spent":"691.248605ms","remote":"127.0.0.1:50936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-23T04:14:07.054442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174172299s","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-09-23T04:14:07.054503Z","caller":"traceutil/trace.go:171","msg":"trace[1333405155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27261; }","duration":"1.174290073s","start":"2026-09-23T04:14:05.880193Z","end":"2026-09-23T04:14:07.054483Z","steps":["trace[1333405155] 'agreement among raft nodes before linearized reading' (duration: 1.174032917s)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.054546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:05.880172Z","time spent":"1.174361794s","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-23T04:14:07.053355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.728044Z","time spent":"325.199408ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:14:07.05643Z","caller":"traceutil/trace.go:171","msg":"trace[550231327] linearizableReadLoop","detail":"{readStateIndex:28171; appliedIndex:28170; }","duration":"518.357796ms","start":"2026-09-23T04:14:06.535865Z","end":"2026-09-23T04:14:07.054223Z","steps":["trace[550231327] 'read index received' (duration: 947.153µs)","trace[550231327] 'applied index is now lower than readState.Index' (duration: 516.90539ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:07.059448Z","caller":"traceutil/trace.go:171","msg":"trace[685561331] transaction","detail":"{read_only:false; response_revision:27262; number_of_response:1; }","duration":"146.826825ms","start":"2026-09-23T04:14:06.912591Z","end":"2026-09-23T04:14:07.059417Z","steps":["trace[685561331] 'process raft request' (duration: 146.496407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.317403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.027265ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-23T04:14:07.317583Z","caller":"traceutil/trace.go:171","msg":"trace[446140575] linearizableReadLoop","detail":"{readStateIndex:28175; appliedIndex:28173; }","duration":"261.040417ms","start":"2026-09-23T04:14:07.056493Z","end":"2026-09-23T04:14:07.317533Z","steps":["trace[446140575] 'read index received' (duration: 2.466949ms)","trace[446140575] 'applied index is now lower than readState.Index' (duration: 258.571628ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:07.3183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.115219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:07.318413Z","caller":"traceutil/trace.go:171","msg":"trace[75227983] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:27262; }","duration":"393.328464ms","start":"2026-09-23T04:14:06.925052Z","end":"2026-09-23T04:14:07.31838Z","steps":["trace[75227983] 'agreement among raft nodes before linearized reading' (duration: 393.087848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.318478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.925019Z","time spent":"393.441967ms","remote":"127.0.0.1:56402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-09-23T04:14:07.318483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.135244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-nt6tb.18d7d733e58aee1f\" ","response":"range_response_count:1 size:1387"} {"level":"warn","ts":"2026-09-23T04:14:07.318476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.870312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:07.31865Z","caller":"traceutil/trace.go:171","msg":"trace[935511039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27262; }","duration":"263.097216ms","start":"2026-09-23T04:14:07.055513Z","end":"2026-09-23T04:14:07.31861Z","steps":["trace[935511039] 'agreement among raft nodes before linearized reading' (duration: 262.785459ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:07.31887Z","caller":"traceutil/trace.go:171","msg":"trace[1607639141] transaction","detail":"{read_only:false; response_revision:27263; number_of_response:1; }","duration":"258.454515ms","start":"2026-09-23T04:14:07.060397Z","end":"2026-09-23T04:14:07.318852Z","steps":["trace[1607639141] 'process raft request' (duration: 258.25165ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:07.318556Z","caller":"traceutil/trace.go:171","msg":"trace[1920578245] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-nt6tb.18d7d733e58aee1f; range_end:; response_count:1; response_revision:27262; }","duration":"589.291188ms","start":"2026-09-23T04:14:06.729241Z","end":"2026-09-23T04:14:07.318532Z","steps":["trace[1920578245] 'agreement among raft nodes before linearized reading' (duration: 589.072053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.319056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:06.729229Z","time spent":"589.80047ms","remote":"127.0.0.1:42944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":1409,"request content":"key:\"/registry/events/openstack/neutron-l3-agent-default-nt6tb.18d7d733e58aee1f\" "} {"level":"warn","ts":"2026-09-23T04:14:07.328316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.484008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-23T04:14:07.328425Z","caller":"traceutil/trace.go:171","msg":"trace[750749274] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27265; }","duration":"266.672812ms","start":"2026-09-23T04:14:07.061726Z","end":"2026-09-23T04:14:07.328399Z","steps":["trace[750749274] 'agreement among raft nodes before linearized reading' (duration: 266.280653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.921375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.187255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:14:07.921496Z","caller":"traceutil/trace.go:171","msg":"trace[899400841] linearizableReadLoop","detail":"{readStateIndex:28180; appliedIndex:28179; }","duration":"513.649853ms","start":"2026-09-23T04:14:07.407825Z","end":"2026-09-23T04:14:07.921475Z","steps":["trace[899400841] 'read index received' (duration: 271.14165ms)","trace[899400841] 'applied index is now lower than readState.Index' (duration: 242.506323ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:07.921906Z","caller":"traceutil/trace.go:171","msg":"trace[1584168286] transaction","detail":"{read_only:false; response_revision:27267; number_of_response:1; }","duration":"517.375561ms","start":"2026-09-23T04:14:07.404472Z","end":"2026-09-23T04:14:07.921847Z","steps":["trace[1584168286] 'process raft request' (duration: 274.552681ms)","trace[1584168286] 'compare' (duration: 241.695913ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:07.922017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.168685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:07.922141Z","caller":"traceutil/trace.go:171","msg":"trace[336437671] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27267; }","duration":"514.321918ms","start":"2026-09-23T04:14:07.407796Z","end":"2026-09-23T04:14:07.922118Z","steps":["trace[336437671] 'agreement among raft nodes before linearized reading' (duration: 514.106733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:07.922192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:07.407784Z","time spent":"514.398641ms","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-23T04:14:07.922578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:07.404438Z","time spent":"517.576576ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:14:08.465122Z","caller":"traceutil/trace.go:171","msg":"trace[102364658] transaction","detail":"{read_only:false; response_revision:27269; number_of_response:1; }","duration":"344.893711ms","start":"2026-09-23T04:14:08.120185Z","end":"2026-09-23T04:14:08.465078Z","steps":["trace[102364658] 'process raft request' (duration: 344.759398ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:08.465453Z","caller":"traceutil/trace.go:171","msg":"trace[1456848738] linearizableReadLoop","detail":"{readStateIndex:28181; appliedIndex:28180; }","duration":"543.878758ms","start":"2026-09-23T04:14:07.921521Z","end":"2026-09-23T04:14:08.4654Z","steps":["trace[1456848738] 'read index received' (duration: 499.175114ms)","trace[1456848738] 'applied index is now lower than readState.Index' (duration: 44.700503ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:08.465481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:08.120149Z","time spent":"345.196388ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:14:08.465427Z","caller":"traceutil/trace.go:171","msg":"trace[1957438993] transaction","detail":"{read_only:false; response_revision:27268; number_of_response:1; }","duration":"1.056742731s","start":"2026-09-23T04:14:07.408638Z","end":"2026-09-23T04:14:08.465381Z","steps":["trace[1957438993] 'process raft request' (duration: 1.01210657s)","trace[1957438993] 'compare' (duration: 43.936294ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:08.46562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.006652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-23T04:14:08.465638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057581051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T04:14:08.465709Z","caller":"traceutil/trace.go:171","msg":"trace[444671783] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:27269; }","duration":"1.057689784s","start":"2026-09-23T04:14:07.408003Z","end":"2026-09-23T04:14:08.465692Z","steps":["trace[444671783] 'agreement among raft nodes before linearized reading' (duration: 1.05753778s)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:08.465704Z","caller":"traceutil/trace.go:171","msg":"trace[995569020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27269; }","duration":"876.180296ms","start":"2026-09-23T04:14:07.589505Z","end":"2026-09-23T04:14:08.465685Z","steps":["trace[995569020] 'agreement among raft nodes before linearized reading' (duration: 875.91311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:08.465723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.129263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:14:08.465845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.303735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:08.465918Z","caller":"traceutil/trace.go:171","msg":"trace[518711429] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:27269; }","duration":"346.434038ms","start":"2026-09-23T04:14:08.119467Z","end":"2026-09-23T04:14:08.465901Z","steps":["trace[518711429] 'agreement among raft nodes before linearized reading' (duration: 346.314295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:08.465962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:08.119421Z","time spent":"346.52645ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-09-23T04:14:08.466025Z","caller":"traceutil/trace.go:171","msg":"trace[1647388765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27269; }","duration":"242.344248ms","start":"2026-09-23T04:14:08.223539Z","end":"2026-09-23T04:14:08.465883Z","steps":["trace[1647388765] 'agreement among raft nodes before linearized reading' (duration: 242.146723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:08.465756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:07.589474Z","time spent":"876.269859ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T04:14:08.465734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:07.40862Z","time spent":"1.056952337s","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:14:08.465812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:07.407996Z","time spent":"1.057802517s","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":30,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-09-23T04:14:08.465645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.835987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:08.466428Z","caller":"traceutil/trace.go:171","msg":"trace[1606008297] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27269; }","duration":"539.620866ms","start":"2026-09-23T04:14:07.926752Z","end":"2026-09-23T04:14:08.466372Z","steps":["trace[1606008297] 'agreement among raft nodes before linearized reading' (duration: 538.839597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:08.466532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:07.92673Z","time spent":"539.765469ms","remote":"127.0.0.1:43050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-23T04:14:08.640848Z","caller":"traceutil/trace.go:171","msg":"trace[1669382082] linearizableReadLoop","detail":"{readStateIndex:28183; appliedIndex:28182; }","duration":"167.723467ms","start":"2026-09-23T04:14:08.473091Z","end":"2026-09-23T04:14:08.640815Z","steps":["trace[1669382082] 'read index received' (duration: 164.08532ms)","trace[1669382082] 'applied index is now lower than readState.Index' (duration: 3.636957ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:08.640876Z","caller":"traceutil/trace.go:171","msg":"trace[355280454] transaction","detail":"{read_only:false; response_revision:27270; number_of_response:1; }","duration":"169.07561ms","start":"2026-09-23T04:14:08.471764Z","end":"2026-09-23T04:14:08.64084Z","steps":["trace[355280454] 'process raft request' (duration: 165.31344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:08.641219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.101966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-09-23T04:14:08.641317Z","caller":"traceutil/trace.go:171","msg":"trace[102460222] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27270; }","duration":"168.27227ms","start":"2026-09-23T04:14:08.473028Z","end":"2026-09-23T04:14:08.6413Z","steps":["trace[102460222] 'agreement among raft nodes before linearized reading' (duration: 167.905961ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:08.649137Z","caller":"traceutil/trace.go:171","msg":"trace[552094817] transaction","detail":"{read_only:false; response_revision:27271; number_of_response:1; }","duration":"105.984665ms","start":"2026-09-23T04:14:08.543116Z","end":"2026-09-23T04:14:08.6491Z","steps":["trace[552094817] 'process raft request' (duration: 105.559795ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:08.649555Z","caller":"traceutil/trace.go:171","msg":"trace[1752928101] transaction","detail":"{read_only:false; response_revision:27272; number_of_response:1; }","duration":"106.088107ms","start":"2026-09-23T04:14:08.54344Z","end":"2026-09-23T04:14:08.649528Z","steps":["trace[1752928101] 'process raft request' (duration: 105.501773ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:08.649679Z","caller":"traceutil/trace.go:171","msg":"trace[1098498809] transaction","detail":"{read_only:false; response_revision:27273; number_of_response:1; }","duration":"105.212746ms","start":"2026-09-23T04:14:08.544422Z","end":"2026-09-23T04:14:08.649635Z","steps":["trace[1098498809] 'process raft request' (duration: 105.008661ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:13.190211Z","caller":"traceutil/trace.go:171","msg":"trace[322177071] linearizableReadLoop","detail":"{readStateIndex:28276; appliedIndex:28275; }","duration":"106.473306ms","start":"2026-09-23T04:14:13.083706Z","end":"2026-09-23T04:14:13.190179Z","steps":["trace[322177071] 'read index received' (duration: 106.294862ms)","trace[322177071] 'applied index is now lower than readState.Index' (duration: 177.454µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:13.190326Z","caller":"traceutil/trace.go:171","msg":"trace[1281437864] transaction","detail":"{read_only:false; response_revision:27362; number_of_response:1; }","duration":"113.72965ms","start":"2026-09-23T04:14:13.07655Z","end":"2026-09-23T04:14:13.190279Z","steps":["trace[1281437864] 'process raft request' (duration: 113.412903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:13.190464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.725732ms","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-09-23T04:14:13.190504Z","caller":"traceutil/trace.go:171","msg":"trace[1763844496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27362; }","duration":"106.831865ms","start":"2026-09-23T04:14:13.08366Z","end":"2026-09-23T04:14:13.190492Z","steps":["trace[1763844496] 'agreement among raft nodes before linearized reading' (duration: 106.64733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:13.47224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.981193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:14:13.47251Z","caller":"traceutil/trace.go:171","msg":"trace[1262833915] transaction","detail":"{read_only:false; response_revision:27363; number_of_response:1; }","duration":"387.123715ms","start":"2026-09-23T04:14:13.085351Z","end":"2026-09-23T04:14:13.472474Z","steps":["trace[1262833915] 'process raft request' (duration: 238.789203ms)","trace[1262833915] 'compare' (duration: 147.777698ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:13.472571Z","caller":"traceutil/trace.go:171","msg":"trace[717594141] linearizableReadLoop","detail":"{readStateIndex:28278; appliedIndex:28276; }","duration":"249.285804ms","start":"2026-09-23T04:14:13.223267Z","end":"2026-09-23T04:14:13.472553Z","steps":["trace[717594141] 'read index received' (duration: 100.843641ms)","trace[717594141] 'applied index is now lower than readState.Index' (duration: 148.441003ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:13.472615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:13.085334Z","time spent":"387.229487ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:14:13.472654Z","caller":"traceutil/trace.go:171","msg":"trace[1670579084] transaction","detail":"{read_only:false; response_revision:27364; number_of_response:1; }","duration":"277.517643ms","start":"2026-09-23T04:14:13.195126Z","end":"2026-09-23T04:14:13.472643Z","steps":["trace[1670579084] 'process raft request' (duration: 277.280617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:13.472788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.50484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:13.472878Z","caller":"traceutil/trace.go:171","msg":"trace[5765107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27364; }","duration":"249.646084ms","start":"2026-09-23T04:14:13.223212Z","end":"2026-09-23T04:14:13.472858Z","steps":["trace[5765107] 'agreement among raft nodes before linearized reading' (duration: 249.50786ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:13.771297Z","caller":"traceutil/trace.go:171","msg":"trace[298147501] linearizableReadLoop","detail":"{readStateIndex:28280; appliedIndex:28278; }","duration":"298.640939ms","start":"2026-09-23T04:14:13.472604Z","end":"2026-09-23T04:14:13.771245Z","steps":["trace[298147501] 'read index received' (duration: 262.84557ms)","trace[298147501] 'applied index is now lower than readState.Index' (duration: 35.794419ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:13.771351Z","caller":"traceutil/trace.go:171","msg":"trace[909758273] transaction","detail":"{read_only:false; response_revision:27366; number_of_response:1; }","duration":"413.574678ms","start":"2026-09-23T04:14:13.357751Z","end":"2026-09-23T04:14:13.771326Z","steps":["trace[909758273] 'process raft request' (duration: 413.435664ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:13.771456Z","caller":"traceutil/trace.go:171","msg":"trace[1722484663] transaction","detail":"{read_only:false; response_revision:27365; number_of_response:1; }","duration":"415.619217ms","start":"2026-09-23T04:14:13.355802Z","end":"2026-09-23T04:14:13.771421Z","steps":["trace[1722484663] 'process raft request' (duration: 379.638493ms)","trace[1722484663] 'compare' (duration: 35.559534ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:13.771495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.519916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-23T04:14:13.771509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:13.357732Z","time spent":"413.665731ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:14:13.771532Z","caller":"traceutil/trace.go:171","msg":"trace[1013751584] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:27366; }","duration":"303.629689ms","start":"2026-09-23T04:14:13.46789Z","end":"2026-09-23T04:14:13.77152Z","steps":["trace[1013751584] 'agreement among raft nodes before linearized reading' (duration: 303.544916ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:13.771561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:13.467861Z","time spent":"303.69151ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-23T04:14:13.771606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:13.355764Z","time spent":"415.761081ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:14:13.771761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.793404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:14:13.771819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.223233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-23T04:14:13.771841Z","caller":"traceutil/trace.go:171","msg":"trace[836365923] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27366; }","duration":"104.303664ms","start":"2026-09-23T04:14:13.667531Z","end":"2026-09-23T04:14:13.771835Z","steps":["trace[836365923] 'agreement among raft nodes before linearized reading' (duration: 104.196942ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:13.771852Z","caller":"traceutil/trace.go:171","msg":"trace[221994361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27366; }","duration":"296.960099ms","start":"2026-09-23T04:14:13.474869Z","end":"2026-09-23T04:14:13.771829Z","steps":["trace[221994361] 'agreement among raft nodes before linearized reading' (duration: 296.800105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:13.772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.056822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-23T04:14:13.77213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.476813ms","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-09-23T04:14:13.772168Z","caller":"traceutil/trace.go:171","msg":"trace[1839134252] 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:27366; }","duration":"150.570485ms","start":"2026-09-23T04:14:13.621582Z","end":"2026-09-23T04:14:13.772153Z","steps":["trace[1839134252] 'agreement among raft nodes before linearized reading' (duration: 150.500223ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:13.772348Z","caller":"traceutil/trace.go:171","msg":"trace[346705657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27366; }","duration":"422.139864ms","start":"2026-09-23T04:14:13.349882Z","end":"2026-09-23T04:14:13.772022Z","steps":["trace[346705657] 'agreement among raft nodes before linearized reading' (duration: 422.00524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:13.772383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.966223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07c6a911-5802-475e-9023-2efbe8f972a6\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-09-23T04:14:13.772435Z","caller":"traceutil/trace.go:171","msg":"trace[1037104990] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07c6a911-5802-475e-9023-2efbe8f972a6; range_end:; response_count:1; response_revision:27366; }","duration":"283.041905ms","start":"2026-09-23T04:14:13.489373Z","end":"2026-09-23T04:14:13.772415Z","steps":["trace[1037104990] 'agreement among raft nodes before linearized reading' (duration: 282.870431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:13.772415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:13.349853Z","time spent":"422.548954ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-23T04:14:20.8081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.174328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:14:20.808855Z","caller":"traceutil/trace.go:171","msg":"trace[1185122877] transaction","detail":"{read_only:false; response_revision:27439; number_of_response:1; }","duration":"217.585153ms","start":"2026-09-23T04:14:20.591236Z","end":"2026-09-23T04:14:20.808821Z","steps":["trace[1185122877] 'process raft request' (duration: 85.530943ms)","trace[1185122877] 'compare' (duration: 130.953053ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:20.896286Z","caller":"traceutil/trace.go:171","msg":"trace[1608962251] transaction","detail":"{read_only:false; response_revision:27440; number_of_response:1; }","duration":"195.431672ms","start":"2026-09-23T04:14:20.700802Z","end":"2026-09-23T04:14:20.896233Z","steps":["trace[1608962251] 'process raft request' (duration: 195.250927ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:21.009598Z","caller":"traceutil/trace.go:171","msg":"trace[371046557] linearizableReadLoop","detail":"{readStateIndex:28358; appliedIndex:28355; }","duration":"303.190359ms","start":"2026-09-23T04:14:20.706374Z","end":"2026-09-23T04:14:21.009564Z","steps":["trace[371046557] 'read index received' (duration: 189.653143ms)","trace[371046557] 'applied index is now lower than readState.Index' (duration: 113.535736ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:21.009667Z","caller":"traceutil/trace.go:171","msg":"trace[456184952] transaction","detail":"{read_only:false; response_revision:27442; number_of_response:1; }","duration":"307.664735ms","start":"2026-09-23T04:14:20.701972Z","end":"2026-09-23T04:14:21.009637Z","steps":["trace[456184952] 'process raft request' (duration: 307.518042ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:21.009663Z","caller":"traceutil/trace.go:171","msg":"trace[962798247] transaction","detail":"{read_only:false; response_revision:27441; number_of_response:1; }","duration":"308.206819ms","start":"2026-09-23T04:14:20.701388Z","end":"2026-09-23T04:14:21.009595Z","steps":["trace[962798247] 'process raft request' (duration: 269.953291ms)","trace[962798247] 'compare' (duration: 37.981122ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:21.009793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.406914ms","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-09-23T04:14:21.009841Z","caller":"traceutil/trace.go:171","msg":"trace[1493326877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27442; }","duration":"303.507726ms","start":"2026-09-23T04:14:20.706323Z","end":"2026-09-23T04:14:21.009831Z","steps":["trace[1493326877] 'agreement among raft nodes before linearized reading' (duration: 303.366443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:21.009833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:20.701958Z","time spent":"307.766809ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:14:21.009863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:20.706302Z","time spent":"303.555387ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T04:14:21.00986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:20.701353Z","time spent":"308.396333ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:14:25.335045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.855422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:25.335304Z","caller":"traceutil/trace.go:171","msg":"trace[1726952741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27479; }","duration":"111.154119ms","start":"2026-09-23T04:14:25.224069Z","end":"2026-09-23T04:14:25.335223Z","steps":["trace[1726952741] 'range keys from in-memory index tree' (duration: 110.609996ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:25.335583Z","caller":"traceutil/trace.go:171","msg":"trace[689603537] transaction","detail":"{read_only:false; response_revision:27480; number_of_response:1; }","duration":"101.980548ms","start":"2026-09-23T04:14:25.233549Z","end":"2026-09-23T04:14:25.33553Z","steps":["trace[689603537] 'process raft request' (duration: 87.256485ms)","trace[689603537] 'compare' (duration: 14.000746ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:25.740227Z","caller":"traceutil/trace.go:171","msg":"trace[28150445] transaction","detail":"{read_only:false; response_revision:27482; number_of_response:1; }","duration":"157.098712ms","start":"2026-09-23T04:14:25.583087Z","end":"2026-09-23T04:14:25.740186Z","steps":["trace[28150445] 'process raft request' (duration: 102.378818ms)","trace[28150445] 'compare' (duration: 54.463107ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:25.956872Z","caller":"traceutil/trace.go:171","msg":"trace[384130239] transaction","detail":"{read_only:false; response_revision:27484; number_of_response:1; }","duration":"106.503107ms","start":"2026-09-23T04:14:25.850329Z","end":"2026-09-23T04:14:25.956832Z","steps":["trace[384130239] 'process raft request' (duration: 106.176209ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:27.137721Z","caller":"traceutil/trace.go:171","msg":"trace[840158018] transaction","detail":"{read_only:false; response_revision:27493; number_of_response:1; }","duration":"100.286898ms","start":"2026-09-23T04:14:27.037388Z","end":"2026-09-23T04:14:27.137675Z","steps":["trace[840158018] 'process raft request' (duration: 99.999631ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:28.732043Z","caller":"traceutil/trace.go:171","msg":"trace[802251809] transaction","detail":"{read_only:false; response_revision:27504; number_of_response:1; }","duration":"111.013285ms","start":"2026-09-23T04:14:28.620995Z","end":"2026-09-23T04:14:28.732008Z","steps":["trace[802251809] 'process raft request' (duration: 110.741389ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:45.166892Z","caller":"traceutil/trace.go:171","msg":"trace[1338596180] linearizableReadLoop","detail":"{readStateIndex:28563; appliedIndex:28562; }","duration":"113.521976ms","start":"2026-09-23T04:14:45.053335Z","end":"2026-09-23T04:14:45.166857Z","steps":["trace[1338596180] 'read index received' (duration: 113.326351ms)","trace[1338596180] 'applied index is now lower than readState.Index' (duration: 194.475µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:45.166937Z","caller":"traceutil/trace.go:171","msg":"trace[1363379573] transaction","detail":"{read_only:false; response_revision:27641; number_of_response:1; }","duration":"130.790311ms","start":"2026-09-23T04:14:45.036099Z","end":"2026-09-23T04:14:45.166889Z","steps":["trace[1363379573] 'process raft request' (duration: 130.530664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:45.167188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.755461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:45.167361Z","caller":"traceutil/trace.go:171","msg":"trace[653764151] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:27641; }","duration":"113.984536ms","start":"2026-09-23T04:14:45.053305Z","end":"2026-09-23T04:14:45.16729Z","steps":["trace[653764151] 'agreement among raft nodes before linearized reading' (duration: 113.680899ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:45.189856Z","caller":"traceutil/trace.go:171","msg":"trace[1786694643] transaction","detail":"{read_only:false; response_revision:27642; number_of_response:1; }","duration":"106.677742ms","start":"2026-09-23T04:14:45.083161Z","end":"2026-09-23T04:14:45.189838Z","steps":["trace[1786694643] 'process raft request' (duration: 106.524578ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:45.49374Z","caller":"traceutil/trace.go:171","msg":"trace[238094043] transaction","detail":"{read_only:false; response_revision:27643; number_of_response:1; }","duration":"222.530173ms","start":"2026-09-23T04:14:45.27118Z","end":"2026-09-23T04:14:45.493711Z","steps":["trace[238094043] 'process raft request' (duration: 222.307098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:45.743963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.861679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:14:45.744239Z","caller":"traceutil/trace.go:171","msg":"trace[1201990361] linearizableReadLoop","detail":"{readStateIndex:28567; appliedIndex:28564; }","duration":"435.417184ms","start":"2026-09-23T04:14:45.308804Z","end":"2026-09-23T04:14:45.744221Z","steps":["trace[1201990361] 'read index received' (duration: 184.817737ms)","trace[1201990361] 'applied index is now lower than readState.Index' (duration: 250.598467ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:45.74436Z","caller":"traceutil/trace.go:171","msg":"trace[1844339694] transaction","detail":"{read_only:false; response_revision:27646; number_of_response:1; }","duration":"292.182065ms","start":"2026-09-23T04:14:45.452135Z","end":"2026-09-23T04:14:45.744317Z","steps":["trace[1844339694] 'process raft request' (duration: 292.047032ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:45.744368Z","caller":"traceutil/trace.go:171","msg":"trace[806292900] transaction","detail":"{read_only:false; response_revision:27645; number_of_response:1; }","duration":"452.642278ms","start":"2026-09-23T04:14:45.291667Z","end":"2026-09-23T04:14:45.744309Z","steps":["trace[806292900] 'process raft request' (duration: 452.455984ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:45.744399Z","caller":"traceutil/trace.go:171","msg":"trace[986858904] transaction","detail":"{read_only:false; response_revision:27644; number_of_response:1; }","duration":"453.521239ms","start":"2026-09-23T04:14:45.290811Z","end":"2026-09-23T04:14:45.744332Z","steps":["trace[986858904] 'process raft request' (duration: 308.164839ms)","trace[986858904] 'compare' (duration: 144.727675ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:45.744582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.759083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-23T04:14:45.744632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:45.290774Z","time spent":"453.733434ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:14:45.744659Z","caller":"traceutil/trace.go:171","msg":"trace[129996990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27646; }","duration":"435.901286ms","start":"2026-09-23T04:14:45.308743Z","end":"2026-09-23T04:14:45.744644Z","steps":["trace[129996990] 'agreement among raft nodes before linearized reading' (duration: 435.570848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:45.744741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:45.308706Z","time spent":"435.983168ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T04:14:45.744629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:45.291631Z","time spent":"452.853613ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:14:45.75367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.329305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:45.753758Z","caller":"traceutil/trace.go:171","msg":"trace[554256359] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:27647; }","duration":"316.480838ms","start":"2026-09-23T04:14:45.437254Z","end":"2026-09-23T04:14:45.753735Z","steps":["trace[554256359] 'agreement among raft nodes before linearized reading' (duration: 316.248323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:45.753804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:14:45.437228Z","time spent":"316.563521ms","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-09-23T04:14:45.753824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.413881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-23T04:14:45.753885Z","caller":"traceutil/trace.go:171","msg":"trace[968017954] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:27647; }","duration":"100.522653ms","start":"2026-09-23T04:14:45.653343Z","end":"2026-09-23T04:14:45.753866Z","steps":["trace[968017954] 'agreement among raft nodes before linearized reading' (duration: 100.237766ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:45.992665Z","caller":"traceutil/trace.go:171","msg":"trace[1285344989] linearizableReadLoop","detail":"{readStateIndex:28570; appliedIndex:28569; }","duration":"149.759346ms","start":"2026-09-23T04:14:45.842871Z","end":"2026-09-23T04:14:45.992631Z","steps":["trace[1285344989] 'read index received' (duration: 72.800128ms)","trace[1285344989] 'applied index is now lower than readState.Index' (duration: 76.957758ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:45.992778Z","caller":"traceutil/trace.go:171","msg":"trace[967979178] transaction","detail":"{read_only:false; response_revision:27648; number_of_response:1; }","duration":"243.623418ms","start":"2026-09-23T04:14:45.749084Z","end":"2026-09-23T04:14:45.992707Z","steps":["trace[967979178] 'process raft request' (duration: 166.544397ms)","trace[967979178] 'compare' (duration: 76.747853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:45.993018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.082814ms","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-09-23T04:14:45.993127Z","caller":"traceutil/trace.go:171","msg":"trace[1372565302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27648; }","duration":"150.269168ms","start":"2026-09-23T04:14:45.842831Z","end":"2026-09-23T04:14:45.9931Z","steps":["trace[1372565302] 'agreement among raft nodes before linearized reading' (duration: 149.92604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:51.959215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.800295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:14:51.959519Z","caller":"traceutil/trace.go:171","msg":"trace[38806600] transaction","detail":"{read_only:false; response_revision:27687; number_of_response:1; }","duration":"274.826018ms","start":"2026-09-23T04:14:51.68464Z","end":"2026-09-23T04:14:51.959466Z","steps":["trace[38806600] 'process raft request' (duration: 87.656964ms)","trace[38806600] 'compare' (duration: 186.365755ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:51.95964Z","caller":"traceutil/trace.go:171","msg":"trace[580589807] transaction","detail":"{read_only:false; response_revision:27688; number_of_response:1; }","duration":"195.978576ms","start":"2026-09-23T04:14:51.763595Z","end":"2026-09-23T04:14:51.959573Z","steps":["trace[580589807] 'process raft request' (duration: 195.770081ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:51.959823Z","caller":"traceutil/trace.go:171","msg":"trace[2046964825] transaction","detail":"{read_only:false; response_revision:27689; number_of_response:1; }","duration":"193.610009ms","start":"2026-09-23T04:14:51.766135Z","end":"2026-09-23T04:14:51.959745Z","steps":["trace[2046964825] 'process raft request' (duration: 193.310361ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:51.959852Z","caller":"traceutil/trace.go:171","msg":"trace[1237960164] transaction","detail":"{read_only:false; response_revision:27690; number_of_response:1; }","duration":"190.193056ms","start":"2026-09-23T04:14:51.769613Z","end":"2026-09-23T04:14:51.959807Z","steps":["trace[1237960164] 'process raft request' (duration: 189.894329ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:51.959958Z","caller":"traceutil/trace.go:171","msg":"trace[389380177] transaction","detail":"{read_only:false; response_revision:27691; number_of_response:1; }","duration":"188.113656ms","start":"2026-09-23T04:14:51.771817Z","end":"2026-09-23T04:14:51.959931Z","steps":["trace[389380177] 'process raft request' (duration: 187.728557ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:52.194643Z","caller":"traceutil/trace.go:171","msg":"trace[1504716975] transaction","detail":"{read_only:false; response_revision:27693; number_of_response:1; }","duration":"147.751297ms","start":"2026-09-23T04:14:52.046855Z","end":"2026-09-23T04:14:52.194606Z","steps":["trace[1504716975] 'process raft request' (duration: 56.039695ms)","trace[1504716975] 'compare' (duration: 91.104508ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:52.195095Z","caller":"traceutil/trace.go:171","msg":"trace[652308292] transaction","detail":"{read_only:false; response_revision:27694; number_of_response:1; }","duration":"143.469574ms","start":"2026-09-23T04:14:52.051584Z","end":"2026-09-23T04:14:52.195054Z","steps":["trace[652308292] 'process raft request' (duration: 143.206948ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:52.195185Z","caller":"traceutil/trace.go:171","msg":"trace[1869226180] transaction","detail":"{read_only:false; response_revision:27695; number_of_response:1; }","duration":"133.901845ms","start":"2026-09-23T04:14:52.061251Z","end":"2026-09-23T04:14:52.195153Z","steps":["trace[1869226180] 'process raft request' (duration: 133.626198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:52.280608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.495631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-23T04:14:52.280718Z","caller":"traceutil/trace.go:171","msg":"trace[983995218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27696; }","duration":"107.733076ms","start":"2026-09-23T04:14:52.172963Z","end":"2026-09-23T04:14:52.280696Z","steps":["trace[983995218] 'agreement among raft nodes before linearized reading' (duration: 107.4701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:52.484915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.96897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:14:52.485111Z","caller":"traceutil/trace.go:171","msg":"trace[1764968877] transaction","detail":"{read_only:false; response_revision:27697; number_of_response:1; }","duration":"283.21817ms","start":"2026-09-23T04:14:52.201841Z","end":"2026-09-23T04:14:52.485059Z","steps":["trace[1764968877] 'process raft request' (duration: 132.948492ms)","trace[1764968877] 'compare' (duration: 149.623512ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:52.485222Z","caller":"traceutil/trace.go:171","msg":"trace[1081421518] transaction","detail":"{read_only:false; response_revision:27698; number_of_response:1; }","duration":"197.45818ms","start":"2026-09-23T04:14:52.287741Z","end":"2026-09-23T04:14:52.4852Z","steps":["trace[1081421518] 'process raft request' (duration: 197.334617ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:52.485439Z","caller":"traceutil/trace.go:171","msg":"trace[2023231109] linearizableReadLoop","detail":"{readStateIndex:28621; appliedIndex:28620; }","duration":"205.107255ms","start":"2026-09-23T04:14:52.280322Z","end":"2026-09-23T04:14:52.48543Z","steps":["trace[2023231109] 'read index received' (duration: 54.482839ms)","trace[2023231109] 'applied index is now lower than readState.Index' (duration: 150.623336ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:52.485738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.38821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:52.485778Z","caller":"traceutil/trace.go:171","msg":"trace[1955942035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27698; }","duration":"262.467232ms","start":"2026-09-23T04:14:52.2233Z","end":"2026-09-23T04:14:52.485767Z","steps":["trace[1955942035] 'agreement among raft nodes before linearized reading' (duration: 262.39589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:52.554172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.260697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-gc\" ","response":"range_response_count:1 size:880"} {"level":"info","ts":"2026-09-23T04:14:52.55425Z","caller":"traceutil/trace.go:171","msg":"trace[1234916199] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery-gc; range_end:; response_count:1; response_revision:27699; }","duration":"100.37868ms","start":"2026-09-23T04:14:52.453849Z","end":"2026-09-23T04:14:52.554228Z","steps":["trace[1234916199] 'agreement among raft nodes before linearized reading' (duration: 100.189665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:52.55452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.722633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T04:14:52.555876Z","caller":"traceutil/trace.go:171","msg":"trace[918912607] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:27699; }","duration":"173.174677ms","start":"2026-09-23T04:14:52.382678Z","end":"2026-09-23T04:14:52.555853Z","steps":["trace[918912607] 'agreement among raft nodes before linearized reading' (duration: 171.521658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:53.166792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.773409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:53.1669Z","caller":"traceutil/trace.go:171","msg":"trace[1413849819] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:27700; }","duration":"125.969995ms","start":"2026-09-23T04:14:53.040904Z","end":"2026-09-23T04:14:53.166874Z","steps":["trace[1413849819] 'count revisions from in-memory index tree' (duration: 125.507653ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:53.424646Z","caller":"traceutil/trace.go:171","msg":"trace[497047159] linearizableReadLoop","detail":"{readStateIndex:28626; appliedIndex:28625; }","duration":"197.82605ms","start":"2026-09-23T04:14:53.226786Z","end":"2026-09-23T04:14:53.424612Z","steps":["trace[497047159] 'read index received' (duration: 29.653912ms)","trace[497047159] 'applied index is now lower than readState.Index' (duration: 168.170578ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:14:53.424801Z","caller":"traceutil/trace.go:171","msg":"trace[870135886] transaction","detail":"{read_only:false; response_revision:27702; number_of_response:1; }","duration":"205.969525ms","start":"2026-09-23T04:14:53.218817Z","end":"2026-09-23T04:14:53.424787Z","steps":["trace[870135886] 'process raft request' (duration: 185.003512ms)","trace[870135886] 'compare' (duration: 20.40845ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:14:53.424989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.172578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:53.425062Z","caller":"traceutil/trace.go:171","msg":"trace[2031654694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27702; }","duration":"198.319871ms","start":"2026-09-23T04:14:53.226727Z","end":"2026-09-23T04:14:53.425047Z","steps":["trace[2031654694] 'agreement among raft nodes before linearized reading' (duration: 198.176908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:14:53.425255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.50253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:14:53.425599Z","caller":"traceutil/trace.go:171","msg":"trace[555378916] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:27702; }","duration":"154.974861ms","start":"2026-09-23T04:14:53.2706Z","end":"2026-09-23T04:14:53.425575Z","steps":["trace[555378916] 'agreement among raft nodes before linearized reading' (duration: 154.338996ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:03.435635Z","caller":"traceutil/trace.go:171","msg":"trace[752030959] transaction","detail":"{read_only:false; response_revision:27790; number_of_response:1; }","duration":"113.734701ms","start":"2026-09-23T04:15:03.321843Z","end":"2026-09-23T04:15:03.435578Z","steps":["trace[752030959] 'process raft request' (duration: 113.479484ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:04.212201Z","caller":"traceutil/trace.go:171","msg":"trace[1111441840] linearizableReadLoop","detail":"{readStateIndex:28722; appliedIndex:28719; }","duration":"100.524853ms","start":"2026-09-23T04:15:04.111653Z","end":"2026-09-23T04:15:04.212178Z","steps":["trace[1111441840] 'read index received' (duration: 63.298419ms)","trace[1111441840] 'applied index is now lower than readState.Index' (duration: 37.225004ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:15:04.212289Z","caller":"traceutil/trace.go:171","msg":"trace[38626932] transaction","detail":"{read_only:false; response_revision:27796; number_of_response:1; }","duration":"187.341118ms","start":"2026-09-23T04:15:04.024905Z","end":"2026-09-23T04:15:04.212246Z","steps":["trace[38626932] 'process raft request' (duration: 187.217905ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:04.212169Z","caller":"traceutil/trace.go:171","msg":"trace[1728857481] transaction","detail":"{read_only:false; response_revision:27794; number_of_response:1; }","duration":"193.952227ms","start":"2026-09-23T04:15:04.018163Z","end":"2026-09-23T04:15:04.212115Z","steps":["trace[1728857481] 'process raft request' (duration: 156.768104ms)","trace[1728857481] 'compare' (duration: 36.67669ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:15:04.212516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.846251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-23T04:15:04.212557Z","caller":"traceutil/trace.go:171","msg":"trace[694234433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27796; }","duration":"100.966434ms","start":"2026-09-23T04:15:04.111575Z","end":"2026-09-23T04:15:04.212542Z","steps":["trace[694234433] 'agreement among raft nodes before linearized reading' (duration: 100.759049ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:04.212997Z","caller":"traceutil/trace.go:171","msg":"trace[1829602343] transaction","detail":"{read_only:false; response_revision:27795; number_of_response:1; }","duration":"194.385668ms","start":"2026-09-23T04:15:04.018564Z","end":"2026-09-23T04:15:04.21295Z","steps":["trace[1829602343] 'process raft request' (duration: 193.427815ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:04.423995Z","caller":"traceutil/trace.go:171","msg":"trace[374000719] linearizableReadLoop","detail":"{readStateIndex:28725; appliedIndex:28723; }","duration":"137.941222ms","start":"2026-09-23T04:15:04.285994Z","end":"2026-09-23T04:15:04.423935Z","steps":["trace[374000719] 'read index received' (duration: 63.318901ms)","trace[374000719] 'applied index is now lower than readState.Index' (duration: 74.621201ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:15:04.424024Z","caller":"traceutil/trace.go:171","msg":"trace[1040139262] transaction","detail":"{read_only:false; response_revision:27798; number_of_response:1; }","duration":"180.728819ms","start":"2026-09-23T04:15:04.243238Z","end":"2026-09-23T04:15:04.423967Z","steps":["trace[1040139262] 'process raft request' (duration: 106.050216ms)","trace[1040139262] 'compare' (duration: 74.451038ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:15:04.424014Z","caller":"traceutil/trace.go:171","msg":"trace[734929614] transaction","detail":"{read_only:false; response_revision:27799; number_of_response:1; }","duration":"169.195592ms","start":"2026-09-23T04:15:04.254775Z","end":"2026-09-23T04:15:04.42397Z","steps":["trace[734929614] 'process raft request' (duration: 169.1101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:15:04.424228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.257811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:15:04.424338Z","caller":"traceutil/trace.go:171","msg":"trace[497253001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27799; }","duration":"136.424615ms","start":"2026-09-23T04:15:04.287887Z","end":"2026-09-23T04:15:04.424312Z","steps":["trace[497253001] 'agreement among raft nodes before linearized reading' (duration: 136.258871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:15:04.424353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.848392ms","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-09-23T04:15:04.424414Z","caller":"traceutil/trace.go:171","msg":"trace[69045661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27799; }","duration":"160.990225ms","start":"2026-09-23T04:15:04.263407Z","end":"2026-09-23T04:15:04.424397Z","steps":["trace[69045661] 'agreement among raft nodes before linearized reading' (duration: 160.656947ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:04.788439Z","caller":"traceutil/trace.go:171","msg":"trace[2056231504] transaction","detail":"{read_only:false; response_revision:27801; number_of_response:1; }","duration":"171.203741ms","start":"2026-09-23T04:15:04.617195Z","end":"2026-09-23T04:15:04.788399Z","steps":["trace[2056231504] 'process raft request' (duration: 170.902633ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:04.831976Z","caller":"traceutil/trace.go:171","msg":"trace[1022446070] transaction","detail":"{read_only:false; response_revision:27802; number_of_response:1; }","duration":"213.174838ms","start":"2026-09-23T04:15:04.618776Z","end":"2026-09-23T04:15:04.831951Z","steps":["trace[1022446070] 'process raft request' (duration: 198.27515ms)","trace[1022446070] 'compare' (duration: 14.370776ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:15:10.375864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.346951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:15:10.376106Z","caller":"traceutil/trace.go:171","msg":"trace[1157701050] transaction","detail":"{read_only:false; response_revision:27843; number_of_response:1; }","duration":"140.007832ms","start":"2026-09-23T04:15:10.236079Z","end":"2026-09-23T04:15:10.376087Z","steps":["trace[1157701050] 'process raft request' (duration: 139.896029ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:10.376214Z","caller":"traceutil/trace.go:171","msg":"trace[223051219] transaction","detail":"{read_only:false; response_revision:27842; number_of_response:1; }","duration":"283.842875ms","start":"2026-09-23T04:15:10.092329Z","end":"2026-09-23T04:15:10.376172Z","steps":["trace[223051219] 'process raft request' (duration: 28.082374ms)","trace[223051219] 'compare' (duration: 255.070704ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:15:10.376227Z","caller":"traceutil/trace.go:171","msg":"trace[1319205093] linearizableReadLoop","detail":"{readStateIndex:28769; appliedIndex:28768; }","duration":"151.670841ms","start":"2026-09-23T04:15:10.224517Z","end":"2026-09-23T04:15:10.376187Z","steps":["trace[1319205093] 'read index received' (duration: 62.991µs)","trace[1319205093] 'applied index is now lower than readState.Index' (duration: 151.60252ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:15:10.376386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.846496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:15:10.376435Z","caller":"traceutil/trace.go:171","msg":"trace[1335262575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27843; }","duration":"151.978639ms","start":"2026-09-23T04:15:10.224441Z","end":"2026-09-23T04:15:10.376419Z","steps":["trace[1335262575] 'agreement among raft nodes before linearized reading' (duration: 151.860336ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:10.442756Z","caller":"traceutil/trace.go:171","msg":"trace[1457314228] transaction","detail":"{read_only:false; response_revision:27844; number_of_response:1; }","duration":"206.265992ms","start":"2026-09-23T04:15:10.236465Z","end":"2026-09-23T04:15:10.442731Z","steps":["trace[1457314228] 'process raft request' (duration: 206.082277ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:15:10.442956Z","caller":"traceutil/trace.go:171","msg":"trace[1460121529] transaction","detail":"{read_only:false; response_revision:27845; number_of_response:1; }","duration":"206.353564ms","start":"2026-09-23T04:15:10.236592Z","end":"2026-09-23T04:15:10.442945Z","steps":["trace[1460121529] 'process raft request' (duration: 206.092388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:15:10.443229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.774144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-23T04:15:10.443355Z","caller":"traceutil/trace.go:171","msg":"trace[1076439651] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27845; }","duration":"186.957218ms","start":"2026-09-23T04:15:10.25637Z","end":"2026-09-23T04:15:10.443327Z","steps":["trace[1076439651] 'agreement among raft nodes before linearized reading' (duration: 186.666591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:15:10.443235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.406769ms","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-09-23T04:15:10.44349Z","caller":"traceutil/trace.go:171","msg":"trace[959488689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27845; }","duration":"132.731947ms","start":"2026-09-23T04:15:10.310728Z","end":"2026-09-23T04:15:10.44346Z","steps":["trace[959488689] 'agreement among raft nodes before linearized reading' (duration: 132.288127ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:17:12.94891Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T04:17:12.956222Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-23T04:17:12.956409Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-23T04:18:38.429243Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27057} {"level":"info","ts":"2026-09-23T04:18:38.588396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27057,"took":"154.542289ms","hash":1923854495,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":36167680,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-23T04:18:38.588492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1923854495,"revision":27057,"compact-revision":23680} {"level":"info","ts":"2026-09-23T04:18:50.775245Z","caller":"traceutil/trace.go:171","msg":"trace[1367682492] transaction","detail":"{read_only:false; response_revision:30012; number_of_response:1; }","duration":"109.368166ms","start":"2026-09-23T04:18:50.665837Z","end":"2026-09-23T04:18:50.775205Z","steps":["trace[1367682492] 'process raft request' (duration: 61.292892ms)","trace[1367682492] 'compare' (duration: 47.884139ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:23:38.447963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29916} {"level":"info","ts":"2026-09-23T04:23:38.599283Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29916,"took":"145.83892ms","hash":3852352412,"current-db-size-bytes":60059648,"current-db-size":"60 MB","current-db-size-in-use-bytes":33255424,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-23T04:23:38.599379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3852352412,"revision":29916,"compact-revision":27057} {"level":"info","ts":"2026-09-23T04:28:38.455209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32309} {"level":"info","ts":"2026-09-23T04:28:38.626483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32309,"took":"166.881009ms","hash":2671145537,"current-db-size-bytes":61104128,"current-db-size":"61 MB","current-db-size-in-use-bytes":31694848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T04:28:38.626557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2671145537,"revision":32309,"compact-revision":29916} {"level":"info","ts":"2026-09-23T04:33:38.461988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34541} {"level":"info","ts":"2026-09-23T04:33:38.591642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34541,"took":"124.754667ms","hash":300326049,"current-db-size-bytes":61104128,"current-db-size":"61 MB","current-db-size-in-use-bytes":30539776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T04:33:38.591707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":300326049,"revision":34541,"compact-revision":32309} {"level":"info","ts":"2026-09-23T04:37:52.395239Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T04:37:52.401459Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-23T04:37:52.401573Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-23T04:38:38.468943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36769} {"level":"info","ts":"2026-09-23T04:38:38.597548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36769,"took":"118.518975ms","hash":1199235915,"current-db-size-bytes":61104128,"current-db-size":"61 MB","current-db-size-in-use-bytes":31969280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T04:38:38.597609Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1199235915,"revision":36769,"compact-revision":34541} {"level":"info","ts":"2026-09-23T04:43:38.475785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39111} {"level":"info","ts":"2026-09-23T04:43:38.603315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39111,"took":"123.454094ms","hash":1331799925,"current-db-size-bytes":62382080,"current-db-size":"62 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T04:43:38.603384Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1331799925,"revision":39111,"compact-revision":36769} {"level":"info","ts":"2026-09-23T04:48:38.483759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41344} {"level":"info","ts":"2026-09-23T04:48:38.608493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41344,"took":"120.999585ms","hash":997154022,"current-db-size-bytes":62382080,"current-db-size":"62 MB","current-db-size-in-use-bytes":30003200,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T04:48:38.608566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":997154022,"revision":41344,"compact-revision":39111} {"level":"info","ts":"2026-09-23T04:53:38.491262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43576} {"level":"info","ts":"2026-09-23T04:53:38.621563Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43576,"took":"126.326303ms","hash":251748064,"current-db-size-bytes":62382080,"current-db-size":"62 MB","current-db-size-in-use-bytes":29822976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T04:53:38.621654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":251748064,"revision":43576,"compact-revision":41344} {"level":"info","ts":"2026-09-23T04:58:38.501428Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":45857} {"level":"info","ts":"2026-09-23T04:58:38.644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":45857,"took":"139.119374ms","hash":3935702384,"current-db-size-bytes":62382080,"current-db-size":"62 MB","current-db-size-in-use-bytes":29704192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T04:58:38.64405Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3935702384,"revision":45857,"compact-revision":43576} {"level":"info","ts":"2026-09-23T04:59:35.163739Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T04:59:35.170049Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-09-23T04:59:35.170156Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}