{"level":"warn","ts":"2026-08-18T23:24:30.720883Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-18T23:24:30.720977Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-18T23:24:30.721044Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-18T23:24:30.721053Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-08-18T23:24:30.721074Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-18T23:24:30.721778Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-08-18T23:24:30.721869Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-18T23:24:30.725109Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.87448ms"} {"level":"info","ts":"2026-08-18T23:24:30.731399Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-08-18T23:24:30.731512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-08-18T23:24:30.731551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-08-18T23:24:30.731578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-18T23:24:30.731596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-08-18T23:24:30.731654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-08-18T23:24:30.7367Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-18T23:24:30.738419Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-18T23:24:30.739548Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-18T23:24:30.741693Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-18T23:24:30.74188Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-18T23:24:30.741981Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-18T23:24:30.742115Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-18T23:24:30.742134Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-18T23:24:30.743602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-08-18T23:24:30.743763Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-08-18T23:24:30.746466Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-18T23:24:30.746594Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-08-18T23:24:30.746651Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-08-18T23:24:30.746815Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-18T23:24:30.746878Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-18T23:24:31.232696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-08-18T23:24:31.232769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-18T23:24:31.232813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-08-18T23:24:31.232829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-08-18T23:24:31.232835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-08-18T23:24:31.232845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-08-18T23:24:31.232853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-08-18T23:24:31.287126Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:24:31.365992Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T23:24:31.366046Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T23:24:31.366Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-08-18T23:24:31.366417Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-18T23:24:31.366466Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-18T23:24:31.368647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-08-18T23:24:31.370398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-18T23:24:31.390136Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:24:31.390312Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:24:31.39036Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-08-18T23:25:12.214019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.431241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9nznn\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-18T23:25:12.214137Z","caller":"traceutil/trace.go:171","msg":"trace[345132302] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9nznn; range_end:; response_count:1; response_revision:583; }","duration":"311.607545ms","start":"2026-08-18T23:25:11.902507Z","end":"2026-08-18T23:25:12.214115Z","steps":["trace[345132302] 'range keys from in-memory index tree' (duration: 311.202956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:25:12.214192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:25:11.902492Z","time spent":"311.692457ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-9nznn\" "} {"level":"info","ts":"2026-08-18T23:25:12.214927Z","caller":"traceutil/trace.go:171","msg":"trace[636723138] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"192.051248ms","start":"2026-08-18T23:25:12.022851Z","end":"2026-08-18T23:25:12.214902Z","steps":["trace[636723138] 'read index received' (duration: 191.171666ms)","trace[636723138] 'applied index is now lower than readState.Index' (duration: 878.312µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:25:12.214977Z","caller":"traceutil/trace.go:171","msg":"trace[1832253500] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"262.507739ms","start":"2026-08-18T23:25:11.952452Z","end":"2026-08-18T23:25:12.21496Z","steps":["trace[1832253500] 'process raft request' (duration: 261.932104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:25:12.215138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.285484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-18T23:25:12.215178Z","caller":"traceutil/trace.go:171","msg":"trace[1746269543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"192.340335ms","start":"2026-08-18T23:25:12.022827Z","end":"2026-08-18T23:25:12.215167Z","steps":["trace[1746269543] 'agreement among raft nodes before linearized reading' (duration: 192.172231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:26:42.984577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.768934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0bc67cea-391b-44d8-958f-fe315078bb70\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-18T23:26:42.984675Z","caller":"traceutil/trace.go:171","msg":"trace[2011029192] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0bc67cea-391b-44d8-958f-fe315078bb70; range_end:; response_count:1; response_revision:1819; }","duration":"212.916667ms","start":"2026-08-18T23:26:42.771736Z","end":"2026-08-18T23:26:42.984653Z","steps":["trace[2011029192] 'range keys from in-memory index tree' (duration: 212.523028ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:26:42.997299Z","caller":"traceutil/trace.go:171","msg":"trace[1086985531] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"189.811898ms","start":"2026-08-18T23:26:42.807471Z","end":"2026-08-18T23:26:42.997283Z","steps":["trace[1086985531] 'process raft request' (duration: 189.712675ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:26:43.41804Z","caller":"traceutil/trace.go:171","msg":"trace[1072524256] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"201.661084ms","start":"2026-08-18T23:26:43.216349Z","end":"2026-08-18T23:26:43.41801Z","steps":["trace[1072524256] 'process raft request' (duration: 201.48229ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:27:27.250236Z","caller":"traceutil/trace.go:171","msg":"trace[1985486968] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"101.897713ms","start":"2026-08-18T23:27:27.148309Z","end":"2026-08-18T23:27:27.250207Z","steps":["trace[1985486968] 'process raft request' (duration: 101.700329ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:27:33.021231Z","caller":"traceutil/trace.go:171","msg":"trace[1390013222] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"118.436833ms","start":"2026-08-18T23:27:32.90276Z","end":"2026-08-18T23:27:33.021197Z","steps":["trace[1390013222] 'process raft request' (duration: 118.254749ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:27:33.390744Z","caller":"traceutil/trace.go:171","msg":"trace[873486170] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"119.15267ms","start":"2026-08-18T23:27:33.271567Z","end":"2026-08-18T23:27:33.390719Z","steps":["trace[873486170] 'process raft request' (duration: 119.006057ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:28:22.501592Z","caller":"traceutil/trace.go:171","msg":"trace[202487471] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"139.051881ms","start":"2026-08-18T23:28:22.362517Z","end":"2026-08-18T23:28:22.501569Z","steps":["trace[202487471] 'process raft request' (duration: 138.885397ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:28:56.726328Z","caller":"traceutil/trace.go:171","msg":"trace[2051003928] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"108.990186ms","start":"2026-08-18T23:28:56.617292Z","end":"2026-08-18T23:28:56.726283Z","steps":["trace[2051003928] 'process raft request' (duration: 108.837832ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:32:39.22975Z","caller":"traceutil/trace.go:171","msg":"trace[954590495] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"145.619282ms","start":"2026-08-18T23:32:39.084074Z","end":"2026-08-18T23:32:39.229693Z","steps":["trace[954590495] 'process raft request' (duration: 145.395756ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:32:50.708304Z","caller":"traceutil/trace.go:171","msg":"trace[783006573] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"108.204077ms","start":"2026-08-18T23:32:50.600053Z","end":"2026-08-18T23:32:50.708257Z","steps":["trace[783006573] 'process raft request' (duration: 107.972911ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:32:58.956958Z","caller":"traceutil/trace.go:171","msg":"trace[462760495] transaction","detail":"{read_only:false; response_revision:5354; number_of_response:1; }","duration":"105.206532ms","start":"2026-08-18T23:32:58.851714Z","end":"2026-08-18T23:32:58.956921Z","steps":["trace[462760495] 'process raft request' (duration: 62.967159ms)","trace[462760495] 'compare' (duration: 42.072359ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:34:31.39383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-08-18T23:34:31.478258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"82.873673ms","hash":2871758765,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":34865152,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-18T23:34:31.478344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2871758765,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-08-18T23:38:57.497554Z","caller":"traceutil/trace.go:171","msg":"trace[1842716282] transaction","detail":"{read_only:false; response_revision:8120; number_of_response:1; }","duration":"238.156765ms","start":"2026-08-18T23:38:57.259364Z","end":"2026-08-18T23:38:57.49752Z","steps":["trace[1842716282] 'process raft request' (duration: 237.93631ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:08.236181Z","caller":"traceutil/trace.go:171","msg":"trace[1599019113] transaction","detail":"{read_only:false; response_revision:8176; number_of_response:1; }","duration":"216.514176ms","start":"2026-08-18T23:39:08.01965Z","end":"2026-08-18T23:39:08.236165Z","steps":["trace[1599019113] 'process raft request' (duration: 216.403754ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:08.243386Z","caller":"traceutil/trace.go:171","msg":"trace[950544407] linearizableReadLoop","detail":"{readStateIndex:8466; appliedIndex:8465; }","duration":"129.645397ms","start":"2026-08-18T23:39:08.113718Z","end":"2026-08-18T23:39:08.243363Z","steps":["trace[950544407] 'read index received' (duration: 122.431504ms)","trace[950544407] 'applied index is now lower than readState.Index' (duration: 7.212953ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:39:08.243507Z","caller":"traceutil/trace.go:171","msg":"trace[548623360] transaction","detail":"{read_only:false; response_revision:8177; number_of_response:1; }","duration":"142.107916ms","start":"2026-08-18T23:39:08.101363Z","end":"2026-08-18T23:39:08.243471Z","steps":["trace[548623360] 'process raft request' (duration: 141.845519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:39:08.243594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.865062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-18T23:39:08.243631Z","caller":"traceutil/trace.go:171","msg":"trace[53317055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8177; }","duration":"129.942704ms","start":"2026-08-18T23:39:08.113677Z","end":"2026-08-18T23:39:08.243619Z","steps":["trace[53317055] 'agreement among raft nodes before linearized reading' (duration: 129.78261ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:09.022272Z","caller":"traceutil/trace.go:171","msg":"trace[46349412] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"163.290525ms","start":"2026-08-18T23:39:08.858954Z","end":"2026-08-18T23:39:09.022245Z","steps":["trace[46349412] 'process raft request' (duration: 143.654583ms)","trace[46349412] 'compare' (duration: 19.419677ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:39:31.415883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6149} {"level":"info","ts":"2026-08-18T23:39:31.519676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6149,"took":"101.716145ms","hash":4107362811,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":23363584,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-18T23:39:31.519731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4107362811,"revision":6149,"compact-revision":2978} {"level":"warn","ts":"2026-08-18T23:39:57.408942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.614147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-18T23:39:57.409137Z","caller":"traceutil/trace.go:171","msg":"trace[3271673] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8534; }","duration":"228.235314ms","start":"2026-08-18T23:39:57.18088Z","end":"2026-08-18T23:39:57.409115Z","steps":["trace[3271673] 'process raft request' (duration: 114.670935ms)","trace[3271673] 'compare' (duration: 111.992491ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:39:57.411239Z","caller":"traceutil/trace.go:171","msg":"trace[1941494268] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"209.834088ms","start":"2026-08-18T23:39:57.201379Z","end":"2026-08-18T23:39:57.411213Z","steps":["trace[1941494268] 'process raft request' (duration: 207.676846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:39:57.411452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.462573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-cvc5g\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-08-18T23:39:57.411531Z","caller":"traceutil/trace.go:171","msg":"trace[829183572] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-cvc5g; range_end:; response_count:1; response_revision:8535; }","duration":"205.593677ms","start":"2026-08-18T23:39:57.205917Z","end":"2026-08-18T23:39:57.41151Z","steps":["trace[829183572] 'agreement among raft nodes before linearized reading' (duration: 205.364521ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:57.411145Z","caller":"traceutil/trace.go:171","msg":"trace[1141229437] linearizableReadLoop","detail":"{readStateIndex:8840; appliedIndex:8838; }","duration":"205.158946ms","start":"2026-08-18T23:39:57.205971Z","end":"2026-08-18T23:39:57.41113Z","steps":["trace[1141229437] 'read index received' (duration: 89.472566ms)","trace[1141229437] 'applied index is now lower than readState.Index' (duration: 115.6854ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:39:57.41218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.657609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-18T23:39:57.412266Z","caller":"traceutil/trace.go:171","msg":"trace[956625301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8535; }","duration":"165.767722ms","start":"2026-08-18T23:39:57.246474Z","end":"2026-08-18T23:39:57.412241Z","steps":["trace[956625301] 'agreement among raft nodes before linearized reading' (duration: 165.547157ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:57.551155Z","caller":"traceutil/trace.go:171","msg":"trace[1245491638] linearizableReadLoop","detail":"{readStateIndex:8841; appliedIndex:8840; }","duration":"134.550267ms","start":"2026-08-18T23:39:57.416583Z","end":"2026-08-18T23:39:57.551133Z","steps":["trace[1245491638] 'read index received' (duration: 120.403594ms)","trace[1245491638] 'applied index is now lower than readState.Index' (duration: 14.145903ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:39:57.551305Z","caller":"traceutil/trace.go:171","msg":"trace[1674553068] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"135.456249ms","start":"2026-08-18T23:39:57.415807Z","end":"2026-08-18T23:39:57.551264Z","steps":["trace[1674553068] 'process raft request' (duration: 121.211694ms)","trace[1674553068] 'compare' (duration: 13.994489ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:39:57.551357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.747001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-cvc5g\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-08-18T23:39:57.551395Z","caller":"traceutil/trace.go:171","msg":"trace[813815550] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-cvc5g; range_end:; response_count:1; response_revision:8536; }","duration":"134.833513ms","start":"2026-08-18T23:39:57.416554Z","end":"2026-08-18T23:39:57.551387Z","steps":["trace[813815550] 'agreement among raft nodes before linearized reading' (duration: 134.662939ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:58.050128Z","caller":"traceutil/trace.go:171","msg":"trace[142613342] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8843; }","duration":"135.49142ms","start":"2026-08-18T23:39:57.914606Z","end":"2026-08-18T23:39:58.050097Z","steps":["trace[142613342] 'read index received' (duration: 70.59745ms)","trace[142613342] 'applied index is now lower than readState.Index' (duration: 64.89246ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:39:58.050164Z","caller":"traceutil/trace.go:171","msg":"trace[1905496085] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"158.287511ms","start":"2026-08-18T23:39:57.891846Z","end":"2026-08-18T23:39:58.050133Z","steps":["trace[1905496085] 'process raft request' (duration: 158.120527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:39:58.050392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.761446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ksfz4\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-18T23:39:58.050432Z","caller":"traceutil/trace.go:171","msg":"trace[1261886191] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ksfz4; range_end:; response_count:1; response_revision:8540; }","duration":"135.858309ms","start":"2026-08-18T23:39:57.914563Z","end":"2026-08-18T23:39:58.050421Z","steps":["trace[1261886191] 'agreement among raft nodes before linearized reading' (duration: 135.678805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:39:58.305926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.094723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:39:58.306127Z","caller":"traceutil/trace.go:171","msg":"trace[1830932231] linearizableReadLoop","detail":"{readStateIndex:8847; appliedIndex:8845; }","duration":"250.875451ms","start":"2026-08-18T23:39:58.055164Z","end":"2026-08-18T23:39:58.30604Z","steps":["trace[1830932231] 'read index received' (duration: 8.196607ms)","trace[1830932231] 'applied index is now lower than readState.Index' (duration: 242.676694ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:39:58.306316Z","caller":"traceutil/trace.go:171","msg":"trace[1123180501] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"251.252451ms","start":"2026-08-18T23:39:58.055028Z","end":"2026-08-18T23:39:58.306281Z","steps":["trace[1123180501] 'process raft request' (duration: 26.476031ms)","trace[1123180501] 'compare' (duration: 223.96005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:39:58.306748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.569117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228764"} {"level":"info","ts":"2026-08-18T23:39:58.306811Z","caller":"traceutil/trace.go:171","msg":"trace[583660586] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8542; }","duration":"251.65272ms","start":"2026-08-18T23:39:58.055143Z","end":"2026-08-18T23:39:58.306796Z","steps":["trace[583660586] 'agreement among raft nodes before linearized reading' (duration: 251.074736ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:58.310501Z","caller":"traceutil/trace.go:171","msg":"trace[2001021212] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"143.689248ms","start":"2026-08-18T23:39:58.166796Z","end":"2026-08-18T23:39:58.310485Z","steps":["trace[2001021212] 'process raft request' (duration: 143.568535ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:58.804962Z","caller":"traceutil/trace.go:171","msg":"trace[389902792] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"151.121108ms","start":"2026-08-18T23:39:58.65348Z","end":"2026-08-18T23:39:58.804602Z","steps":["trace[389902792] 'process raft request' (duration: 150.938774ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:39:58.935338Z","caller":"traceutil/trace.go:171","msg":"trace[721693430] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"116.448929ms","start":"2026-08-18T23:39:58.81886Z","end":"2026-08-18T23:39:58.935309Z","steps":["trace[721693430] 'process raft request' (duration: 28.349026ms)","trace[721693430] 'compare' (duration: 87.965729ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:40:32.675241Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ed.wal"} {"level":"info","ts":"2026-08-18T23:40:58.776431Z","caller":"traceutil/trace.go:171","msg":"trace[384851827] linearizableReadLoop","detail":"{readStateIndex:9502; appliedIndex:9500; }","duration":"136.044173ms","start":"2026-08-18T23:40:58.640364Z","end":"2026-08-18T23:40:58.776408Z","steps":["trace[384851827] 'read index received' (duration: 846.671µs)","trace[384851827] 'applied index is now lower than readState.Index' (duration: 135.196662ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:40:58.776566Z","caller":"traceutil/trace.go:171","msg":"trace[1166369034] transaction","detail":"{read_only:false; response_revision:9180; number_of_response:1; }","duration":"197.243273ms","start":"2026-08-18T23:40:58.579309Z","end":"2026-08-18T23:40:58.776552Z","steps":["trace[1166369034] 'process raft request' (duration: 146.379172ms)","trace[1166369034] 'compare' (duration: 50.42446ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:40:58.776565Z","caller":"traceutil/trace.go:171","msg":"trace[162936404] transaction","detail":"{read_only:false; response_revision:9181; number_of_response:1; }","duration":"123.527939ms","start":"2026-08-18T23:40:58.65301Z","end":"2026-08-18T23:40:58.776538Z","steps":["trace[162936404] 'process raft request' (duration: 123.321284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:40:58.776862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.479903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:40:58.776925Z","caller":"traceutil/trace.go:171","msg":"trace[499897406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9181; }","duration":"136.611026ms","start":"2026-08-18T23:40:58.640298Z","end":"2026-08-18T23:40:58.776909Z","steps":["trace[499897406] 'agreement among raft nodes before linearized reading' (duration: 136.468413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:40:58.776998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.281533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ksfz4\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-18T23:40:58.777079Z","caller":"traceutil/trace.go:171","msg":"trace[579959656] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ksfz4; range_end:; response_count:1; response_revision:9181; }","duration":"135.401037ms","start":"2026-08-18T23:40:58.641659Z","end":"2026-08-18T23:40:58.77706Z","steps":["trace[579959656] 'agreement among raft nodes before linearized reading' (duration: 135.11057ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:42:04.114753Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-18T23:42:04.119495Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-18T23:42:04.119587Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-18T23:43:24.795415Z","caller":"traceutil/trace.go:171","msg":"trace[2128087423] transaction","detail":"{read_only:false; response_revision:10299; number_of_response:1; }","duration":"101.858927ms","start":"2026-08-18T23:43:24.69352Z","end":"2026-08-18T23:43:24.795378Z","steps":["trace[2128087423] 'process raft request' (duration: 101.638572ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:44:31.421798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8309} {"level":"info","ts":"2026-08-18T23:44:31.495112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8309,"took":"71.661121ms","hash":413671271,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":26742784,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-18T23:44:31.495223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":413671271,"revision":8309,"compact-revision":6149} {"level":"info","ts":"2026-08-18T23:44:49.538088Z","caller":"traceutil/trace.go:171","msg":"trace[736786244] transaction","detail":"{read_only:false; response_revision:11091; number_of_response:1; }","duration":"100.914647ms","start":"2026-08-18T23:44:49.437148Z","end":"2026-08-18T23:44:49.538063Z","steps":["trace[736786244] 'process raft request' (duration: 100.743073ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:44:49.616393Z","caller":"traceutil/trace.go:171","msg":"trace[1979444684] linearizableReadLoop","detail":"{readStateIndex:11471; appliedIndex:11469; }","duration":"109.388621ms","start":"2026-08-18T23:44:49.506976Z","end":"2026-08-18T23:44:49.616365Z","steps":["trace[1979444684] 'read index received' (duration: 30.846775ms)","trace[1979444684] 'applied index is now lower than readState.Index' (duration: 78.540856ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:44:49.616563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.568455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-18T23:44:49.61661Z","caller":"traceutil/trace.go:171","msg":"trace[1129961] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:11092; }","duration":"109.669648ms","start":"2026-08-18T23:44:49.506924Z","end":"2026-08-18T23:44:49.616593Z","steps":["trace[1129961] 'agreement among raft nodes before linearized reading' (duration: 109.540675ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:44:49.616669Z","caller":"traceutil/trace.go:171","msg":"trace[1847729602] transaction","detail":"{read_only:false; response_revision:11092; number_of_response:1; }","duration":"178.857748ms","start":"2026-08-18T23:44:49.437705Z","end":"2026-08-18T23:44:49.616563Z","steps":["trace[1847729602] 'process raft request' (duration: 178.52814ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:44:49.682723Z","caller":"traceutil/trace.go:171","msg":"trace[848011893] transaction","detail":"{read_only:false; response_revision:11094; number_of_response:1; }","duration":"134.964659ms","start":"2026-08-18T23:44:49.547734Z","end":"2026-08-18T23:44:49.682698Z","steps":["trace[848011893] 'process raft request' (duration: 134.906917ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:44:49.682719Z","caller":"traceutil/trace.go:171","msg":"trace[1515687531] transaction","detail":"{read_only:false; response_revision:11093; number_of_response:1; }","duration":"135.696536ms","start":"2026-08-18T23:44:49.546995Z","end":"2026-08-18T23:44:49.682691Z","steps":["trace[1515687531] 'process raft request' (duration: 135.519512ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:44:49.796769Z","caller":"traceutil/trace.go:171","msg":"trace[2129483550] transaction","detail":"{read_only:false; response_revision:11095; number_of_response:1; }","duration":"107.031065ms","start":"2026-08-18T23:44:49.689707Z","end":"2026-08-18T23:44:49.796738Z","steps":["trace[2129483550] 'process raft request' (duration: 106.729927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:44:49.834411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.369933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-18T23:44:49.834497Z","caller":"traceutil/trace.go:171","msg":"trace[1335171341] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11095; }","duration":"136.508076ms","start":"2026-08-18T23:44:49.697965Z","end":"2026-08-18T23:44:49.834473Z","steps":["trace[1335171341] 'agreement among raft nodes before linearized reading' (duration: 98.837147ms)","trace[1335171341] 'count revisions from in-memory index tree' (duration: 37.536076ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:49:31.427321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10990} {"level":"info","ts":"2026-08-18T23:49:31.508252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10990,"took":"77.865553ms","hash":4019151356,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":28356608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-18T23:49:31.508313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4019151356,"revision":10990,"compact-revision":8309} {"level":"warn","ts":"2026-08-18T23:51:44.789018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.317504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-18T23:51:44.789152Z","caller":"traceutil/trace.go:171","msg":"trace[1252362911] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:13833; }","duration":"313.513269ms","start":"2026-08-18T23:51:44.475615Z","end":"2026-08-18T23:51:44.789128Z","steps":["trace[1252362911] 'count revisions from in-memory index tree' (duration: 313.235402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:51:44.789225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:51:44.475599Z","time spent":"313.59606ms","remote":"127.0.0.1:34242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":66,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-18T23:51:44.789201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.79148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:51:44.789354Z","caller":"traceutil/trace.go:171","msg":"trace[2140246754] linearizableReadLoop","detail":"{readStateIndex:14312; appliedIndex:14311; }","duration":"173.373862ms","start":"2026-08-18T23:51:44.615953Z","end":"2026-08-18T23:51:44.789327Z","steps":["trace[2140246754] 'read index received' (duration: 21.374526ms)","trace[2140246754] 'applied index is now lower than readState.Index' (duration: 151.997205ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:51:44.789568Z","caller":"traceutil/trace.go:171","msg":"trace[1875804198] transaction","detail":"{read_only:false; response_revision:13834; number_of_response:1; }","duration":"261.356929ms","start":"2026-08-18T23:51:44.528153Z","end":"2026-08-18T23:51:44.78951Z","steps":["trace[1875804198] 'process raft request' (duration: 109.154929ms)","trace[1875804198] 'compare' (duration: 151.40442ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:51:44.789619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.647909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:51:44.789696Z","caller":"traceutil/trace.go:171","msg":"trace[353019877] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:13834; }","duration":"173.779851ms","start":"2026-08-18T23:51:44.615895Z","end":"2026-08-18T23:51:44.789675Z","steps":["trace[353019877] 'agreement among raft nodes before linearized reading' (duration: 173.580256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:51:44.789606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.785587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:51:44.789831Z","caller":"traceutil/trace.go:171","msg":"trace[1373643581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13834; }","duration":"149.032353ms","start":"2026-08-18T23:51:44.640749Z","end":"2026-08-18T23:51:44.789781Z","steps":["trace[1373643581] 'agreement among raft nodes before linearized reading' (duration: 148.736806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:52:05.938695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.644625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:52:05.938963Z","caller":"traceutil/trace.go:171","msg":"trace[163817398] linearizableReadLoop","detail":"{readStateIndex:14632; appliedIndex:14630; }","duration":"299.45603ms","start":"2026-08-18T23:52:05.639488Z","end":"2026-08-18T23:52:05.938944Z","steps":["trace[163817398] 'read index received' (duration: 78.472937ms)","trace[163817398] 'applied index is now lower than readState.Index' (duration: 220.982133ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:52:05.93895Z","caller":"traceutil/trace.go:171","msg":"trace[979127897] transaction","detail":"{read_only:false; response_revision:14138; number_of_response:1; }","duration":"302.465602ms","start":"2026-08-18T23:52:05.636456Z","end":"2026-08-18T23:52:05.938921Z","steps":["trace[979127897] 'process raft request' (duration: 81.492ms)","trace[979127897] 'compare' (duration: 220.44689ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:52:05.939159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:52:05.636431Z","time spent":"302.602866ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:52:05.939211Z","caller":"traceutil/trace.go:171","msg":"trace[1174478754] transaction","detail":"{read_only:false; response_revision:14139; number_of_response:1; }","duration":"301.793516ms","start":"2026-08-18T23:52:05.637403Z","end":"2026-08-18T23:52:05.939196Z","steps":["trace[1174478754] 'process raft request' (duration: 301.467538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:52:05.93926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.756858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:52:05.939284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:52:05.637387Z","time spent":"301.856897ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:52:05.939341Z","caller":"traceutil/trace.go:171","msg":"trace[1993151309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14139; }","duration":"299.8541ms","start":"2026-08-18T23:52:05.639469Z","end":"2026-08-18T23:52:05.939323Z","steps":["trace[1993151309] 'agreement among raft nodes before linearized reading' (duration: 299.706026ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:52:57.233533Z","caller":"traceutil/trace.go:171","msg":"trace[197784899] transaction","detail":"{read_only:false; response_revision:14766; number_of_response:1; }","duration":"111.979128ms","start":"2026-08-18T23:52:57.121525Z","end":"2026-08-18T23:52:57.233504Z","steps":["trace[197784899] 'process raft request' (duration: 109.5976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:53:24.56025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.817661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:53:24.560373Z","caller":"traceutil/trace.go:171","msg":"trace[1891524425] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14907; }","duration":"395.982735ms","start":"2026-08-18T23:53:24.164371Z","end":"2026-08-18T23:53:24.560353Z","steps":["trace[1891524425] 'range keys from in-memory index tree' (duration: 395.738339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:53:24.560413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:53:24.164353Z","time spent":"396.051897ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-18T23:53:24.560429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.323112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-18T23:53:24.560469Z","caller":"traceutil/trace.go:171","msg":"trace[679339851] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14907; }","duration":"386.374093ms","start":"2026-08-18T23:53:24.174084Z","end":"2026-08-18T23:53:24.560458Z","steps":["trace[679339851] 'range keys from in-memory index tree' (duration: 386.241939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:53:24.560466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.651621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-18T23:53:24.560495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:53:24.174078Z","time spent":"386.411173ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-08-18T23:53:24.560513Z","caller":"traceutil/trace.go:171","msg":"trace[181155352] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14907; }","duration":"391.725102ms","start":"2026-08-18T23:53:24.168774Z","end":"2026-08-18T23:53:24.560499Z","steps":["trace[181155352] 'range keys from in-memory index tree' (duration: 391.562698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:53:24.560551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:53:24.168769Z","time spent":"391.775274ms","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-18T23:53:24.560573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.251571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-k7qmf\" ","response":"range_response_count:1 size:9798"} {"level":"warn","ts":"2026-08-18T23:53:24.560573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.623274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ksfz4\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-18T23:53:24.560637Z","caller":"traceutil/trace.go:171","msg":"trace[1391430286] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-k7qmf; range_end:; response_count:1; response_revision:14907; }","duration":"367.336754ms","start":"2026-08-18T23:53:24.193286Z","end":"2026-08-18T23:53:24.560623Z","steps":["trace[1391430286] 'range keys from in-memory index tree' (duration: 367.140289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:53:24.560648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.029977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:53:24.560647Z","caller":"traceutil/trace.go:171","msg":"trace[764646684] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ksfz4; range_end:; response_count:1; response_revision:14907; }","duration":"380.714006ms","start":"2026-08-18T23:53:24.179916Z","end":"2026-08-18T23:53:24.56063Z","steps":["trace[764646684] 'range keys from in-memory index tree' (duration: 380.511791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:53:24.560667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:53:24.19328Z","time spent":"367.381185ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9821,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-k7qmf\" "} {"level":"warn","ts":"2026-08-18T23:53:24.560685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:53:24.179911Z","time spent":"380.765677ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-ksfz4\" "} {"level":"info","ts":"2026-08-18T23:53:24.560691Z","caller":"traceutil/trace.go:171","msg":"trace[310749153] linearizableReadLoop","detail":"{readStateIndex:15421; appliedIndex:15420; }","duration":"261.034993ms","start":"2026-08-18T23:53:24.299647Z","end":"2026-08-18T23:53:24.560682Z","steps":["trace[310749153] 'read index received' (duration: 23.261µs)","trace[310749153] 'applied index is now lower than readState.Index' (duration: 261.010882ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:53:24.560775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.127235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-18T23:53:24.560822Z","caller":"traceutil/trace.go:171","msg":"trace[1406595669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14908; }","duration":"261.199266ms","start":"2026-08-18T23:53:24.299616Z","end":"2026-08-18T23:53:24.560815Z","steps":["trace[1406595669] 'agreement among raft nodes before linearized reading' (duration: 261.088434ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:53:24.560856Z","caller":"traceutil/trace.go:171","msg":"trace[1439701721] transaction","detail":"{read_only:false; response_revision:14908; number_of_response:1; }","duration":"359.124695ms","start":"2026-08-18T23:53:24.201716Z","end":"2026-08-18T23:53:24.560841Z","steps":["trace[1439701721] 'process raft request' (duration: 84.850282ms)","trace[1439701721] 'compare' (duration: 273.579975ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:53:24.560926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.412651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-18T23:53:24.560924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.88431ms","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":"warn","ts":"2026-08-18T23:53:24.560931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:53:24.201702Z","time spent":"359.190216ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:53:24.560954Z","caller":"traceutil/trace.go:171","msg":"trace[1424510146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14908; }","duration":"256.445801ms","start":"2026-08-18T23:53:24.304499Z","end":"2026-08-18T23:53:24.560945Z","steps":["trace[1424510146] 'agreement among raft nodes before linearized reading' (duration: 256.38716ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:53:24.560964Z","caller":"traceutil/trace.go:171","msg":"trace[1203268149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14908; }","duration":"253.92997ms","start":"2026-08-18T23:53:24.30702Z","end":"2026-08-18T23:53:24.56095Z","steps":["trace[1203268149] 'agreement among raft nodes before linearized reading' (duration: 253.831398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:53:24.560872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.086305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-18T23:53:24.561015Z","caller":"traceutil/trace.go:171","msg":"trace[111307145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14908; }","duration":"259.233369ms","start":"2026-08-18T23:53:24.301769Z","end":"2026-08-18T23:53:24.561002Z","steps":["trace[111307145] 'agreement among raft nodes before linearized reading' (duration: 259.047934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:53:24.561023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.196899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-18T23:53:24.561101Z","caller":"traceutil/trace.go:171","msg":"trace[933539772] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:14908; }","duration":"190.279421ms","start":"2026-08-18T23:53:24.3708Z","end":"2026-08-18T23:53:24.561079Z","steps":["trace[933539772] 'agreement among raft nodes before linearized reading' (duration: 190.077287ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:54:31.435028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13029} {"level":"info","ts":"2026-08-18T23:54:31.515253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13029,"took":"77.35131ms","hash":2282219825,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-18T23:54:31.515327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2282219825,"revision":13029,"compact-revision":10990} {"level":"warn","ts":"2026-08-18T23:59:12.42826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.529144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-k7qmf\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-08-18T23:59:12.428539Z","caller":"traceutil/trace.go:171","msg":"trace[995024138] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-k7qmf; range_end:; response_count:1; response_revision:17494; }","duration":"103.741979ms","start":"2026-08-18T23:59:12.324662Z","end":"2026-08-18T23:59:12.428404Z","steps":["trace[995024138] 'agreement among raft nodes before linearized reading' (duration: 103.427272ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:59:12.89473Z","caller":"traceutil/trace.go:171","msg":"trace[1378128695] transaction","detail":"{read_only:false; response_revision:17498; number_of_response:1; }","duration":"244.265739ms","start":"2026-08-18T23:59:12.650439Z","end":"2026-08-18T23:59:12.894705Z","steps":["trace[1378128695] 'process raft request' (duration: 242.692831ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:59:13.110814Z","caller":"traceutil/trace.go:171","msg":"trace[2002206216] transaction","detail":"{read_only:false; response_revision:17499; number_of_response:1; }","duration":"125.25488ms","start":"2026-08-18T23:59:12.985538Z","end":"2026-08-18T23:59:13.110793Z","steps":["trace[2002206216] 'process raft request' (duration: 125.133907ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:59:31.441059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15349} {"level":"info","ts":"2026-08-18T23:59:31.531155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15349,"took":"87.66601ms","hash":3518863841,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":32600064,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-18T23:59:31.531205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3518863841,"revision":15349,"compact-revision":13029} {"level":"info","ts":"2026-08-19T00:02:43.263652Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T00:02:43.26858Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-19T00:02:43.268821Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-19T00:04:31.450574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17754} {"level":"info","ts":"2026-08-19T00:04:31.546338Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17754,"took":"92.349496ms","hash":2163048375,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":34349056,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-19T00:04:31.546409Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2163048375,"revision":17754,"compact-revision":15349} {"level":"info","ts":"2026-08-19T00:04:51.081026Z","caller":"traceutil/trace.go:171","msg":"trace[1807909964] transaction","detail":"{read_only:false; response_revision:20241; number_of_response:1; }","duration":"114.565674ms","start":"2026-08-19T00:04:50.966434Z","end":"2026-08-19T00:04:51.081Z","steps":["trace[1807909964] 'process raft request' (duration: 114.41418ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:04:56.835691Z","caller":"traceutil/trace.go:171","msg":"trace[1764366943] transaction","detail":"{read_only:false; response_revision:20270; number_of_response:1; }","duration":"137.399557ms","start":"2026-08-19T00:04:56.698264Z","end":"2026-08-19T00:04:56.835663Z","steps":["trace[1764366943] 'process raft request' (duration: 137.196932ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:04:58.038065Z","caller":"traceutil/trace.go:171","msg":"trace[1795049960] transaction","detail":"{read_only:false; response_revision:20274; number_of_response:1; }","duration":"146.549157ms","start":"2026-08-19T00:04:57.891486Z","end":"2026-08-19T00:04:58.038035Z","steps":["trace[1795049960] 'process raft request' (duration: 136.71663ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:04:58.038091Z","caller":"traceutil/trace.go:171","msg":"trace[1162812471] transaction","detail":"{read_only:false; response_revision:20275; number_of_response:1; }","duration":"135.253064ms","start":"2026-08-19T00:04:57.902815Z","end":"2026-08-19T00:04:58.038068Z","steps":["trace[1162812471] 'process raft request' (duration: 135.138051ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:09:31.458473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20137} {"level":"info","ts":"2026-08-19T00:09:31.564173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20137,"took":"102.06912ms","hash":4094469383,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":43020288,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-19T00:09:31.564247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4094469383,"revision":20137,"compact-revision":17754} {"level":"info","ts":"2026-08-19T00:12:05.197699Z","caller":"traceutil/trace.go:171","msg":"trace[1050570731] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25612; }","duration":"113.028536ms","start":"2026-08-19T00:12:05.084647Z","end":"2026-08-19T00:12:05.197676Z","steps":["trace[1050570731] 'read index received' (duration: 112.857562ms)","trace[1050570731] 'applied index is now lower than readState.Index' (duration: 169.904µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:05.197877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.213891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:05.197936Z","caller":"traceutil/trace.go:171","msg":"trace[647311927] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24802; }","duration":"113.340864ms","start":"2026-08-19T00:12:05.084576Z","end":"2026-08-19T00:12:05.197917Z","steps":["trace[647311927] 'agreement among raft nodes before linearized reading' (duration: 113.208671ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:05.198355Z","caller":"traceutil/trace.go:171","msg":"trace[858543192] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"132.284453ms","start":"2026-08-19T00:12:05.066056Z","end":"2026-08-19T00:12:05.198341Z","steps":["trace[858543192] 'process raft request' (duration: 131.471363ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:09.099534Z","caller":"traceutil/trace.go:171","msg":"trace[2002256611] linearizableReadLoop","detail":"{readStateIndex:25640; appliedIndex:25639; }","duration":"176.741509ms","start":"2026-08-19T00:12:08.922755Z","end":"2026-08-19T00:12:09.099496Z","steps":["trace[2002256611] 'read index received' (duration: 176.581955ms)","trace[2002256611] 'applied index is now lower than readState.Index' (duration: 158.604µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:09.09965Z","caller":"traceutil/trace.go:171","msg":"trace[1555496689] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"177.145079ms","start":"2026-08-19T00:12:08.922472Z","end":"2026-08-19T00:12:09.099617Z","steps":["trace[1555496689] 'process raft request' (duration: 176.882592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:09.099696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.918653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:09.09982Z","caller":"traceutil/trace.go:171","msg":"trace[1458739298] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24829; }","duration":"177.062327ms","start":"2026-08-19T00:12:08.922734Z","end":"2026-08-19T00:12:09.099796Z","steps":["trace[1458739298] 'agreement among raft nodes before linearized reading' (duration: 176.888273ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:09.34129Z","caller":"traceutil/trace.go:171","msg":"trace[210162513] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"130.595132ms","start":"2026-08-19T00:12:09.210653Z","end":"2026-08-19T00:12:09.341248Z","steps":["trace[210162513] 'process raft request' (duration: 60.383251ms)","trace[210162513] 'compare' (duration: 70.097808ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:09.868392Z","caller":"traceutil/trace.go:171","msg":"trace[618499620] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"220.55697ms","start":"2026-08-19T00:12:09.647801Z","end":"2026-08-19T00:12:09.868358Z","steps":["trace[618499620] 'process raft request' (duration: 139.014305ms)","trace[618499620] 'compare' (duration: 80.705634ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:09.868428Z","caller":"traceutil/trace.go:171","msg":"trace[627240458] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"220.462247ms","start":"2026-08-19T00:12:09.647922Z","end":"2026-08-19T00:12:09.868385Z","steps":["trace[627240458] 'process raft request' (duration: 219.76608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:10.000429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.671502ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-19T00:12:10.000766Z","caller":"traceutil/trace.go:171","msg":"trace[1055667440] linearizableReadLoop","detail":"{readStateIndex:25646; appliedIndex:25643; }","duration":"313.467669ms","start":"2026-08-19T00:12:09.687276Z","end":"2026-08-19T00:12:10.000744Z","steps":["trace[1055667440] 'read index received' (duration: 99.52472ms)","trace[1055667440] 'applied index is now lower than readState.Index' (duration: 213.941639ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:10.001132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.947334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:10.001224Z","caller":"traceutil/trace.go:171","msg":"trace[405764144] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:24834; }","duration":"360.078987ms","start":"2026-08-19T00:12:09.641124Z","end":"2026-08-19T00:12:10.001203Z","steps":["trace[405764144] 'agreement among raft nodes before linearized reading' (duration: 359.941494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:10.001268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:09.641107Z","time spent":"360.150238ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-08-19T00:12:10.002155Z","caller":"traceutil/trace.go:171","msg":"trace[1547530130] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"240.359199ms","start":"2026-08-19T00:12:09.761783Z","end":"2026-08-19T00:12:10.002142Z","steps":["trace[1547530130] 'process raft request' (duration: 238.75979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:10.13908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.451658ms","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:6"} {"level":"info","ts":"2026-08-19T00:12:10.139149Z","caller":"traceutil/trace.go:171","msg":"trace[782331975] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24835; }","duration":"258.59143ms","start":"2026-08-19T00:12:09.88054Z","end":"2026-08-19T00:12:10.139131Z","steps":["trace[782331975] 'agreement among raft nodes before linearized reading' (duration: 121.737807ms)","trace[782331975] 'count revisions from in-memory index tree' (duration: 136.745981ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:10.139296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.228928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:10.139324Z","caller":"traceutil/trace.go:171","msg":"trace[1015735504] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:24835; }","duration":"369.28339ms","start":"2026-08-19T00:12:09.770032Z","end":"2026-08-19T00:12:10.139315Z","steps":["trace[1015735504] 'agreement among raft nodes before linearized reading' (duration: 232.091388ms)","trace[1015735504] 'count revisions from in-memory index tree' (duration: 137.152721ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:10.139346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:09.77002Z","time spent":"369.31861ms","remote":"127.0.0.1:60958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-08-19T00:12:10.139448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.470963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-19T00:12:10.139527Z","caller":"traceutil/trace.go:171","msg":"trace[806984298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24835; }","duration":"136.587866ms","start":"2026-08-19T00:12:10.002922Z","end":"2026-08-19T00:12:10.13951Z","steps":["trace[806984298] 'range keys from in-memory index tree' (duration: 136.337ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:10.264024Z","caller":"traceutil/trace.go:171","msg":"trace[1067654358] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"120.58929ms","start":"2026-08-19T00:12:10.143405Z","end":"2026-08-19T00:12:10.263994Z","steps":["trace[1067654358] 'process raft request' (duration: 120.393835ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:10.265604Z","caller":"traceutil/trace.go:171","msg":"trace[1734401295] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"117.517475ms","start":"2026-08-19T00:12:10.148066Z","end":"2026-08-19T00:12:10.265584Z","steps":["trace[1734401295] 'process raft request' (duration: 116.627003ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:10.483393Z","caller":"traceutil/trace.go:171","msg":"trace[1658214740] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"212.770522ms","start":"2026-08-19T00:12:10.270599Z","end":"2026-08-19T00:12:10.48337Z","steps":["trace[1658214740] 'process raft request' (duration: 212.668679ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:10.483618Z","caller":"traceutil/trace.go:171","msg":"trace[447258779] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"213.972ms","start":"2026-08-19T00:12:10.269631Z","end":"2026-08-19T00:12:10.483603Z","steps":["trace[447258779] 'process raft request' (duration: 184.733543ms)","trace[447258779] 'compare' (duration: 28.651063ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:14.388602Z","caller":"traceutil/trace.go:171","msg":"trace[1438754984] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"101.977868ms","start":"2026-08-19T00:12:14.2866Z","end":"2026-08-19T00:12:14.388578Z","steps":["trace[1438754984] 'process raft request' (duration: 101.736352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:14.840156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.819196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:14.840257Z","caller":"traceutil/trace.go:171","msg":"trace[1328757743] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"267.043405ms","start":"2026-08-19T00:12:14.573196Z","end":"2026-08-19T00:12:14.840239Z","steps":["trace[1328757743] 'process raft request' (duration: 122.064725ms)","trace[1328757743] 'compare' (duration: 144.708343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:15.148759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.214395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:15.148972Z","caller":"traceutil/trace.go:171","msg":"trace[1271454732] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"575.691098ms","start":"2026-08-19T00:12:14.573244Z","end":"2026-08-19T00:12:15.148935Z","steps":["trace[1271454732] 'process raft request' (duration: 409.224287ms)","trace[1271454732] 'compare' (duration: 165.976868ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:15.149037Z","caller":"traceutil/trace.go:171","msg":"trace[1523773805] linearizableReadLoop","detail":"{readStateIndex:25684; appliedIndex:25681; }","duration":"560.382357ms","start":"2026-08-19T00:12:14.588642Z","end":"2026-08-19T00:12:15.149024Z","steps":["trace[1523773805] 'read index received' (duration: 106.697423ms)","trace[1523773805] 'applied index is now lower than readState.Index' (duration: 453.682424ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:15.149039Z","caller":"traceutil/trace.go:171","msg":"trace[951417552] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"575.76461ms","start":"2026-08-19T00:12:14.573252Z","end":"2026-08-19T00:12:15.149016Z","steps":["trace[951417552] 'process raft request' (duration: 575.617217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:15.149131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:14.573238Z","time spent":"575.843932ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:12:15.149129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:14.573223Z","time spent":"575.813652ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T00:12:15.149256Z","caller":"traceutil/trace.go:171","msg":"trace[655069844] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"545.764694ms","start":"2026-08-19T00:12:14.60348Z","end":"2026-08-19T00:12:15.149245Z","steps":["trace[655069844] 'process raft request' (duration: 545.446916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:15.14932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:14.603462Z","time spent":"545.821834ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:12:15.149468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.543893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:15.149513Z","caller":"traceutil/trace.go:171","msg":"trace[1216837487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24873; }","duration":"508.626705ms","start":"2026-08-19T00:12:14.640873Z","end":"2026-08-19T00:12:15.1495Z","steps":["trace[1216837487] 'agreement among raft nodes before linearized reading' (duration: 508.547443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:15.149549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:14.640856Z","time spent":"508.684096ms","remote":"127.0.0.1:33970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T00:12:15.149645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.298097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:15.14968Z","caller":"traceutil/trace.go:171","msg":"trace[882905317] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:24873; }","duration":"197.334428ms","start":"2026-08-19T00:12:14.952334Z","end":"2026-08-19T00:12:15.149668Z","steps":["trace[882905317] 'agreement among raft nodes before linearized reading' (duration: 197.276886ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:15.149735Z","caller":"traceutil/trace.go:171","msg":"trace[1861108718] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"532.460291ms","start":"2026-08-19T00:12:14.61726Z","end":"2026-08-19T00:12:15.14972Z","steps":["trace[1861108718] 'process raft request' (duration: 531.714423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:15.149742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.806499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:15.149777Z","caller":"traceutil/trace.go:171","msg":"trace[901530784] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24873; }","duration":"508.886581ms","start":"2026-08-19T00:12:14.640879Z","end":"2026-08-19T00:12:15.149766Z","steps":["trace[901530784] 'agreement among raft nodes before linearized reading' (duration: 508.823129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:15.1498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:14.617237Z","time spent":"532.528683ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:12:15.149809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:14.64085Z","time spent":"508.949942ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-19T00:12:15.149959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.318549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:15.149991Z","caller":"traceutil/trace.go:171","msg":"trace[19157917] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24873; }","duration":"561.392261ms","start":"2026-08-19T00:12:14.588589Z","end":"2026-08-19T00:12:15.149981Z","steps":["trace[19157917] 'agreement among raft nodes before linearized reading' (duration: 561.321879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:15.150018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:14.588568Z","time spent":"561.443312ms","remote":"127.0.0.1:34252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":18,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-19T00:12:15.438773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.006193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:15.439058Z","caller":"traceutil/trace.go:171","msg":"trace[721048651] linearizableReadLoop","detail":"{readStateIndex:25687; appliedIndex:25686; }","duration":"273.379958ms","start":"2026-08-19T00:12:15.16566Z","end":"2026-08-19T00:12:15.43904Z","steps":["trace[721048651] 'read index received' (duration: 131.963445ms)","trace[721048651] 'applied index is now lower than readState.Index' (duration: 141.415073ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:15.439176Z","caller":"traceutil/trace.go:171","msg":"trace[1715981857] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"273.608424ms","start":"2026-08-19T00:12:15.165554Z","end":"2026-08-19T00:12:15.439163Z","steps":["trace[1715981857] 'process raft request' (duration: 132.125759ms)","trace[1715981857] 'compare' (duration: 140.87836ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:15.439431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.733396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-19T00:12:15.439508Z","caller":"traceutil/trace.go:171","msg":"trace[172220933] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24874; }","duration":"273.836499ms","start":"2026-08-19T00:12:15.165648Z","end":"2026-08-19T00:12:15.439485Z","steps":["trace[172220933] 'agreement among raft nodes before linearized reading' (duration: 273.625574ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:15.604247Z","caller":"traceutil/trace.go:171","msg":"trace[1699707284] linearizableReadLoop","detail":"{readStateIndex:25689; appliedIndex:25687; }","duration":"165.097017ms","start":"2026-08-19T00:12:15.439122Z","end":"2026-08-19T00:12:15.604219Z","steps":["trace[1699707284] 'read index received' (duration: 5.36047ms)","trace[1699707284] 'applied index is now lower than readState.Index' (duration: 159.735407ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:15.604304Z","caller":"traceutil/trace.go:171","msg":"trace[2003061717] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"175.451337ms","start":"2026-08-19T00:12:15.428815Z","end":"2026-08-19T00:12:15.604266Z","steps":["trace[2003061717] 'process raft request' (duration: 175.259733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:15.604432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.43347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:15.604482Z","caller":"traceutil/trace.go:171","msg":"trace[1174304329] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:24876; }","duration":"202.524893ms","start":"2026-08-19T00:12:15.401943Z","end":"2026-08-19T00:12:15.604468Z","steps":["trace[1174304329] 'agreement among raft nodes before linearized reading' (duration: 202.423901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:15.604842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.221552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-08-19T00:12:15.604891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.413526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-19T00:12:15.604968Z","caller":"traceutil/trace.go:171","msg":"trace[922436903] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24876; }","duration":"163.56579ms","start":"2026-08-19T00:12:15.441389Z","end":"2026-08-19T00:12:15.604955Z","steps":["trace[922436903] 'agreement among raft nodes before linearized reading' (duration: 163.16667ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:15.60502Z","caller":"traceutil/trace.go:171","msg":"trace[1454423408] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:24876; }","duration":"268.425167ms","start":"2026-08-19T00:12:15.336517Z","end":"2026-08-19T00:12:15.604942Z","steps":["trace[1454423408] 'agreement among raft nodes before linearized reading' (duration: 268.095269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:16.220237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.859309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:16.220356Z","caller":"traceutil/trace.go:171","msg":"trace[755076295] linearizableReadLoop","detail":"{readStateIndex:25693; appliedIndex:25692; }","duration":"110.387743ms","start":"2026-08-19T00:12:16.109949Z","end":"2026-08-19T00:12:16.220336Z","steps":["trace[755076295] 'read index received' (duration: 310.618µs)","trace[755076295] 'applied index is now lower than readState.Index' (duration: 110.075425ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:16.220463Z","caller":"traceutil/trace.go:171","msg":"trace[1392563987] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"194.494279ms","start":"2026-08-19T00:12:16.025922Z","end":"2026-08-19T00:12:16.220416Z","steps":["trace[1392563987] 'process raft request' (duration: 84.344212ms)","trace[1392563987] 'compare' (duration: 109.4632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:16.220606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.616038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:16.220712Z","caller":"traceutil/trace.go:171","msg":"trace[1653243634] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:24880; }","duration":"110.794682ms","start":"2026-08-19T00:12:16.109897Z","end":"2026-08-19T00:12:16.220692Z","steps":["trace[1653243634] 'agreement among raft nodes before linearized reading' (duration: 110.607788ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:16.722491Z","caller":"traceutil/trace.go:171","msg":"trace[42726688] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"276.136865ms","start":"2026-08-19T00:12:16.446321Z","end":"2026-08-19T00:12:16.722457Z","steps":["trace[42726688] 'process raft request' (duration: 272.698332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.016078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.392094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:17.016227Z","caller":"traceutil/trace.go:171","msg":"trace[1843781391] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"239.438156ms","start":"2026-08-19T00:12:16.776757Z","end":"2026-08-19T00:12:17.016195Z","steps":["trace[1843781391] 'process raft request' (duration: 80.813917ms)","trace[1843781391] 'compare' (duration: 158.166659ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:17.016303Z","caller":"traceutil/trace.go:171","msg":"trace[299778701] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"165.796344ms","start":"2026-08-19T00:12:16.850492Z","end":"2026-08-19T00:12:17.016288Z","steps":["trace[299778701] 'process raft request' (duration: 165.699581ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:17.016293Z","caller":"traceutil/trace.go:171","msg":"trace[64532971] linearizableReadLoop","detail":"{readStateIndex:25696; appliedIndex:25695; }","duration":"175.149119ms","start":"2026-08-19T00:12:16.84112Z","end":"2026-08-19T00:12:17.01627Z","steps":["trace[64532971] 'read index received' (duration: 16.468628ms)","trace[64532971] 'applied index is now lower than readState.Index' (duration: 158.678151ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:17.016466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.340145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"warn","ts":"2026-08-19T00:12:17.016555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.191791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T00:12:17.016583Z","caller":"traceutil/trace.go:171","msg":"trace[91612303] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:24884; }","duration":"175.459537ms","start":"2026-08-19T00:12:16.841108Z","end":"2026-08-19T00:12:17.016568Z","steps":["trace[91612303] 'agreement among raft nodes before linearized reading' (duration: 175.221922ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:17.016616Z","caller":"traceutil/trace.go:171","msg":"trace[2126830204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24884; }","duration":"168.309674ms","start":"2026-08-19T00:12:16.848291Z","end":"2026-08-19T00:12:17.016601Z","steps":["trace[2126830204] 'agreement among raft nodes before linearized reading' (duration: 168.13135ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:17.154609Z","caller":"traceutil/trace.go:171","msg":"trace[1611262366] linearizableReadLoop","detail":"{readStateIndex:25698; appliedIndex:25697; }","duration":"130.437779ms","start":"2026-08-19T00:12:17.024147Z","end":"2026-08-19T00:12:17.154585Z","steps":["trace[1611262366] 'read index received' (duration: 128.86742ms)","trace[1611262366] 'applied index is now lower than readState.Index' (duration: 1.569379ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:17.154711Z","caller":"traceutil/trace.go:171","msg":"trace[915958504] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"133.470812ms","start":"2026-08-19T00:12:17.021202Z","end":"2026-08-19T00:12:17.154673Z","steps":["trace[915958504] 'process raft request' (duration: 131.859912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.154784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.912897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:17.154822Z","caller":"traceutil/trace.go:171","msg":"trace[192792512] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24885; }","duration":"109.008009ms","start":"2026-08-19T00:12:17.045801Z","end":"2026-08-19T00:12:17.154809Z","steps":["trace[192792512] 'agreement among raft nodes before linearized reading' (duration: 108.924107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.154903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.723685ms","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-08-19T00:12:17.154983Z","caller":"traceutil/trace.go:171","msg":"trace[758211682] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24885; }","duration":"130.863939ms","start":"2026-08-19T00:12:17.0241Z","end":"2026-08-19T00:12:17.154964Z","steps":["trace[758211682] 'agreement among raft nodes before linearized reading' (duration: 130.590982ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:17.471168Z","caller":"traceutil/trace.go:171","msg":"trace[1586475460] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"305.091457ms","start":"2026-08-19T00:12:17.166049Z","end":"2026-08-19T00:12:17.471141Z","steps":["trace[1586475460] 'process raft request' (duration: 304.915372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.47133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.166024Z","time spent":"305.2199ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T00:12:17.50114Z","caller":"traceutil/trace.go:171","msg":"trace[931757675] linearizableReadLoop","detail":"{readStateIndex:25700; appliedIndex:25698; }","duration":"332.352175ms","start":"2026-08-19T00:12:17.16876Z","end":"2026-08-19T00:12:17.501112Z","steps":["trace[931757675] 'read index received' (duration: 302.221957ms)","trace[931757675] 'applied index is now lower than readState.Index' (duration: 30.129028ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:17.501258Z","caller":"traceutil/trace.go:171","msg":"trace[1945759370] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"334.351604ms","start":"2026-08-19T00:12:17.166896Z","end":"2026-08-19T00:12:17.501248Z","steps":["trace[1945759370] 'process raft request' (duration: 334.036637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.501382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.166873Z","time spent":"334.411175ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:12:17.501463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.696233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-19T00:12:17.501508Z","caller":"traceutil/trace.go:171","msg":"trace[506978697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24888; }","duration":"332.756035ms","start":"2026-08-19T00:12:17.168739Z","end":"2026-08-19T00:12:17.501495Z","steps":["trace[506978697] 'agreement among raft nodes before linearized reading' (duration: 332.616332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.50154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.168732Z","time spent":"332.800236ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T00:12:17.501685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.658873ms","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-08-19T00:12:17.501711Z","caller":"traceutil/trace.go:171","msg":"trace[603139749] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24888; }","duration":"289.706724ms","start":"2026-08-19T00:12:17.211997Z","end":"2026-08-19T00:12:17.501703Z","steps":["trace[603139749] 'agreement among raft nodes before linearized reading' (duration: 289.664663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.50198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.985432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:17.502022Z","caller":"traceutil/trace.go:171","msg":"trace[1392252916] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24888; }","duration":"245.058504ms","start":"2026-08-19T00:12:17.256949Z","end":"2026-08-19T00:12:17.502008Z","steps":["trace[1392252916] 'agreement among raft nodes before linearized reading' (duration: 244.980722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.767721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.337892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:17.767804Z","caller":"traceutil/trace.go:171","msg":"trace[127377293] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"261.950993ms","start":"2026-08-19T00:12:17.505838Z","end":"2026-08-19T00:12:17.767789Z","steps":["trace[127377293] 'process raft request' (duration: 134.479006ms)","trace[127377293] 'compare' (duration: 127.208659ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:17.983954Z","caller":"traceutil/trace.go:171","msg":"trace[1440221826] linearizableReadLoop","detail":"{readStateIndex:25703; appliedIndex:25701; }","duration":"474.454297ms","start":"2026-08-19T00:12:17.509474Z","end":"2026-08-19T00:12:17.983928Z","steps":["trace[1440221826] 'read index received' (duration: 130.853588ms)","trace[1440221826] 'applied index is now lower than readState.Index' (duration: 343.599619ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:17.98406Z","caller":"traceutil/trace.go:171","msg":"trace[915704444] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"374.676621ms","start":"2026-08-19T00:12:17.609372Z","end":"2026-08-19T00:12:17.984049Z","steps":["trace[915704444] 'process raft request' (duration: 374.412965ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:17.984055Z","caller":"traceutil/trace.go:171","msg":"trace[954088395] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"478.031594ms","start":"2026-08-19T00:12:17.506002Z","end":"2026-08-19T00:12:17.984033Z","steps":["trace[954088395] 'process raft request' (duration: 456.947443ms)","trace[954088395] 'compare' (duration: 20.62403ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:17.984174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.505994Z","time spent":"478.107875ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:12:17.984178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.609354Z","time spent":"374.739133ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:12:17.984429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.933449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-19T00:12:17.984509Z","caller":"traceutil/trace.go:171","msg":"trace[1951331707] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24891; }","duration":"475.048292ms","start":"2026-08-19T00:12:17.509442Z","end":"2026-08-19T00:12:17.98449Z","steps":["trace[1951331707] 'agreement among raft nodes before linearized reading' (duration: 474.786535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:17.984556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.509425Z","time spent":"475.122223ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-19T00:12:18.241872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.936578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:18.242088Z","caller":"traceutil/trace.go:171","msg":"trace[735644779] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"291.21143ms","start":"2026-08-19T00:12:17.950864Z","end":"2026-08-19T00:12:18.242076Z","steps":["trace[735644779] 'process raft request' (duration: 291.151378ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:18.242223Z","caller":"traceutil/trace.go:171","msg":"trace[587741998] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"482.335107ms","start":"2026-08-19T00:12:17.759828Z","end":"2026-08-19T00:12:18.242163Z","steps":["trace[587741998] 'process raft request' (duration: 354.02718ms)","trace[587741998] 'compare' (duration: 127.727973ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:18.242309Z","caller":"traceutil/trace.go:171","msg":"trace[1395223423] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"471.133266ms","start":"2026-08-19T00:12:17.77115Z","end":"2026-08-19T00:12:18.242284Z","steps":["trace[1395223423] 'process raft request' (duration: 470.797378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:18.242403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.759799Z","time spent":"482.511571ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:12:18.242452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.771131Z","time spent":"471.246159ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T00:12:18.242669Z","caller":"traceutil/trace.go:171","msg":"trace[1179735184] linearizableReadLoop","detail":"{readStateIndex:25708; appliedIndex:25704; }","duration":"258.628171ms","start":"2026-08-19T00:12:17.98402Z","end":"2026-08-19T00:12:18.242648Z","steps":["trace[1179735184] 'read index received' (duration: 129.765122ms)","trace[1179735184] 'applied index is now lower than readState.Index' (duration: 128.861779ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:18.242641Z","caller":"traceutil/trace.go:171","msg":"trace[982702453] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"290.533535ms","start":"2026-08-19T00:12:17.952074Z","end":"2026-08-19T00:12:18.242607Z","steps":["trace[982702453] 'process raft request' (duration: 289.974461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:18.242834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.818382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:18.242882Z","caller":"traceutil/trace.go:171","msg":"trace[1125960544] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24895; }","duration":"542.906165ms","start":"2026-08-19T00:12:17.699964Z","end":"2026-08-19T00:12:18.24287Z","steps":["trace[1125960544] 'agreement among raft nodes before linearized reading' (duration: 542.735261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:18.242912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.699943Z","time spent":"542.961756ms","remote":"127.0.0.1:35534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-19T00:12:18.242906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.87407ms","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":"warn","ts":"2026-08-19T00:12:18.242922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.861921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-19T00:12:18.242988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.042953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-19T00:12:18.243008Z","caller":"traceutil/trace.go:171","msg":"trace[1366276434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24896; }","duration":"601.998045ms","start":"2026-08-19T00:12:17.640992Z","end":"2026-08-19T00:12:18.24299Z","steps":["trace[1366276434] 'agreement among raft nodes before linearized reading' (duration: 601.830421ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:18.243031Z","caller":"traceutil/trace.go:171","msg":"trace[1798722436] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24896; }","duration":"257.118395ms","start":"2026-08-19T00:12:17.985902Z","end":"2026-08-19T00:12:18.24302Z","steps":["trace[1798722436] 'agreement among raft nodes before linearized reading' (duration: 256.954401ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:18.243061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.64098Z","time spent":"602.073216ms","remote":"127.0.0.1:33970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-19T00:12:18.242954Z","caller":"traceutil/trace.go:171","msg":"trace[1268465020] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:24896; }","duration":"698.982802ms","start":"2026-08-19T00:12:17.543955Z","end":"2026-08-19T00:12:18.242938Z","steps":["trace[1268465020] 'agreement among raft nodes before linearized reading' (duration: 698.88577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:18.243123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:17.543932Z","time spent":"699.168956ms","remote":"127.0.0.1:46602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-08-19T00:12:18.385762Z","caller":"traceutil/trace.go:171","msg":"trace[1653233738] linearizableReadLoop","detail":"{readStateIndex:25710; appliedIndex:25709; }","duration":"132.495768ms","start":"2026-08-19T00:12:18.253235Z","end":"2026-08-19T00:12:18.385731Z","steps":["trace[1653233738] 'read index received' (duration: 103.192959ms)","trace[1653233738] 'applied index is now lower than readState.Index' (duration: 29.301679ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:18.386074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.811265ms","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-08-19T00:12:18.386178Z","caller":"traceutil/trace.go:171","msg":"trace[1552361569] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24897; }","duration":"132.933928ms","start":"2026-08-19T00:12:18.253202Z","end":"2026-08-19T00:12:18.386136Z","steps":["trace[1552361569] 'agreement among raft nodes before linearized reading' (duration: 132.647451ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:18.385791Z","caller":"traceutil/trace.go:171","msg":"trace[1658385628] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"139.254952ms","start":"2026-08-19T00:12:18.246497Z","end":"2026-08-19T00:12:18.385752Z","steps":["trace[1658385628] 'process raft request' (duration: 109.915352ms)","trace[1658385628] 'compare' (duration: 29.162196ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:18.658993Z","caller":"traceutil/trace.go:171","msg":"trace[1475404307] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"188.33675ms","start":"2026-08-19T00:12:18.470629Z","end":"2026-08-19T00:12:18.658966Z","steps":["trace[1475404307] 'process raft request' (duration: 98.884974ms)","trace[1475404307] 'compare' (duration: 89.245451ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:18.990324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.149589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:18.990443Z","caller":"traceutil/trace.go:171","msg":"trace[777759097] linearizableReadLoop","detail":"{readStateIndex:25712; appliedIndex:25711; }","duration":"178.220175ms","start":"2026-08-19T00:12:18.812192Z","end":"2026-08-19T00:12:18.990412Z","steps":["trace[777759097] 'read index received' (duration: 38.873771ms)","trace[777759097] 'applied index is now lower than readState.Index' (duration: 139.342514ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:18.990512Z","caller":"traceutil/trace.go:171","msg":"trace[1476036580] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"260.991119ms","start":"2026-08-19T00:12:18.729489Z","end":"2026-08-19T00:12:18.99048Z","steps":["trace[1476036580] 'process raft request' (duration: 121.604154ms)","trace[1476036580] 'compare' (duration: 139.030766ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:18.990561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.362028ms","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:8"} {"level":"info","ts":"2026-08-19T00:12:18.99085Z","caller":"traceutil/trace.go:171","msg":"trace[470416040] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:24899; }","duration":"178.691996ms","start":"2026-08-19T00:12:18.812145Z","end":"2026-08-19T00:12:18.990837Z","steps":["trace[470416040] 'agreement among raft nodes before linearized reading' (duration: 178.348958ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:18.998857Z","caller":"traceutil/trace.go:171","msg":"trace[477672218] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"100.040922ms","start":"2026-08-19T00:12:18.898783Z","end":"2026-08-19T00:12:18.998824Z","steps":["trace[477672218] 'process raft request' (duration: 99.874468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:18.998955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.186263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:18.999002Z","caller":"traceutil/trace.go:171","msg":"trace[1161564740] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:24900; }","duration":"135.254524ms","start":"2026-08-19T00:12:18.863726Z","end":"2026-08-19T00:12:18.99898Z","steps":["trace[1161564740] 'agreement among raft nodes before linearized reading' (duration: 135.019469ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:19.446466Z","caller":"traceutil/trace.go:171","msg":"trace[587665088] linearizableReadLoop","detail":"{readStateIndex:25715; appliedIndex:25714; }","duration":"253.136999ms","start":"2026-08-19T00:12:19.193301Z","end":"2026-08-19T00:12:19.446438Z","steps":["trace[587665088] 'read index received' (duration: 237.528621ms)","trace[587665088] 'applied index is now lower than readState.Index' (duration: 15.606998ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:19.44653Z","caller":"traceutil/trace.go:171","msg":"trace[2051870659] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"281.373153ms","start":"2026-08-19T00:12:19.165117Z","end":"2026-08-19T00:12:19.44649Z","steps":["trace[2051870659] 'process raft request' (duration: 265.289533ms)","trace[2051870659] 'compare' (duration: 15.712252ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:19.446672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.346785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:19.447002Z","caller":"traceutil/trace.go:171","msg":"trace[1218868765] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24902; }","duration":"253.731914ms","start":"2026-08-19T00:12:19.193249Z","end":"2026-08-19T00:12:19.446981Z","steps":["trace[1218868765] 'agreement among raft nodes before linearized reading' (duration: 253.278933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:19.752393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.84124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:12:19.752504Z","caller":"traceutil/trace.go:171","msg":"trace[210884251] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"276.298339ms","start":"2026-08-19T00:12:19.476194Z","end":"2026-08-19T00:12:19.752493Z","steps":["trace[210884251] 'process raft request' (duration: 123.302555ms)","trace[210884251] 'compare' (duration: 152.421851ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:19.752972Z","caller":"traceutil/trace.go:171","msg":"trace[978497000] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25715; }","duration":"112.850992ms","start":"2026-08-19T00:12:19.640091Z","end":"2026-08-19T00:12:19.752942Z","steps":["trace[978497000] 'read index received' (duration: 71.052µs)","trace[978497000] 'applied index is now lower than readState.Index' (duration: 112.77866ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:19.753095Z","caller":"traceutil/trace.go:171","msg":"trace[1430006201] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"246.020336ms","start":"2026-08-19T00:12:19.506844Z","end":"2026-08-19T00:12:19.752864Z","steps":["trace[1430006201] 'process raft request' (duration: 245.613826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:19.753106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.996015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:19.7531Z","caller":"traceutil/trace.go:171","msg":"trace[1864114330] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"246.221751ms","start":"2026-08-19T00:12:19.506853Z","end":"2026-08-19T00:12:19.753075Z","steps":["trace[1864114330] 'process raft request' (duration: 245.972835ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:19.753153Z","caller":"traceutil/trace.go:171","msg":"trace[1183787733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24905; }","duration":"113.095888ms","start":"2026-08-19T00:12:19.640043Z","end":"2026-08-19T00:12:19.753139Z","steps":["trace[1183787733] 'agreement among raft nodes before linearized reading' (duration: 113.002555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:20.049846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.226621ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-19T00:12:20.049974Z","caller":"traceutil/trace.go:171","msg":"trace[964616074] linearizableReadLoop","detail":"{readStateIndex:25719; appliedIndex:25718; }","duration":"294.987142ms","start":"2026-08-19T00:12:19.754971Z","end":"2026-08-19T00:12:20.049959Z","steps":["trace[964616074] 'read index received' (duration: 15.621088ms)","trace[964616074] 'applied index is now lower than readState.Index' (duration: 279.364634ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:20.050063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.091644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:20.050097Z","caller":"traceutil/trace.go:171","msg":"trace[591981378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24905; }","duration":"295.161826ms","start":"2026-08-19T00:12:19.754926Z","end":"2026-08-19T00:12:20.050088Z","steps":["trace[591981378] 'agreement among raft nodes before linearized reading' (duration: 295.095195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:20.116648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.927087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-19T00:12:20.116655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.965252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-19T00:12:20.116714Z","caller":"traceutil/trace.go:171","msg":"trace[1841072635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24906; }","duration":"128.03642ms","start":"2026-08-19T00:12:19.988662Z","end":"2026-08-19T00:12:20.116699Z","steps":["trace[1841072635] 'agreement among raft nodes before linearized reading' (duration: 127.804305ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:20.116768Z","caller":"traceutil/trace.go:171","msg":"trace[682562943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24906; }","duration":"345.075214ms","start":"2026-08-19T00:12:19.771636Z","end":"2026-08-19T00:12:20.116711Z","steps":["trace[682562943] 'agreement among raft nodes before linearized reading' (duration: 344.856849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:20.116855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:19.771629Z","time spent":"345.214838ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-19T00:12:20.116981Z","caller":"traceutil/trace.go:171","msg":"trace[838879308] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"127.598699ms","start":"2026-08-19T00:12:19.989314Z","end":"2026-08-19T00:12:20.116912Z","steps":["trace[838879308] 'process raft request' (duration: 126.950734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:20.117008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.998152ms","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-08-19T00:12:20.117155Z","caller":"traceutil/trace.go:171","msg":"trace[449288702] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24906; }","duration":"295.151495ms","start":"2026-08-19T00:12:19.82198Z","end":"2026-08-19T00:12:20.117132Z","steps":["trace[449288702] 'agreement among raft nodes before linearized reading' (duration: 294.699255ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:20.311467Z","caller":"traceutil/trace.go:171","msg":"trace[1112881953] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"190.558074ms","start":"2026-08-19T00:12:20.120888Z","end":"2026-08-19T00:12:20.311446Z","steps":["trace[1112881953] 'process raft request' (duration: 190.195035ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:20.47098Z","caller":"traceutil/trace.go:171","msg":"trace[2011085867] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"349.586142ms","start":"2026-08-19T00:12:20.121371Z","end":"2026-08-19T00:12:20.470957Z","steps":["trace[2011085867] 'process raft request' (duration: 341.744613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:20.471159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:20.12135Z","time spent":"349.708126ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T00:12:20.471173Z","caller":"traceutil/trace.go:171","msg":"trace[1413079369] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"224.080375ms","start":"2026-08-19T00:12:20.24707Z","end":"2026-08-19T00:12:20.471151Z","steps":["trace[1413079369] 'process raft request' (duration: 223.812767ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:20.471268Z","caller":"traceutil/trace.go:171","msg":"trace[655028846] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"221.913823ms","start":"2026-08-19T00:12:20.249329Z","end":"2026-08-19T00:12:20.471243Z","steps":["trace[655028846] 'process raft request' (duration: 221.742579ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:20.471287Z","caller":"traceutil/trace.go:171","msg":"trace[503232119] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"221.779971ms","start":"2026-08-19T00:12:20.249484Z","end":"2026-08-19T00:12:20.471264Z","steps":["trace[503232119] 'process raft request' (duration: 221.695729ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:20.471373Z","caller":"traceutil/trace.go:171","msg":"trace[962750357] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"221.7752ms","start":"2026-08-19T00:12:20.249591Z","end":"2026-08-19T00:12:20.471366Z","steps":["trace[962750357] 'process raft request' (duration: 221.642927ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:20.471355Z","caller":"traceutil/trace.go:171","msg":"trace[956231257] linearizableReadLoop","detail":"{readStateIndex:25722; appliedIndex:25720; }","duration":"306.166533ms","start":"2026-08-19T00:12:20.165162Z","end":"2026-08-19T00:12:20.471329Z","steps":["trace[956231257] 'read index received' (duration: 145.965064ms)","trace[956231257] 'applied index is now lower than readState.Index' (duration: 160.200439ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:20.4716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.55162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:20.471636Z","caller":"traceutil/trace.go:171","msg":"trace[1645383721] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24912; }","duration":"144.639622ms","start":"2026-08-19T00:12:20.326984Z","end":"2026-08-19T00:12:20.471624Z","steps":["trace[1645383721] 'agreement among raft nodes before linearized reading' (duration: 144.490529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:20.47165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.47852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:20.471696Z","caller":"traceutil/trace.go:171","msg":"trace[18143508] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24912; }","duration":"306.568283ms","start":"2026-08-19T00:12:20.165113Z","end":"2026-08-19T00:12:20.471681Z","steps":["trace[18143508] 'agreement among raft nodes before linearized reading' (duration: 306.298716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:20.471735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:20.1651Z","time spent":"306.625234ms","remote":"127.0.0.1:37792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-08-19T00:12:20.834286Z","caller":"traceutil/trace.go:171","msg":"trace[1375246680] linearizableReadLoop","detail":"{readStateIndex:25727; appliedIndex:25726; }","duration":"152.198834ms","start":"2026-08-19T00:12:20.682063Z","end":"2026-08-19T00:12:20.834262Z","steps":["trace[1375246680] 'read index received' (duration: 151.975649ms)","trace[1375246680] 'applied index is now lower than readState.Index' (duration: 222.345µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:20.83445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.358048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-19T00:12:20.834494Z","caller":"traceutil/trace.go:171","msg":"trace[1256659310] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:24913; }","duration":"152.448511ms","start":"2026-08-19T00:12:20.682033Z","end":"2026-08-19T00:12:20.834481Z","steps":["trace[1256659310] 'agreement among raft nodes before linearized reading' (duration: 152.323058ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:20.834591Z","caller":"traceutil/trace.go:171","msg":"trace[945563242] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"164.841381ms","start":"2026-08-19T00:12:20.669721Z","end":"2026-08-19T00:12:20.834562Z","steps":["trace[945563242] 'process raft request' (duration: 164.38231ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:24.959785Z","caller":"traceutil/trace.go:171","msg":"trace[1791078467] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"110.321572ms","start":"2026-08-19T00:12:24.849438Z","end":"2026-08-19T00:12:24.95976Z","steps":["trace[1791078467] 'process raft request' (duration: 110.132728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:25.223659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.428763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-19T00:12:25.22376Z","caller":"traceutil/trace.go:171","msg":"trace[1134953621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24946; }","duration":"200.579756ms","start":"2026-08-19T00:12:25.023156Z","end":"2026-08-19T00:12:25.223736Z","steps":["trace[1134953621] 'range keys from in-memory index tree' (duration: 200.219868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:25.224624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.328929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-19T00:12:25.224699Z","caller":"traceutil/trace.go:171","msg":"trace[1370525050] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24946; }","duration":"105.456433ms","start":"2026-08-19T00:12:25.119226Z","end":"2026-08-19T00:12:25.224682Z","steps":["trace[1370525050] 'count revisions from in-memory index tree' (duration: 105.212687ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:29.757884Z","caller":"traceutil/trace.go:171","msg":"trace[463382664] linearizableReadLoop","detail":"{readStateIndex:25851; appliedIndex:25850; }","duration":"117.434733ms","start":"2026-08-19T00:12:29.640431Z","end":"2026-08-19T00:12:29.757866Z","steps":["trace[463382664] 'read index received' (duration: 117.412733ms)","trace[463382664] 'applied index is now lower than readState.Index' (duration: 21.23µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:29.757985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.538256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:29.757964Z","caller":"traceutil/trace.go:171","msg":"trace[1788956607] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"191.382924ms","start":"2026-08-19T00:12:29.566545Z","end":"2026-08-19T00:12:29.757928Z","steps":["trace[1788956607] 'process raft request' (duration: 191.21391ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:29.758038Z","caller":"traceutil/trace.go:171","msg":"trace[984270213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25035; }","duration":"117.645748ms","start":"2026-08-19T00:12:29.640383Z","end":"2026-08-19T00:12:29.758028Z","steps":["trace[984270213] 'agreement among raft nodes before linearized reading' (duration: 117.555456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:29.971578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.349642ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-19T00:12:29.971847Z","caller":"traceutil/trace.go:171","msg":"trace[1163467471] linearizableReadLoop","detail":"{readStateIndex:25852; appliedIndex:25851; }","duration":"213.889478ms","start":"2026-08-19T00:12:29.757934Z","end":"2026-08-19T00:12:29.971824Z","steps":["trace[1163467471] 'read index received' (duration: 46.426583ms)","trace[1163467471] 'applied index is now lower than readState.Index' (duration: 167.461385ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:29.972122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.784126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9eb0557-17bc-4a17-a420-8c2dc277b815\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-19T00:12:29.972233Z","caller":"traceutil/trace.go:171","msg":"trace[609278157] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9eb0557-17bc-4a17-a420-8c2dc277b815; range_end:; response_count:1; response_revision:25035; }","duration":"308.936129ms","start":"2026-08-19T00:12:29.663276Z","end":"2026-08-19T00:12:29.972212Z","steps":["trace[609278157] 'agreement among raft nodes before linearized reading' (duration: 308.667733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:29.972284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:12:29.663264Z","time spent":"309.010952ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9eb0557-17bc-4a17-a420-8c2dc277b815\" "} {"level":"info","ts":"2026-08-19T00:12:29.972323Z","caller":"traceutil/trace.go:171","msg":"trace[1584413503] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"190.209075ms","start":"2026-08-19T00:12:29.782084Z","end":"2026-08-19T00:12:29.972293Z","steps":["trace[1584413503] 'process raft request' (duration: 189.627191ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:29.972534Z","caller":"traceutil/trace.go:171","msg":"trace[1099633334] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"186.351341ms","start":"2026-08-19T00:12:29.786156Z","end":"2026-08-19T00:12:29.972508Z","steps":["trace[1099633334] 'process raft request' (duration: 185.935261ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:29.972716Z","caller":"traceutil/trace.go:171","msg":"trace[499658351] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"183.895642ms","start":"2026-08-19T00:12:29.788809Z","end":"2026-08-19T00:12:29.972704Z","steps":["trace[499658351] 'process raft request' (duration: 183.669706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:29.972691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.248853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:29.97332Z","caller":"traceutil/trace.go:171","msg":"trace[1686280014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25038; }","duration":"213.917258ms","start":"2026-08-19T00:12:29.759384Z","end":"2026-08-19T00:12:29.973302Z","steps":["trace[1686280014] 'agreement among raft nodes before linearized reading' (duration: 213.237112ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:30.25007Z","caller":"traceutil/trace.go:171","msg":"trace[1469657686] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"270.834227ms","start":"2026-08-19T00:12:29.979194Z","end":"2026-08-19T00:12:30.250028Z","steps":["trace[1469657686] 'process raft request' (duration: 270.634392ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:30.405166Z","caller":"traceutil/trace.go:171","msg":"trace[1611199005] linearizableReadLoop","detail":"{readStateIndex:25857; appliedIndex:25855; }","duration":"201.036667ms","start":"2026-08-19T00:12:30.204108Z","end":"2026-08-19T00:12:30.405145Z","steps":["trace[1611199005] 'read index received' (duration: 45.658145ms)","trace[1611199005] 'applied index is now lower than readState.Index' (duration: 155.377482ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:30.405307Z","caller":"traceutil/trace.go:171","msg":"trace[1641672702] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"258.048227ms","start":"2026-08-19T00:12:30.147242Z","end":"2026-08-19T00:12:30.405291Z","steps":["trace[1641672702] 'process raft request' (duration: 218.436659ms)","trace[1641672702] 'compare' (duration: 39.327402ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:12:30.405625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.483348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:12:30.4057Z","caller":"traceutil/trace.go:171","msg":"trace[727346843] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25040; }","duration":"201.621552ms","start":"2026-08-19T00:12:30.204061Z","end":"2026-08-19T00:12:30.405683Z","steps":["trace[727346843] 'agreement among raft nodes before linearized reading' (duration: 201.469158ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:30.537438Z","caller":"traceutil/trace.go:171","msg":"trace[524346257] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"128.44435ms","start":"2026-08-19T00:12:30.408959Z","end":"2026-08-19T00:12:30.537404Z","steps":["trace[524346257] 'process raft request' (duration: 128.231915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:12:33.800058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.333914ms","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-08-19T00:12:33.800127Z","caller":"traceutil/trace.go:171","msg":"trace[342514502] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25075; }","duration":"106.433467ms","start":"2026-08-19T00:12:33.693679Z","end":"2026-08-19T00:12:33.800112Z","steps":["trace[342514502] 'range keys from in-memory index tree' (duration: 106.21214ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:38.180601Z","caller":"traceutil/trace.go:171","msg":"trace[896546700] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"145.884302ms","start":"2026-08-19T00:12:38.034687Z","end":"2026-08-19T00:12:38.180571Z","steps":["trace[896546700] 'process raft request' (duration: 54.258314ms)","trace[896546700] 'compare' (duration: 91.516825ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:12:38.186223Z","caller":"traceutil/trace.go:171","msg":"trace[346468377] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"149.695903ms","start":"2026-08-19T00:12:38.03651Z","end":"2026-08-19T00:12:38.186206Z","steps":["trace[346468377] 'process raft request' (duration: 149.660153ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:38.186482Z","caller":"traceutil/trace.go:171","msg":"trace[348011439] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"150.839922ms","start":"2026-08-19T00:12:38.035634Z","end":"2026-08-19T00:12:38.186474Z","steps":["trace[348011439] 'process raft request' (duration: 150.421192ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:12:55.142684Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065e9.wal"} {"level":"info","ts":"2026-08-19T00:13:00.933387Z","caller":"traceutil/trace.go:171","msg":"trace[905339981] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"139.670321ms","start":"2026-08-19T00:13:00.793687Z","end":"2026-08-19T00:13:00.933357Z","steps":["trace[905339981] 'process raft request' (duration: 41.594877ms)","trace[905339981] 'compare' (duration: 97.298366ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:00.971335Z","caller":"traceutil/trace.go:171","msg":"trace[945002603] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"175.121011ms","start":"2026-08-19T00:13:00.796184Z","end":"2026-08-19T00:13:00.971305Z","steps":["trace[945002603] 'process raft request' (duration: 174.910156ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:00.971658Z","caller":"traceutil/trace.go:171","msg":"trace[851949934] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"173.281436ms","start":"2026-08-19T00:13:00.798363Z","end":"2026-08-19T00:13:00.971644Z","steps":["trace[851949934] 'process raft request' (duration: 172.862026ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:00.972029Z","caller":"traceutil/trace.go:171","msg":"trace[952591323] linearizableReadLoop","detail":"{readStateIndex:26133; appliedIndex:26129; }","duration":"169.124815ms","start":"2026-08-19T00:13:00.802891Z","end":"2026-08-19T00:13:00.972016Z","steps":["trace[952591323] 'read index received' (duration: 32.329422ms)","trace[952591323] 'applied index is now lower than readState.Index' (duration: 136.794393ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:00.972164Z","caller":"traceutil/trace.go:171","msg":"trace[165769439] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"171.245557ms","start":"2026-08-19T00:13:00.800893Z","end":"2026-08-19T00:13:00.972139Z","steps":["trace[165769439] 'process raft request' (duration: 170.370456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:00.97232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.402241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-19T00:13:00.972774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.265683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-19T00:13:00.97287Z","caller":"traceutil/trace.go:171","msg":"trace[186417533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25310; }","duration":"161.377297ms","start":"2026-08-19T00:13:00.811481Z","end":"2026-08-19T00:13:00.972858Z","steps":["trace[186417533] 'agreement among raft nodes before linearized reading' (duration: 161.213663ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:00.973178Z","caller":"traceutil/trace.go:171","msg":"trace[1985886027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25310; }","duration":"169.769899ms","start":"2026-08-19T00:13:00.802867Z","end":"2026-08-19T00:13:00.972636Z","steps":["trace[1985886027] 'agreement among raft nodes before linearized reading' (duration: 169.304978ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:01.17Z","caller":"traceutil/trace.go:171","msg":"trace[1539780264] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"194.255774ms","start":"2026-08-19T00:13:00.975716Z","end":"2026-08-19T00:13:01.169972Z","steps":["trace[1539780264] 'process raft request' (duration: 134.461335ms)","trace[1539780264] 'compare' (duration: 59.315607ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:01.192657Z","caller":"traceutil/trace.go:171","msg":"trace[1107813905] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"216.220505ms","start":"2026-08-19T00:13:00.976405Z","end":"2026-08-19T00:13:01.192625Z","steps":["trace[1107813905] 'process raft request' (duration: 215.962768ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:01.337437Z","caller":"traceutil/trace.go:171","msg":"trace[158691606] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"133.606874ms","start":"2026-08-19T00:13:01.203803Z","end":"2026-08-19T00:13:01.33741Z","steps":["trace[158691606] 'process raft request' (duration: 129.364792ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:01.632187Z","caller":"traceutil/trace.go:171","msg":"trace[521453290] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"197.466661ms","start":"2026-08-19T00:13:01.434696Z","end":"2026-08-19T00:13:01.632162Z","steps":["trace[521453290] 'process raft request' (duration: 197.305167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:04.488249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.875474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:13:04.488515Z","caller":"traceutil/trace.go:171","msg":"trace[1430933427] linearizableReadLoop","detail":"{readStateIndex:26158; appliedIndex:26155; }","duration":"175.810826ms","start":"2026-08-19T00:13:04.312683Z","end":"2026-08-19T00:13:04.488493Z","steps":["trace[1430933427] 'read index received' (duration: 33.569513ms)","trace[1430933427] 'applied index is now lower than readState.Index' (duration: 142.240243ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:04.488838Z","caller":"traceutil/trace.go:171","msg":"trace[1123964663] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"227.914707ms","start":"2026-08-19T00:13:04.260905Z","end":"2026-08-19T00:13:04.488819Z","steps":["trace[1123964663] 'process raft request' (duration: 85.366657ms)","trace[1123964663] 'compare' (duration: 141.742671ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:04.489084Z","caller":"traceutil/trace.go:171","msg":"trace[1693697041] transaction","detail":"{read_only:false; response_revision:25334; number_of_response:1; }","duration":"227.527288ms","start":"2026-08-19T00:13:04.261545Z","end":"2026-08-19T00:13:04.489072Z","steps":["trace[1693697041] 'process raft request' (duration: 226.824381ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:04.489235Z","caller":"traceutil/trace.go:171","msg":"trace[956582342] transaction","detail":"{read_only:false; response_revision:25335; number_of_response:1; }","duration":"223.989903ms","start":"2026-08-19T00:13:04.265236Z","end":"2026-08-19T00:13:04.489226Z","steps":["trace[956582342] 'process raft request' (duration: 223.203273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:04.489452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.76831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-19T00:13:04.4895Z","caller":"traceutil/trace.go:171","msg":"trace[1968155724] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25335; }","duration":"176.819171ms","start":"2026-08-19T00:13:04.312669Z","end":"2026-08-19T00:13:04.489488Z","steps":["trace[1968155724] 'agreement among raft nodes before linearized reading' (duration: 176.673968ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:04.982082Z","caller":"traceutil/trace.go:171","msg":"trace[1705303973] linearizableReadLoop","detail":"{readStateIndex:26160; appliedIndex:26158; }","duration":"487.312157ms","start":"2026-08-19T00:13:04.494742Z","end":"2026-08-19T00:13:04.982054Z","steps":["trace[1705303973] 'read index received' (duration: 71.919331ms)","trace[1705303973] 'applied index is now lower than readState.Index' (duration: 415.391376ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:04.982122Z","caller":"traceutil/trace.go:171","msg":"trace[1083446438] transaction","detail":"{read_only:false; response_revision:25336; number_of_response:1; }","duration":"487.515742ms","start":"2026-08-19T00:13:04.49458Z","end":"2026-08-19T00:13:04.982096Z","steps":["trace[1083446438] 'process raft request' (duration: 487.282427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:04.982273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:04.494563Z","time spent":"487.626416ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:13:04.982391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.625145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-19T00:13:04.982452Z","caller":"traceutil/trace.go:171","msg":"trace[1522294766] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25336; }","duration":"487.740839ms","start":"2026-08-19T00:13:04.494697Z","end":"2026-08-19T00:13:04.982437Z","steps":["trace[1522294766] 'agreement among raft nodes before linearized reading' (duration: 487.496774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:04.982501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:04.49468Z","time spent":"487.812841ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-19T00:13:05.172939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.669157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:13:05.173123Z","caller":"traceutil/trace.go:171","msg":"trace[850399380] linearizableReadLoop","detail":"{readStateIndex:26163; appliedIndex:26160; }","duration":"190.948243ms","start":"2026-08-19T00:13:04.982165Z","end":"2026-08-19T00:13:05.173113Z","steps":["trace[850399380] 'read index received' (duration: 50.058461ms)","trace[850399380] 'applied index is now lower than readState.Index' (duration: 140.888932ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:05.173176Z","caller":"traceutil/trace.go:171","msg":"trace[1229267525] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"605.512479ms","start":"2026-08-19T00:13:04.567656Z","end":"2026-08-19T00:13:05.173168Z","steps":["trace[1229267525] 'process raft request' (duration: 464.550985ms)","trace[1229267525] 'compare' (duration: 140.40555ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T00:13:05.173207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:04.567636Z","time spent":"605.554191ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T00:13:05.173398Z","caller":"traceutil/trace.go:171","msg":"trace[1745224077] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"602.636689ms","start":"2026-08-19T00:13:04.570755Z","end":"2026-08-19T00:13:05.173392Z","steps":["trace[1745224077] 'process raft request' (duration: 602.26136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:05.173433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:04.570744Z","time spent":"602.672351ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T00:13:05.173542Z","caller":"traceutil/trace.go:171","msg":"trace[1570100752] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"229.737262ms","start":"2026-08-19T00:13:04.9438Z","end":"2026-08-19T00:13:05.173537Z","steps":["trace[1570100752] 'process raft request' (duration: 229.285821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:05.173719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.961536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-08-19T00:13:05.173792Z","caller":"traceutil/trace.go:171","msg":"trace[721142771] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25339; }","duration":"315.084648ms","start":"2026-08-19T00:13:04.858687Z","end":"2026-08-19T00:13:05.173772Z","steps":["trace[721142771] 'agreement among raft nodes before linearized reading' (duration: 314.875743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:05.173835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:04.858664Z","time spent":"315.160761ms","remote":"127.0.0.1:34100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1873,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"info","ts":"2026-08-19T00:13:05.231607Z","caller":"traceutil/trace.go:171","msg":"trace[1091699675] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"248.703232ms","start":"2026-08-19T00:13:04.982884Z","end":"2026-08-19T00:13:05.231587Z","steps":["trace[1091699675] 'process raft request' (duration: 248.542697ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:05.23195Z","caller":"traceutil/trace.go:171","msg":"trace[1038706091] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"248.653611ms","start":"2026-08-19T00:13:04.983274Z","end":"2026-08-19T00:13:05.231928Z","steps":["trace[1038706091] 'process raft request' (duration: 248.271292ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:05.232063Z","caller":"traceutil/trace.go:171","msg":"trace[37706871] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"248.777033ms","start":"2026-08-19T00:13:04.983257Z","end":"2026-08-19T00:13:05.232034Z","steps":["trace[37706871] 'process raft request' (duration: 248.575029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:05.232181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.529107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-19T00:13:05.232265Z","caller":"traceutil/trace.go:171","msg":"trace[401762112] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25342; }","duration":"248.65006ms","start":"2026-08-19T00:13:04.983598Z","end":"2026-08-19T00:13:05.232248Z","steps":["trace[401762112] 'agreement among raft nodes before linearized reading' (duration: 248.430225ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:05.753787Z","caller":"traceutil/trace.go:171","msg":"trace[54039761] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"100.77824ms","start":"2026-08-19T00:13:05.652985Z","end":"2026-08-19T00:13:05.753763Z","steps":["trace[54039761] 'process raft request' (duration: 100.634057ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:06.207609Z","caller":"traceutil/trace.go:171","msg":"trace[712173721] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"135.730327ms","start":"2026-08-19T00:13:06.071823Z","end":"2026-08-19T00:13:06.207553Z","steps":["trace[712173721] 'process raft request' (duration: 135.557022ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:06.610252Z","caller":"traceutil/trace.go:171","msg":"trace[1001472946] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"115.374124ms","start":"2026-08-19T00:13:06.494852Z","end":"2026-08-19T00:13:06.610226Z","steps":["trace[1001472946] 'process raft request' (duration: 113.817765ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:06.610356Z","caller":"traceutil/trace.go:171","msg":"trace[427900452] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"114.283137ms","start":"2026-08-19T00:13:06.49604Z","end":"2026-08-19T00:13:06.610323Z","steps":["trace[427900452] 'process raft request' (duration: 114.054961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:06.826345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.142656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:13:06.826394Z","caller":"traceutil/trace.go:171","msg":"trace[1234717298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25350; }","duration":"112.229867ms","start":"2026-08-19T00:13:06.714154Z","end":"2026-08-19T00:13:06.826384Z","steps":["trace[1234717298] 'range keys from in-memory index tree' (duration: 112.070833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:17.389939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.002099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-19T00:13:17.390122Z","caller":"traceutil/trace.go:171","msg":"trace[759348854] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"180.642754ms","start":"2026-08-19T00:13:17.209459Z","end":"2026-08-19T00:13:17.390102Z","steps":["trace[759348854] 'process raft request' (duration: 52.403629ms)","trace[759348854] 'compare' (duration: 127.905787ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:17.39045Z","caller":"traceutil/trace.go:171","msg":"trace[2049131954] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"174.940105ms","start":"2026-08-19T00:13:17.215481Z","end":"2026-08-19T00:13:17.390421Z","steps":["trace[2049131954] 'process raft request' (duration: 174.550516ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:17.390472Z","caller":"traceutil/trace.go:171","msg":"trace[205378456] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"124.72253ms","start":"2026-08-19T00:13:17.265727Z","end":"2026-08-19T00:13:17.390449Z","steps":["trace[205378456] 'process raft request' (duration: 124.609707ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:17.641392Z","caller":"traceutil/trace.go:171","msg":"trace[1803837903] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"374.534377ms","start":"2026-08-19T00:13:17.26684Z","end":"2026-08-19T00:13:17.641375Z","steps":["trace[1803837903] 'process raft request' (duration: 368.010379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:17.641512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:17.266819Z","time spent":"374.63976ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T00:13:17.641569Z","caller":"traceutil/trace.go:171","msg":"trace[1678083229] linearizableReadLoop","detail":"{readStateIndex:26264; appliedIndex:26259; }","duration":"353.807196ms","start":"2026-08-19T00:13:17.287739Z","end":"2026-08-19T00:13:17.641547Z","steps":["trace[1678083229] 'read index received' (duration: 85.691865ms)","trace[1678083229] 'applied index is now lower than readState.Index' (duration: 268.114131ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T00:13:17.641661Z","caller":"traceutil/trace.go:171","msg":"trace[85593513] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"361.957254ms","start":"2026-08-19T00:13:17.279675Z","end":"2026-08-19T00:13:17.641632Z","steps":["trace[85593513] 'process raft request' (duration: 361.646976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:17.641729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.563694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-19T00:13:17.641732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.977801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-19T00:13:17.641774Z","caller":"traceutil/trace.go:171","msg":"trace[2092296535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25438; }","duration":"190.651156ms","start":"2026-08-19T00:13:17.451112Z","end":"2026-08-19T00:13:17.641763Z","steps":["trace[2092296535] 'agreement among raft nodes before linearized reading' (duration: 190.578954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:17.641781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.544652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-19T00:13:17.641801Z","caller":"traceutil/trace.go:171","msg":"trace[78193570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25438; }","duration":"354.084204ms","start":"2026-08-19T00:13:17.287698Z","end":"2026-08-19T00:13:17.641782Z","steps":["trace[78193570] 'agreement among raft nodes before linearized reading' (duration: 353.93664ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:17.64183Z","caller":"traceutil/trace.go:171","msg":"trace[616108304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25438; }","duration":"351.651824ms","start":"2026-08-19T00:13:17.290168Z","end":"2026-08-19T00:13:17.64182Z","steps":["trace[616108304] 'agreement among raft nodes before linearized reading' (duration: 351.526121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:17.641825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:17.279651Z","time spent":"362.083547ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T00:13:17.641848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:17.287685Z","time spent":"354.151425ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T00:13:17.641858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:17.290157Z","time spent":"351.694475ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T00:13:17.641941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.228009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:13:17.64206Z","caller":"traceutil/trace.go:171","msg":"trace[113976556] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25438; }","duration":"288.444313ms","start":"2026-08-19T00:13:17.35358Z","end":"2026-08-19T00:13:17.642024Z","steps":["trace[113976556] 'agreement among raft nodes before linearized reading' (duration: 288.120226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:22.606123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.581868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-08-19T00:13:22.60622Z","caller":"traceutil/trace.go:171","msg":"trace[436728391] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25474; }","duration":"184.724772ms","start":"2026-08-19T00:13:22.42147Z","end":"2026-08-19T00:13:22.606194Z","steps":["trace[436728391] 'range keys from in-memory index tree' (duration: 184.411454ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:13:28.196765Z","caller":"traceutil/trace.go:171","msg":"trace[808096097] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"304.949294ms","start":"2026-08-19T00:13:27.891791Z","end":"2026-08-19T00:13:28.19674Z","steps":["trace[808096097] 'process raft request' (duration: 304.80349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:28.196934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T00:13:27.891779Z","time spent":"305.081557ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T00:13:28.431757Z","caller":"traceutil/trace.go:171","msg":"trace[384336400] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"123.646753ms","start":"2026-08-19T00:13:28.308087Z","end":"2026-08-19T00:13:28.431734Z","steps":["trace[384336400] 'process raft request' (duration: 123.5303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T00:13:28.740652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.135165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T00:13:28.740746Z","caller":"traceutil/trace.go:171","msg":"trace[1685411415] 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:25518; }","duration":"181.33538ms","start":"2026-08-19T00:13:28.559377Z","end":"2026-08-19T00:13:28.740712Z","steps":["trace[1685411415] 'range keys from in-memory index tree' (duration: 181.024822ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T00:14:31.471271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23222} {"level":"info","ts":"2026-08-19T00:14:31.604602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23222,"took":"128.309857ms","hash":4216581997,"current-db-size-bytes":59117568,"current-db-size":"59 MB","current-db-size-in-use-bytes":36839424,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-19T00:14:31.604695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4216581997,"revision":23222,"compact-revision":20137} {"level":"info","ts":"2026-08-19T00:19:31.476393Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26181} {"level":"info","ts":"2026-08-19T00:19:31.61753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26181,"took":"136.708249ms","hash":3092590902,"current-db-size-bytes":59117568,"current-db-size":"59 MB","current-db-size-in-use-bytes":34922496,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-19T00:19:31.617607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3092590902,"revision":26181,"compact-revision":23222} {"level":"info","ts":"2026-08-19T00:20:00.133636Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":30005,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T00:20:00.139601Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-08-19T00:20:00.139901Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005} {"level":"info","ts":"2026-08-19T00:24:31.481605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28856} {"level":"info","ts":"2026-08-19T00:24:31.614912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28856,"took":"129.908573ms","hash":2027757776,"current-db-size-bytes":59117568,"current-db-size":"59 MB","current-db-size-in-use-bytes":30896128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-19T00:24:31.614981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2027757776,"revision":28856,"compact-revision":26181} {"level":"info","ts":"2026-08-19T00:29:31.488254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31111} {"level":"info","ts":"2026-08-19T00:29:31.611455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31111,"took":"118.757147ms","hash":1064171540,"current-db-size-bytes":59117568,"current-db-size":"59 MB","current-db-size-in-use-bytes":30810112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-19T00:29:31.611552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1064171540,"revision":31111,"compact-revision":28856} {"level":"info","ts":"2026-08-19T00:34:31.493658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33379} {"level":"info","ts":"2026-08-19T00:34:31.631544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33379,"took":"134.912317ms","hash":2253782837,"current-db-size-bytes":60162048,"current-db-size":"60 MB","current-db-size-in-use-bytes":32194560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-19T00:34:31.631622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2253782837,"revision":33379,"compact-revision":31111} {"level":"info","ts":"2026-08-19T00:39:31.499335Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35673} {"level":"info","ts":"2026-08-19T00:39:31.637152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35673,"took":"133.133474ms","hash":4155402411,"current-db-size-bytes":61456384,"current-db-size":"62 MB","current-db-size-in-use-bytes":31948800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-19T00:39:31.637218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4155402411,"revision":35673,"compact-revision":33379} {"level":"info","ts":"2026-08-19T00:41:21.168903Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":40006,"local-member-snapshot-index":30005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T00:41:21.1735Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40006} {"level":"info","ts":"2026-08-19T00:41:21.173678Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35006}