{"level":"warn","ts":"2026-10-17T10:42:42.890639Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:42:42.890763Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.19:2380","--initial-cluster=instance=https://162.253.55.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.19: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-10-17T10:42:42.890831Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:42:42.890846Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-10-17T10:42:42.890864Z","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-10-17T10:42:42.891448Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"]} {"level":"info","ts":"2026-10-17T10:42:42.891519Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.19: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-10-17T10:42:42.898766Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.035325ms"} {"level":"info","ts":"2026-10-17T10:42:42.909732Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ded76e421897a43d","cluster-id":"8853d1107d87769b"} {"level":"info","ts":"2026-10-17T10:42:42.909831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T10:42:42.909856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 0"} {"level":"info","ts":"2026-10-17T10:42:42.909871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ded76e421897a43d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T10:42:42.909887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 1"} {"level":"info","ts":"2026-10-17T10:42:42.909931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"warn","ts":"2026-10-17T10:42:42.920799Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T10:42:42.922548Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T10:42:42.923936Z","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-10-17T10:42:42.92572Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ded76e421897a43d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T10:42:42.925938Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ded76e421897a43d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T10:42:42.926027Z","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-10-17T10:42:42.926415Z","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-10-17T10:42:42.926439Z","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-10-17T10:42:42.927161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"info","ts":"2026-10-17T10:42:42.927767Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","added-peer-id":"ded76e421897a43d","added-peer-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-10-17T10:42:42.93214Z","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-10-17T10:42:42.93232Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ded76e421897a43d","initial-advertise-peer-urls":["https://162.253.55.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T10:42:42.932343Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T10:42:42.932433Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-10-17T10:42:42.932444Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-10-17T10:42:43.110786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T10:42:43.110849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T10:42:43.110899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgPreVoteResp from ded76e421897a43d at term 1"} {"level":"info","ts":"2026-10-17T10:42:43.110918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became candidate at term 2"} {"level":"info","ts":"2026-10-17T10:42:43.110923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgVoteResp from ded76e421897a43d at term 2"} {"level":"info","ts":"2026-10-17T10:42:43.110931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became leader at term 2"} {"level":"info","ts":"2026-10-17T10:42:43.11094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ded76e421897a43d elected leader ded76e421897a43d at term 2"} {"level":"info","ts":"2026-10-17T10:42:43.11298Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ded76e421897a43d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.19:2379]}","request-path":"/0/members/ded76e421897a43d/attributes","cluster-id":"8853d1107d87769b","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T10:42:43.113049Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:42:43.117119Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:42:43.119288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:42:43.122076Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:42:43.122311Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:42:43.122418Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:42:43.121727Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T10:42:43.12252Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T10:42:43.122799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.19:2379"} {"level":"info","ts":"2026-10-17T10:42:43.125717Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T10:43:33.659458Z","caller":"traceutil/trace.go:171","msg":"trace[1680096080] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"135.642528ms","start":"2026-10-17T10:43:33.523799Z","end":"2026-10-17T10:43:33.659442Z","steps":["trace[1680096080] 'process raft request' (duration: 134.63436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:43:33.852105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.078812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:43:33.852277Z","caller":"traceutil/trace.go:171","msg":"trace[1068722203] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"168.914746ms","start":"2026-10-17T10:43:33.683346Z","end":"2026-10-17T10:43:33.852261Z","steps":["trace[1068722203] 'read index received' (duration: 49.693673ms)","trace[1068722203] 'applied index is now lower than readState.Index' (duration: 119.219833ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:43:33.852342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.989788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-29g6b\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-17T10:43:33.852366Z","caller":"traceutil/trace.go:171","msg":"trace[2078603195] range","detail":"{range_begin:/registry/pods/kube-system/cilium-29g6b; range_end:; response_count:1; response_revision:650; }","duration":"169.038049ms","start":"2026-10-17T10:43:33.683319Z","end":"2026-10-17T10:43:33.852357Z","steps":["trace[2078603195] 'agreement among raft nodes before linearized reading' (duration: 168.980728ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:43:33.852354Z","caller":"traceutil/trace.go:171","msg":"trace[1120244103] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"185.580887ms","start":"2026-10-17T10:43:33.666741Z","end":"2026-10-17T10:43:33.852322Z","steps":["trace[1120244103] 'process raft request' (duration: 66.363023ms)","trace[1120244103] 'compare' (duration: 117.988431ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:43:34.080191Z","caller":"traceutil/trace.go:171","msg":"trace[1796849082] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"217.129406ms","start":"2026-10-17T10:43:33.863036Z","end":"2026-10-17T10:43:34.080165Z","steps":["trace[1796849082] 'process raft request' (duration: 217.052755ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:43:34.080242Z","caller":"traceutil/trace.go:171","msg":"trace[1933621890] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"322.601991ms","start":"2026-10-17T10:43:33.757613Z","end":"2026-10-17T10:43:34.080215Z","steps":["trace[1933621890] 'process raft request' (duration: 257.937295ms)","trace[1933621890] 'compare' (duration: 64.283159ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:43:34.080364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:43:33.757597Z","time spent":"322.693072ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T10:43:34.615896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.552256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-17T10:43:34.615989Z","caller":"traceutil/trace.go:171","msg":"trace[1434473138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"227.683728ms","start":"2026-10-17T10:43:34.388284Z","end":"2026-10-17T10:43:34.615967Z","steps":["trace[1434473138] 'range keys from in-memory index tree' (duration: 227.359703ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:43:35.981603Z","caller":"traceutil/trace.go:171","msg":"trace[242994096] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"122.900924ms","start":"2026-10-17T10:43:35.858683Z","end":"2026-10-17T10:43:35.981584Z","steps":["trace[242994096] 'process raft request' (duration: 120.105444ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:43:35.981538Z","caller":"traceutil/trace.go:171","msg":"trace[687431785] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"109.654428ms","start":"2026-10-17T10:43:35.871857Z","end":"2026-10-17T10:43:35.981512Z","steps":["trace[687431785] 'read index received' (duration: 106.960181ms)","trace[687431785] 'applied index is now lower than readState.Index' (duration: 2.692697ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:43:35.981926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.047625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:43:35.982045Z","caller":"traceutil/trace.go:171","msg":"trace[435688084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:676; }","duration":"110.199477ms","start":"2026-10-17T10:43:35.871829Z","end":"2026-10-17T10:43:35.982029Z","steps":["trace[435688084] 'agreement among raft nodes before linearized reading' (duration: 109.808461ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:44:43.914909Z","caller":"traceutil/trace.go:171","msg":"trace[2010101414] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"129.113184ms","start":"2026-10-17T10:44:43.78577Z","end":"2026-10-17T10:44:43.914883Z","steps":["trace[2010101414] 'process raft request' (duration: 128.901331ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:45:26.596835Z","caller":"traceutil/trace.go:171","msg":"trace[896727753] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"124.839989ms","start":"2026-10-17T10:45:26.471965Z","end":"2026-10-17T10:45:26.596805Z","steps":["trace[896727753] 'process raft request' (duration: 124.672507ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:45:41.530138Z","caller":"traceutil/trace.go:171","msg":"trace[237529619] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"227.308138ms","start":"2026-10-17T10:45:41.302809Z","end":"2026-10-17T10:45:41.530117Z","steps":["trace[237529619] 'process raft request' (duration: 227.149906ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:45:41.637247Z","caller":"traceutil/trace.go:171","msg":"trace[1126233897] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"241.720813ms","start":"2026-10-17T10:45:41.395492Z","end":"2026-10-17T10:45:41.637213Z","steps":["trace[1126233897] 'process raft request' (duration: 172.033624ms)","trace[1126233897] 'compare' (duration: 69.248084ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:45:42.039113Z","caller":"traceutil/trace.go:171","msg":"trace[176169852] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"142.492109ms","start":"2026-10-17T10:45:41.896592Z","end":"2026-10-17T10:45:42.039084Z","steps":["trace[176169852] 'process raft request' (duration: 112.94829ms)","trace[176169852] 'compare' (duration: 29.318319ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:45:42.956684Z","caller":"traceutil/trace.go:171","msg":"trace[315930388] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"125.356975ms","start":"2026-10-17T10:45:42.831305Z","end":"2026-10-17T10:45:42.956662Z","steps":["trace[315930388] 'process raft request' (duration: 124.81534ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:45:43.32507Z","caller":"traceutil/trace.go:171","msg":"trace[1617029733] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2208; }","duration":"242.408067ms","start":"2026-10-17T10:45:43.082639Z","end":"2026-10-17T10:45:43.325047Z","steps":["trace[1617029733] 'read index received' (duration: 241.996566ms)","trace[1617029733] 'applied index is now lower than readState.Index' (duration: 410.571µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:45:43.325136Z","caller":"traceutil/trace.go:171","msg":"trace[313705580] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"364.061545ms","start":"2026-10-17T10:45:42.961045Z","end":"2026-10-17T10:45:43.325107Z","steps":["trace[313705580] 'process raft request' (duration: 363.650296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:45:43.325248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.570764ms","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:7"} {"level":"info","ts":"2026-10-17T10:45:43.325316Z","caller":"traceutil/trace.go:171","msg":"trace[1669641632] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2133; }","duration":"242.70661ms","start":"2026-10-17T10:45:43.082593Z","end":"2026-10-17T10:45:43.3253Z","steps":["trace[1669641632] 'agreement among raft nodes before linearized reading' (duration: 242.555423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:45:43.325349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:45:42.961021Z","time spent":"364.176941ms","remote":"127.0.0.1:55262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:45:43.32535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.924185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-17T10:45:43.325523Z","caller":"traceutil/trace.go:171","msg":"trace[135913332] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2133; }","duration":"238.117074ms","start":"2026-10-17T10:45:43.087382Z","end":"2026-10-17T10:45:43.325499Z","steps":["trace[135913332] 'agreement among raft nodes before linearized reading' (duration: 237.837791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:47:32.850329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.179751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:47:32.850447Z","caller":"traceutil/trace.go:171","msg":"trace[971303187] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"264.05223ms","start":"2026-10-17T10:47:32.586375Z","end":"2026-10-17T10:47:32.850428Z","steps":["trace[971303187] 'process raft request' (duration: 90.694094ms)","trace[971303187] 'compare' (duration: 173.072668ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:47:38.449987Z","caller":"traceutil/trace.go:171","msg":"trace[947459275] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"125.450397ms","start":"2026-10-17T10:47:38.324503Z","end":"2026-10-17T10:47:38.449953Z","steps":["trace[947459275] 'process raft request' (duration: 125.238861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:51:58.516684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.039417ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:51:58.516922Z","caller":"traceutil/trace.go:171","msg":"trace[1567013453] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4826; }","duration":"150.343072ms","start":"2026-10-17T10:51:58.366557Z","end":"2026-10-17T10:51:58.5169Z","steps":["trace[1567013453] 'range keys from in-memory index tree' (duration: 150.019896ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:51:58.660153Z","caller":"traceutil/trace.go:171","msg":"trace[1129905647] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5008; }","duration":"361.80272ms","start":"2026-10-17T10:51:58.298317Z","end":"2026-10-17T10:51:58.660119Z","steps":["trace[1129905647] 'read index received' (duration: 361.562496ms)","trace[1129905647] 'applied index is now lower than readState.Index' (duration: 238.994µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:51:58.660368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.028134ms","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-10-17T10:51:58.660434Z","caller":"traceutil/trace.go:171","msg":"trace[312323805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4827; }","duration":"362.127205ms","start":"2026-10-17T10:51:58.298292Z","end":"2026-10-17T10:51:58.660419Z","steps":["trace[312323805] 'agreement among raft nodes before linearized reading' (duration: 361.941302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:51:58.660462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.172447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-17T10:51:58.660486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.188434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39772c5d-59a1-46bc-995a-eb0c9df9e11e\" ","response":"range_response_count:1 size:4063"} {"level":"info","ts":"2026-10-17T10:51:58.6602Z","caller":"traceutil/trace.go:171","msg":"trace[1535991527] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"366.238546ms","start":"2026-10-17T10:51:58.293938Z","end":"2026-10-17T10:51:58.660177Z","steps":["trace[1535991527] 'process raft request' (duration: 366.011442ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:51:58.660535Z","caller":"traceutil/trace.go:171","msg":"trace[403708402] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4827; }","duration":"329.272929ms","start":"2026-10-17T10:51:58.331244Z","end":"2026-10-17T10:51:58.660517Z","steps":["trace[403708402] 'agreement among raft nodes before linearized reading' (duration: 329.040065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:51:58.660582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:51:58.331232Z","time spent":"329.33825ms","remote":"127.0.0.1:55262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-17T10:51:58.660572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.746442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f0e3914f-12bf-43b5-8704-651cdf018a1e\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-10-17T10:51:58.660533Z","caller":"traceutil/trace.go:171","msg":"trace[267446843] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39772c5d-59a1-46bc-995a-eb0c9df9e11e; range_end:; response_count:1; response_revision:4827; }","duration":"231.239545ms","start":"2026-10-17T10:51:58.429281Z","end":"2026-10-17T10:51:58.66052Z","steps":["trace[267446843] 'agreement among raft nodes before linearized reading' (duration: 231.098142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:51:58.660484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:51:58.298286Z","time spent":"362.185767ms","remote":"127.0.0.1:55262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-17T10:51:58.660741Z","caller":"traceutil/trace.go:171","msg":"trace[1227600045] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f0e3914f-12bf-43b5-8704-651cdf018a1e; range_end:; response_count:1; response_revision:4827; }","duration":"106.953195ms","start":"2026-10-17T10:51:58.553751Z","end":"2026-10-17T10:51:58.660704Z","steps":["trace[1227600045] 'agreement among raft nodes before linearized reading' (duration: 106.548938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:51:58.661046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:51:58.293921Z","time spent":"366.639342ms","remote":"127.0.0.1:55244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:51:58.803349Z","caller":"traceutil/trace.go:171","msg":"trace[933797593] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"136.543958ms","start":"2026-10-17T10:51:58.666777Z","end":"2026-10-17T10:51:58.80332Z","steps":["trace[933797593] 'process raft request' (duration: 82.566172ms)","trace[933797593] 'compare' (duration: 53.513298ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:51:58.813289Z","caller":"traceutil/trace.go:171","msg":"trace[1800973102] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"146.391544ms","start":"2026-10-17T10:51:58.666877Z","end":"2026-10-17T10:51:58.813268Z","steps":["trace[1800973102] 'process raft request' (duration: 146.230891ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:51:58.813608Z","caller":"traceutil/trace.go:171","msg":"trace[368069584] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"143.589098ms","start":"2026-10-17T10:51:58.669985Z","end":"2026-10-17T10:51:58.813574Z","steps":["trace[368069584] 'process raft request' (duration: 143.209601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:52:08.719259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.465481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:52:08.719592Z","caller":"traceutil/trace.go:171","msg":"trace[408349662] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"190.792035ms","start":"2026-10-17T10:52:08.528781Z","end":"2026-10-17T10:52:08.719573Z","steps":["trace[408349662] 'process raft request' (duration: 12.913087ms)","trace[408349662] 'compare' (duration: 177.348949ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:52:09.067026Z","caller":"traceutil/trace.go:171","msg":"trace[1377548492] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5229; }","duration":"135.93039ms","start":"2026-10-17T10:52:08.931075Z","end":"2026-10-17T10:52:09.067005Z","steps":["trace[1377548492] 'read index received' (duration: 135.865869ms)","trace[1377548492] 'applied index is now lower than readState.Index' (duration: 63.651µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:52:09.067233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.126693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-17T10:52:09.067272Z","caller":"traceutil/trace.go:171","msg":"trace[1718185924] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5041; }","duration":"136.215905ms","start":"2026-10-17T10:52:08.931044Z","end":"2026-10-17T10:52:09.06726Z","steps":["trace[1718185924] 'agreement among raft nodes before linearized reading' (duration: 136.058332ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:09.067058Z","caller":"traceutil/trace.go:171","msg":"trace[2140239467] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"138.716457ms","start":"2026-10-17T10:52:08.928319Z","end":"2026-10-17T10:52:09.067035Z","steps":["trace[2140239467] 'process raft request' (duration: 138.583345ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:09.118948Z","caller":"traceutil/trace.go:171","msg":"trace[1449887937] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"146.790772ms","start":"2026-10-17T10:52:08.972095Z","end":"2026-10-17T10:52:09.118885Z","steps":["trace[1449887937] 'process raft request' (duration: 146.566878ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:22.649031Z","caller":"traceutil/trace.go:171","msg":"trace[795629206] linearizableReadLoop","detail":"{readStateIndex:5411; appliedIndex:5410; }","duration":"210.687095ms","start":"2026-10-17T10:52:22.438318Z","end":"2026-10-17T10:52:22.649005Z","steps":["trace[795629206] 'read index received' (duration: 210.459751ms)","trace[795629206] 'applied index is now lower than readState.Index' (duration: 226.194µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:52:22.649454Z","caller":"traceutil/trace.go:171","msg":"trace[1400011617] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"219.508232ms","start":"2026-10-17T10:52:22.429931Z","end":"2026-10-17T10:52:22.649439Z","steps":["trace[1400011617] 'process raft request' (duration: 218.882392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:52:22.649767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.441467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-17T10:52:22.649808Z","caller":"traceutil/trace.go:171","msg":"trace[402346698] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5215; }","duration":"211.524348ms","start":"2026-10-17T10:52:22.438271Z","end":"2026-10-17T10:52:22.649796Z","steps":["trace[402346698] 'agreement among raft nodes before linearized reading' (duration: 211.385186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:52:22.650002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.542522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3315"} {"level":"info","ts":"2026-10-17T10:52:22.650035Z","caller":"traceutil/trace.go:171","msg":"trace[880113752] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5215; }","duration":"210.611804ms","start":"2026-10-17T10:52:22.439415Z","end":"2026-10-17T10:52:22.650027Z","steps":["trace[880113752] 'agreement among raft nodes before linearized reading' (duration: 210.525712ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:22.851103Z","caller":"traceutil/trace.go:171","msg":"trace[891342310] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"190.173854ms","start":"2026-10-17T10:52:22.660898Z","end":"2026-10-17T10:52:22.851072Z","steps":["trace[891342310] 'process raft request' (duration: 176.739269ms)","trace[891342310] 'compare' (duration: 13.13421ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:52:22.855703Z","caller":"traceutil/trace.go:171","msg":"trace[39106164] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"193.775433ms","start":"2026-10-17T10:52:22.661902Z","end":"2026-10-17T10:52:22.855677Z","steps":["trace[39106164] 'process raft request' (duration: 193.58048ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:22.855994Z","caller":"traceutil/trace.go:171","msg":"trace[1683860810] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"194.059028ms","start":"2026-10-17T10:52:22.661915Z","end":"2026-10-17T10:52:22.855974Z","steps":["trace[1683860810] 'process raft request' (duration: 193.723812ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:22.856327Z","caller":"traceutil/trace.go:171","msg":"trace[674053897] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"192.196326ms","start":"2026-10-17T10:52:22.664118Z","end":"2026-10-17T10:52:22.856314Z","steps":["trace[674053897] 'process raft request' (duration: 191.707388ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:23.02595Z","caller":"traceutil/trace.go:171","msg":"trace[1370230913] linearizableReadLoop","detail":"{readStateIndex:5417; appliedIndex:5416; }","duration":"111.778765ms","start":"2026-10-17T10:52:22.914142Z","end":"2026-10-17T10:52:23.025921Z","steps":["trace[1370230913] 'read index received' (duration: 43.917682ms)","trace[1370230913] 'applied index is now lower than readState.Index' (duration: 67.859933ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:52:23.026027Z","caller":"traceutil/trace.go:171","msg":"trace[1157714280] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"150.684804ms","start":"2026-10-17T10:52:22.875297Z","end":"2026-10-17T10:52:23.025982Z","steps":["trace[1157714280] 'process raft request' (duration: 82.853762ms)","trace[1157714280] 'compare' (duration: 67.618819ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:52:23.02626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.09798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:52:23.026307Z","caller":"traceutil/trace.go:171","msg":"trace[1559142280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5221; }","duration":"112.193131ms","start":"2026-10-17T10:52:22.914103Z","end":"2026-10-17T10:52:23.026296Z","steps":["trace[1559142280] 'agreement among raft nodes before linearized reading' (duration: 112.0859ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:23.256156Z","caller":"traceutil/trace.go:171","msg":"trace[1618353968] linearizableReadLoop","detail":"{readStateIndex:5421; appliedIndex:5420; }","duration":"117.998337ms","start":"2026-10-17T10:52:23.138119Z","end":"2026-10-17T10:52:23.256117Z","steps":["trace[1618353968] 'read index received' (duration: 69.161752ms)","trace[1618353968] 'applied index is now lower than readState.Index' (duration: 48.834595ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:52:23.256588Z","caller":"traceutil/trace.go:171","msg":"trace[90669814] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"139.778261ms","start":"2026-10-17T10:52:23.11678Z","end":"2026-10-17T10:52:23.256558Z","steps":["trace[90669814] 'process raft request' (duration: 90.58279ms)","trace[90669814] 'compare' (duration: 48.51927ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:52:23.257367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.226717ms","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-10-17T10:52:23.257476Z","caller":"traceutil/trace.go:171","msg":"trace[1435760695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5225; }","duration":"119.38826ms","start":"2026-10-17T10:52:23.138069Z","end":"2026-10-17T10:52:23.257457Z","steps":["trace[1435760695] 'agreement among raft nodes before linearized reading' (duration: 119.033384ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:23.275886Z","caller":"traceutil/trace.go:171","msg":"trace[407725787] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"108.600561ms","start":"2026-10-17T10:52:23.167262Z","end":"2026-10-17T10:52:23.275863Z","steps":["trace[407725787] 'process raft request' (duration: 108.474579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:52:23.276109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.122435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:52:23.276193Z","caller":"traceutil/trace.go:171","msg":"trace[469644972] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5226; }","duration":"107.240338ms","start":"2026-10-17T10:52:23.168931Z","end":"2026-10-17T10:52:23.276171Z","steps":["trace[469644972] 'agreement among raft nodes before linearized reading' (duration: 107.018175ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:52:43.430882Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2883} {"level":"info","ts":"2026-10-17T10:52:43.51063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2883,"took":"78.25244ms","hash":1184362821,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":27754496,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-17T10:52:43.510697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1184362821,"revision":2883,"compact-revision":-1} {"level":"warn","ts":"2026-10-17T10:53:01.751026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.466767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:53:01.751113Z","caller":"traceutil/trace.go:171","msg":"trace[988529766] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5564; }","duration":"117.601759ms","start":"2026-10-17T10:53:01.633493Z","end":"2026-10-17T10:53:01.751095Z","steps":["trace[988529766] 'range keys from in-memory index tree' (duration: 117.367796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:53:01.751562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.78321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"warn","ts":"2026-10-17T10:53:01.751543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.941686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:53:01.751642Z","caller":"traceutil/trace.go:171","msg":"trace[812265229] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5564; }","duration":"107.863031ms","start":"2026-10-17T10:53:01.64375Z","end":"2026-10-17T10:53:01.751613Z","steps":["trace[812265229] 'range keys from in-memory index tree' (duration: 107.622137ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:53:01.751642Z","caller":"traceutil/trace.go:171","msg":"trace[1128347862] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5564; }","duration":"118.098448ms","start":"2026-10-17T10:53:01.633525Z","end":"2026-10-17T10:53:01.751624Z","steps":["trace[1128347862] 'range keys from in-memory index tree' (duration: 117.861805ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:53:01.782991Z","caller":"traceutil/trace.go:171","msg":"trace[176929555] transaction","detail":"{read_only:false; response_revision:5565; number_of_response:1; }","duration":"122.474159ms","start":"2026-10-17T10:53:01.66049Z","end":"2026-10-17T10:53:01.782964Z","steps":["trace[176929555] 'process raft request' (duration: 122.299316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:53:02.363502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.981212ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-17T10:57:43.437233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5363} {"level":"info","ts":"2026-10-17T10:57:43.541073Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5363,"took":"102.318782ms","hash":1822783828,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":18644992,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-17T10:57:43.54114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1822783828,"revision":5363,"compact-revision":2883}