{"level":"warn","ts":"2026-10-26T21:24:07.006603Z","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-26T21:24:07.006683Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.241: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.241:2380","--initial-cluster=instance=https://162.253.55.241:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.241:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.241: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-26T21:24:07.006739Z","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-26T21:24:07.006756Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-10-26T21:24:07.006773Z","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-26T21:24:07.007361Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"]} {"level":"info","ts":"2026-10-26T21:24:07.00753Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.241: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-26T21:24:07.011651Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.882448ms"} {"level":"info","ts":"2026-10-26T21:24:07.024296Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f50d212ec16cc59","cluster-id":"30c503bd66239e72"} {"level":"info","ts":"2026-10-26T21:24:07.024377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T21:24:07.0244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 0"} {"level":"info","ts":"2026-10-26T21:24:07.024416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f50d212ec16cc59 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T21:24:07.024427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 1"} {"level":"info","ts":"2026-10-26T21:24:07.024466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"warn","ts":"2026-10-26T21:24:07.029682Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T21:24:07.032373Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T21:24:07.033915Z","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-26T21:24:07.03627Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f50d212ec16cc59","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T21:24:07.037271Z","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-26T21:24:07.037376Z","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-26T21:24:07.037393Z","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-26T21:24:07.037286Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f50d212ec16cc59","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T21:24:07.039239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"info","ts":"2026-10-26T21:24:07.039458Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","added-peer-id":"5f50d212ec16cc59","added-peer-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-10-26T21:24:07.040617Z","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-26T21:24:07.040749Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-10-26T21:24:07.04081Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-10-26T21:24:07.040856Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f50d212ec16cc59","initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T21:24:07.040889Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T21:24:07.225048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T21:24:07.225121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T21:24:07.225163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgPreVoteResp from 5f50d212ec16cc59 at term 1"} {"level":"info","ts":"2026-10-26T21:24:07.225209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became candidate at term 2"} {"level":"info","ts":"2026-10-26T21:24:07.225221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgVoteResp from 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-10-26T21:24:07.225241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became leader at term 2"} {"level":"info","ts":"2026-10-26T21:24:07.225255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f50d212ec16cc59 elected leader 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-10-26T21:24:07.226335Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:24:07.227513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:24:07.227517Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f50d212ec16cc59","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.241:2379]}","request-path":"/0/members/5f50d212ec16cc59/attributes","cluster-id":"30c503bd66239e72","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T21:24:07.227649Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:24:07.228047Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T21:24:07.228065Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:24:07.228101Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T21:24:07.228219Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:24:07.228259Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:24:07.231119Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T21:24:07.231211Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.241:2379"} {"level":"info","ts":"2026-10-26T21:24:52.585591Z","caller":"traceutil/trace.go:171","msg":"trace[75907008] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"131.90647ms","start":"2026-10-26T21:24:52.453657Z","end":"2026-10-26T21:24:52.585563Z","steps":["trace[75907008] 'process raft request' (duration: 131.641313ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.585643Z","caller":"traceutil/trace.go:171","msg":"trace[5337919] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"128.461034ms","start":"2026-10-26T21:24:52.457152Z","end":"2026-10-26T21:24:52.585613Z","steps":["trace[5337919] 'process raft request' (duration: 128.257499ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.58616Z","caller":"traceutil/trace.go:171","msg":"trace[1852502165] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"128.849805ms","start":"2026-10-26T21:24:52.457281Z","end":"2026-10-26T21:24:52.586131Z","steps":["trace[1852502165] 'process raft request' (duration: 128.220007ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.586882Z","caller":"traceutil/trace.go:171","msg":"trace[280060785] transaction","detail":"{read_only:false; number_of_response:1; response_revision:510; }","duration":"129.344618ms","start":"2026-10-26T21:24:52.457526Z","end":"2026-10-26T21:24:52.58687Z","steps":["trace[280060785] 'process raft request' (duration: 128.498225ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.587037Z","caller":"traceutil/trace.go:171","msg":"trace[1868548535] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"120.711179ms","start":"2026-10-26T21:24:52.466317Z","end":"2026-10-26T21:24:52.587028Z","steps":["trace[1868548535] 'process raft request' (duration: 120.178464ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.587133Z","caller":"traceutil/trace.go:171","msg":"trace[2074573771] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"118.128766ms","start":"2026-10-26T21:24:52.468995Z","end":"2026-10-26T21:24:52.587124Z","steps":["trace[2074573771] 'process raft request' (duration: 117.555701ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.587233Z","caller":"traceutil/trace.go:171","msg":"trace[337074583] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"107.924973ms","start":"2026-10-26T21:24:52.479251Z","end":"2026-10-26T21:24:52.587176Z","steps":["trace[337074583] 'process raft request' (duration: 107.44432ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.587426Z","caller":"traceutil/trace.go:171","msg":"trace[2023263456] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"115.479634ms","start":"2026-10-26T21:24:52.471933Z","end":"2026-10-26T21:24:52.587413Z","steps":["trace[2023263456] 'process raft request' (duration: 114.655351ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.587531Z","caller":"traceutil/trace.go:171","msg":"trace[709961645] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"112.372937ms","start":"2026-10-26T21:24:52.475145Z","end":"2026-10-26T21:24:52.587518Z","steps":["trace[709961645] 'process raft request' (duration: 111.478692ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.58765Z","caller":"traceutil/trace.go:171","msg":"trace[1586066311] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"112.054608ms","start":"2026-10-26T21:24:52.475562Z","end":"2026-10-26T21:24:52.587617Z","steps":["trace[1586066311] 'process raft request' (duration: 111.096422ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.621068Z","caller":"traceutil/trace.go:171","msg":"trace[164104486] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"106.537354ms","start":"2026-10-26T21:24:52.514511Z","end":"2026-10-26T21:24:52.621049Z","steps":["trace[164104486] 'process raft request' (duration: 106.153954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:24:52.783722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.594223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:24:52.783923Z","caller":"traceutil/trace.go:171","msg":"trace[1340219845] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"191.911154ms","start":"2026-10-26T21:24:52.591978Z","end":"2026-10-26T21:24:52.783889Z","steps":["trace[1340219845] 'process raft request' (duration: 58.569819ms)","trace[1340219845] 'compare' (duration: 132.45477ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:24:52.784229Z","caller":"traceutil/trace.go:171","msg":"trace[933886715] transaction","detail":"{read_only:false; number_of_response:1; response_revision:524; }","duration":"177.046271ms","start":"2026-10-26T21:24:52.607166Z","end":"2026-10-26T21:24:52.784212Z","steps":["trace[933886715] 'process raft request' (duration: 176.968688ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.784316Z","caller":"traceutil/trace.go:171","msg":"trace[1654309573] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"191.610236ms","start":"2026-10-26T21:24:52.592693Z","end":"2026-10-26T21:24:52.784303Z","steps":["trace[1654309573] 'process raft request' (duration: 191.276257ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.784421Z","caller":"traceutil/trace.go:171","msg":"trace[1887168335] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"185.150986ms","start":"2026-10-26T21:24:52.59926Z","end":"2026-10-26T21:24:52.784411Z","steps":["trace[1887168335] 'process raft request' (duration: 184.804906ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.784495Z","caller":"traceutil/trace.go:171","msg":"trace[1314154595] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"191.981426ms","start":"2026-10-26T21:24:52.592502Z","end":"2026-10-26T21:24:52.784483Z","steps":["trace[1314154595] 'process raft request' (duration: 191.38793ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.784623Z","caller":"traceutil/trace.go:171","msg":"trace[252237407] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"179.007825ms","start":"2026-10-26T21:24:52.605588Z","end":"2026-10-26T21:24:52.784596Z","steps":["trace[252237407] 'process raft request' (duration: 178.511221ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.850311Z","caller":"traceutil/trace.go:171","msg":"trace[336275934] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"227.651788ms","start":"2026-10-26T21:24:52.622636Z","end":"2026-10-26T21:24:52.850288Z","steps":["trace[336275934] 'process raft request' (duration: 227.408771ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.850595Z","caller":"traceutil/trace.go:171","msg":"trace[979160588] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"216.943731ms","start":"2026-10-26T21:24:52.633622Z","end":"2026-10-26T21:24:52.850566Z","steps":["trace[979160588] 'process raft request' (duration: 216.614161ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.850673Z","caller":"traceutil/trace.go:171","msg":"trace[1516799532] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"217.061773ms","start":"2026-10-26T21:24:52.633601Z","end":"2026-10-26T21:24:52.850663Z","steps":["trace[1516799532] 'process raft request' (duration: 216.873348ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.850719Z","caller":"traceutil/trace.go:171","msg":"trace[30587874] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"216.393585ms","start":"2026-10-26T21:24:52.634309Z","end":"2026-10-26T21:24:52.850703Z","steps":["trace[30587874] 'process raft request' (duration: 216.293413ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.850851Z","caller":"traceutil/trace.go:171","msg":"trace[1580616466] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:542; }","duration":"214.536633ms","start":"2026-10-26T21:24:52.636307Z","end":"2026-10-26T21:24:52.850844Z","steps":["trace[1580616466] 'read index received' (duration: 14.317238ms)","trace[1580616466] 'applied index is now lower than readState.Index' (duration: 200.217825ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:24:52.850858Z","caller":"traceutil/trace.go:171","msg":"trace[816260478] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"213.978259ms","start":"2026-10-26T21:24:52.636872Z","end":"2026-10-26T21:24:52.85085Z","steps":["trace[816260478] 'process raft request' (duration: 213.794323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:24:52.850941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.613845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:24:52.850968Z","caller":"traceutil/trace.go:171","msg":"trace[284786300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"214.680178ms","start":"2026-10-26T21:24:52.63628Z","end":"2026-10-26T21:24:52.85096Z","steps":["trace[284786300] 'agreement among raft nodes before linearized reading' (duration: 214.595045ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.979352Z","caller":"traceutil/trace.go:171","msg":"trace[600608064] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"185.54839ms","start":"2026-10-26T21:24:52.793773Z","end":"2026-10-26T21:24:52.979321Z","steps":["trace[600608064] 'process raft request' (duration: 174.383478ms)","trace[600608064] 'compare' (duration: 10.890624ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:24:52.979669Z","caller":"traceutil/trace.go:171","msg":"trace[1055664725] transaction","detail":"{read_only:false; number_of_response:1; response_revision:530; }","duration":"184.793058ms","start":"2026-10-26T21:24:52.794829Z","end":"2026-10-26T21:24:52.979622Z","steps":["trace[1055664725] 'process raft request' (duration: 184.445058ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.979828Z","caller":"traceutil/trace.go:171","msg":"trace[1966222209] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:553; }","duration":"127.868476ms","start":"2026-10-26T21:24:52.851934Z","end":"2026-10-26T21:24:52.979803Z","steps":["trace[1966222209] 'read index received' (duration: 116.309953ms)","trace[1966222209] 'applied index is now lower than readState.Index' (duration: 11.557343ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:24:52.979863Z","caller":"traceutil/trace.go:171","msg":"trace[1282411470] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"175.816457ms","start":"2026-10-26T21:24:52.804032Z","end":"2026-10-26T21:24:52.979848Z","steps":["trace[1282411470] 'process raft request' (duration: 175.440427ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.979931Z","caller":"traceutil/trace.go:171","msg":"trace[1955147372] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"166.291791ms","start":"2026-10-26T21:24:52.81362Z","end":"2026-10-26T21:24:52.979912Z","steps":["trace[1955147372] 'process raft request' (duration: 165.990693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:24:52.980258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.304768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:24:52.980303Z","caller":"traceutil/trace.go:171","msg":"trace[1890480611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"128.400611ms","start":"2026-10-26T21:24:52.851892Z","end":"2026-10-26T21:24:52.980292Z","steps":["trace[1890480611] 'agreement among raft nodes before linearized reading' (duration: 127.99674ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:52.980326Z","caller":"traceutil/trace.go:171","msg":"trace[1529027875] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"120.49495ms","start":"2026-10-26T21:24:52.859806Z","end":"2026-10-26T21:24:52.980301Z","steps":["trace[1529027875] 'process raft request' (duration: 119.950075ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:58.364344Z","caller":"traceutil/trace.go:171","msg":"trace[1660257303] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"175.56945ms","start":"2026-10-26T21:24:58.18874Z","end":"2026-10-26T21:24:58.36431Z","steps":["trace[1660257303] 'process raft request' (duration: 174.987904ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:58.84275Z","caller":"traceutil/trace.go:171","msg":"trace[1920213131] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"407.887169ms","start":"2026-10-26T21:24:58.434838Z","end":"2026-10-26T21:24:58.842726Z","steps":["trace[1920213131] 'process raft request' (duration: 407.698703ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:24:58.842781Z","caller":"traceutil/trace.go:171","msg":"trace[950762474] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"209.190511ms","start":"2026-10-26T21:24:58.633558Z","end":"2026-10-26T21:24:58.842749Z","steps":["trace[950762474] 'read index received' (duration: 208.877032ms)","trace[950762474] 'applied index is now lower than readState.Index' (duration: 244.257µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:24:58.842878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:24:58.434812Z","time spent":"408.004922ms","remote":"127.0.0.1:54304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T21:24:58.843058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.431388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:24:58.843121Z","caller":"traceutil/trace.go:171","msg":"trace[1860162263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"209.584792ms","start":"2026-10-26T21:24:58.633506Z","end":"2026-10-26T21:24:58.843091Z","steps":["trace[1860162263] 'agreement among raft nodes before linearized reading' (duration: 209.417927ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:26:29.867325Z","caller":"traceutil/trace.go:171","msg":"trace[1112797013] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"103.537919ms","start":"2026-10-26T21:26:29.76376Z","end":"2026-10-26T21:26:29.867297Z","steps":["trace[1112797013] 'process raft request' (duration: 103.373554ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:26:30.164381Z","caller":"traceutil/trace.go:171","msg":"trace[902300276] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"216.223212ms","start":"2026-10-26T21:26:29.948125Z","end":"2026-10-26T21:26:30.164348Z","steps":["trace[902300276] 'process raft request' (duration: 216.15636ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:26:30.164697Z","caller":"traceutil/trace.go:171","msg":"trace[817197084] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1904; }","duration":"147.70608ms","start":"2026-10-26T21:26:30.016969Z","end":"2026-10-26T21:26:30.164675Z","steps":["trace[817197084] 'read index received' (duration: 147.6993ms)","trace[817197084] 'applied index is now lower than readState.Index' (duration: 5.51µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:26:30.164794Z","caller":"traceutil/trace.go:171","msg":"trace[1252622575] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"216.653794ms","start":"2026-10-26T21:26:29.94811Z","end":"2026-10-26T21:26:30.164764Z","steps":["trace[1252622575] 'process raft request' (duration: 215.939064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:26:30.164917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.210164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T21:26:30.164957Z","caller":"traceutil/trace.go:171","msg":"trace[1049061076] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1832; }","duration":"115.295747ms","start":"2026-10-26T21:26:30.049646Z","end":"2026-10-26T21:26:30.164942Z","steps":["trace[1049061076] 'agreement among raft nodes before linearized reading' (duration: 115.140062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:26:30.164963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.974638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-26T21:26:30.165034Z","caller":"traceutil/trace.go:171","msg":"trace[1295512030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1832; }","duration":"148.132553ms","start":"2026-10-26T21:26:30.016881Z","end":"2026-10-26T21:26:30.165014Z","steps":["trace[1295512030] 'agreement among raft nodes before linearized reading' (duration: 147.902836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:26:30.302772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.177072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:26:30.302863Z","caller":"traceutil/trace.go:171","msg":"trace[996815833] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1904; }","duration":"124.69114ms","start":"2026-10-26T21:26:30.178158Z","end":"2026-10-26T21:26:30.302849Z","steps":["trace[996815833] 'read index received' (duration: 23.215769ms)","trace[996815833] 'applied index is now lower than readState.Index' (duration: 101.474451ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:26:30.302879Z","caller":"traceutil/trace.go:171","msg":"trace[764567396] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"133.486935ms","start":"2026-10-26T21:26:30.169373Z","end":"2026-10-26T21:26:30.30286Z","steps":["trace[764567396] 'process raft request' (duration: 32.009994ms)","trace[764567396] 'compare' (duration: 101.06993ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:26:30.303031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.876284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T21:26:30.303063Z","caller":"traceutil/trace.go:171","msg":"trace[854952675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1833; }","duration":"124.938316ms","start":"2026-10-26T21:26:30.178115Z","end":"2026-10-26T21:26:30.303054Z","steps":["trace[854952675] 'agreement among raft nodes before linearized reading' (duration: 124.824483ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:26:30.499522Z","caller":"traceutil/trace.go:171","msg":"trace[1091704301] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"136.56532ms","start":"2026-10-26T21:26:30.362926Z","end":"2026-10-26T21:26:30.499492Z","steps":["trace[1091704301] 'process raft request' (duration: 136.391395ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:27:13.922843Z","caller":"traceutil/trace.go:171","msg":"trace[401917086] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"116.802374ms","start":"2026-10-26T21:27:13.80602Z","end":"2026-10-26T21:27:13.922822Z","steps":["trace[401917086] 'process raft request' (duration: 116.67241ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:10.270559Z","caller":"traceutil/trace.go:171","msg":"trace[826824677] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"101.416438ms","start":"2026-10-26T21:28:10.169124Z","end":"2026-10-26T21:28:10.270541Z","steps":["trace[826824677] 'process raft request' (duration: 101.296414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:43.774375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.891852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:28:43.774481Z","caller":"traceutil/trace.go:171","msg":"trace[103561077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2809; }","duration":"142.019905ms","start":"2026-10-26T21:28:43.632436Z","end":"2026-10-26T21:28:43.774456Z","steps":["trace[103561077] 'range keys from in-memory index tree' (duration: 141.799659ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:49.139134Z","caller":"traceutil/trace.go:171","msg":"trace[1495771834] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"102.520599ms","start":"2026-10-26T21:28:49.036588Z","end":"2026-10-26T21:28:49.139108Z","steps":["trace[1495771834] 'process raft request' (duration: 98.759984ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:22.538138Z","caller":"traceutil/trace.go:171","msg":"trace[629041089] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"191.94544ms","start":"2026-10-26T21:33:22.346153Z","end":"2026-10-26T21:33:22.538098Z","steps":["trace[629041089] 'process raft request' (duration: 191.677453ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:22.710392Z","caller":"traceutil/trace.go:171","msg":"trace[1915679449] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"104.370888ms","start":"2026-10-26T21:33:22.605994Z","end":"2026-10-26T21:33:22.710365Z","steps":["trace[1915679449] 'process raft request' (duration: 98.912567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:33:22.710406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.18851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T21:33:22.71048Z","caller":"traceutil/trace.go:171","msg":"trace[657523468] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5269; }","duration":"114.294532ms","start":"2026-10-26T21:33:22.596168Z","end":"2026-10-26T21:33:22.710462Z","steps":["trace[657523468] 'agreement among raft nodes before linearized reading' (duration: 114.066326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:33:42.496419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.16535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:33:42.496547Z","caller":"traceutil/trace.go:171","msg":"trace[1025527079] linearizableReadLoop","detail":"{readStateIndex:5827; appliedIndex:5826; }","duration":"165.574278ms","start":"2026-10-26T21:33:42.330952Z","end":"2026-10-26T21:33:42.496527Z","steps":["trace[1025527079] 'read index received' (duration: 24.161011ms)","trace[1025527079] 'applied index is now lower than readState.Index' (duration: 141.411286ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:33:42.496643Z","caller":"traceutil/trace.go:171","msg":"trace[475230305] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"166.869343ms","start":"2026-10-26T21:33:42.329748Z","end":"2026-10-26T21:33:42.496618Z","steps":["trace[475230305] 'process raft request' (duration: 25.385385ms)","trace[475230305] 'compare' (duration: 140.804939ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:33:42.49693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.951268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-26T21:33:42.497019Z","caller":"traceutil/trace.go:171","msg":"trace[1974505365] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5619; }","duration":"166.070091ms","start":"2026-10-26T21:33:42.330931Z","end":"2026-10-26T21:33:42.497001Z","steps":["trace[1974505365] 'agreement among raft nodes before linearized reading' (duration: 165.766992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:33:42.496953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.335191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111030"} {"level":"info","ts":"2026-10-26T21:33:42.497256Z","caller":"traceutil/trace.go:171","msg":"trace[1639274314] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5619; }","duration":"165.658979ms","start":"2026-10-26T21:33:42.33158Z","end":"2026-10-26T21:33:42.497239Z","steps":["trace[1639274314] 'agreement among raft nodes before linearized reading' (duration: 165.061473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:33:42.839431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.258987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"warn","ts":"2026-10-26T21:33:42.839478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.158731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3baf7f53-8c6d-4e0c-b342-9c1c90ebada2\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-10-26T21:33:42.839494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.616185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T21:33:42.839521Z","caller":"traceutil/trace.go:171","msg":"trace[1602671968] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5619; }","duration":"242.36644ms","start":"2026-10-26T21:33:42.597127Z","end":"2026-10-26T21:33:42.839493Z","steps":["trace[1602671968] 'range keys from in-memory index tree' (duration: 242.100603ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:42.839546Z","caller":"traceutil/trace.go:171","msg":"trace[1709420963] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3baf7f53-8c6d-4e0c-b342-9c1c90ebada2; range_end:; response_count:1; response_revision:5619; }","duration":"109.280505ms","start":"2026-10-26T21:33:42.73025Z","end":"2026-10-26T21:33:42.839531Z","steps":["trace[1709420963] 'range keys from in-memory index tree' (duration: 108.963626ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:42.839577Z","caller":"traceutil/trace.go:171","msg":"trace[876307583] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5619; }","duration":"140.734078ms","start":"2026-10-26T21:33:42.69882Z","end":"2026-10-26T21:33:42.839554Z","steps":["trace[876307583] 'count revisions from in-memory index tree' (duration: 140.538263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:33:42.839889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.571985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T21:33:42.839988Z","caller":"traceutil/trace.go:171","msg":"trace[721663594] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5619; }","duration":"148.73996ms","start":"2026-10-26T21:33:42.691224Z","end":"2026-10-26T21:33:42.839964Z","steps":["trace[721663594] 'count revisions from in-memory index tree' (duration: 148.440902ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:43.115097Z","caller":"traceutil/trace.go:171","msg":"trace[1922199279] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"270.46951ms","start":"2026-10-26T21:33:42.844594Z","end":"2026-10-26T21:33:43.115064Z","steps":["trace[1922199279] 'process raft request' (duration: 270.318496ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:43.115107Z","caller":"traceutil/trace.go:171","msg":"trace[319030908] linearizableReadLoop","detail":"{readStateIndex:5831; appliedIndex:5828; }","duration":"270.219953ms","start":"2026-10-26T21:33:42.844863Z","end":"2026-10-26T21:33:43.115083Z","steps":["trace[319030908] 'read index received' (duration: 61.812236ms)","trace[319030908] 'applied index is now lower than readState.Index' (duration: 208.406757ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:33:43.115143Z","caller":"traceutil/trace.go:171","msg":"trace[915510481] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"270.313735ms","start":"2026-10-26T21:33:42.84479Z","end":"2026-10-26T21:33:43.115104Z","steps":["trace[915510481] 'process raft request' (duration: 270.169191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:33:43.115368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.47709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-26T21:33:43.115439Z","caller":"traceutil/trace.go:171","msg":"trace[243945011] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5625; }","duration":"270.630544ms","start":"2026-10-26T21:33:42.844792Z","end":"2026-10-26T21:33:43.115422Z","steps":["trace[243945011] 'agreement among raft nodes before linearized reading' (duration: 270.365267ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:43.115197Z","caller":"traceutil/trace.go:171","msg":"trace[1755606257] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"184.272737ms","start":"2026-10-26T21:33:42.930914Z","end":"2026-10-26T21:33:43.115187Z","steps":["trace[1755606257] 'process raft request' (duration: 184.137173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:33:43.115489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.532201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:33:43.115516Z","caller":"traceutil/trace.go:171","msg":"trace[1823358712] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:5625; }","duration":"230.592762ms","start":"2026-10-26T21:33:42.884912Z","end":"2026-10-26T21:33:43.115504Z","steps":["trace[1823358712] 'agreement among raft nodes before linearized reading' (duration: 230.534861ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:43.115178Z","caller":"traceutil/trace.go:171","msg":"trace[439991179] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"265.612175ms","start":"2026-10-26T21:33:42.849534Z","end":"2026-10-26T21:33:43.115146Z","steps":["trace[439991179] 'process raft request' (duration: 265.458911ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:43.115266Z","caller":"traceutil/trace.go:171","msg":"trace[875008318] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"270.550052ms","start":"2026-10-26T21:33:42.844544Z","end":"2026-10-26T21:33:43.115094Z","steps":["trace[875008318] 'process raft request' (duration: 231.836587ms)","trace[875008318] 'compare' (duration: 38.390736ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:33:54.05567Z","caller":"traceutil/trace.go:171","msg":"trace[1604811424] transaction","detail":"{read_only:false; response_revision:5773; number_of_response:1; }","duration":"130.112373ms","start":"2026-10-26T21:33:53.925529Z","end":"2026-10-26T21:33:54.055642Z","steps":["trace[1604811424] 'process raft request' (duration: 62.880256ms)","trace[1604811424] 'compare' (duration: 66.823316ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:33:54.055675Z","caller":"traceutil/trace.go:171","msg":"trace[2020487972] transaction","detail":"{read_only:false; response_revision:5774; number_of_response:1; }","duration":"130.065911ms","start":"2026-10-26T21:33:53.925585Z","end":"2026-10-26T21:33:54.055651Z","steps":["trace[2020487972] 'process raft request' (duration: 129.968008ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:54.055915Z","caller":"traceutil/trace.go:171","msg":"trace[896032797] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"129.908767ms","start":"2026-10-26T21:33:53.92596Z","end":"2026-10-26T21:33:54.055869Z","steps":["trace[896032797] 'process raft request' (duration: 129.64153ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:33:54.056719Z","caller":"traceutil/trace.go:171","msg":"trace[359662798] linearizableReadLoop","detail":"{readStateIndex:5986; appliedIndex:5982; }","duration":"116.378711ms","start":"2026-10-26T21:33:53.940317Z","end":"2026-10-26T21:33:54.056696Z","steps":["trace[359662798] 'read index received' (duration: 48.104856ms)","trace[359662798] 'applied index is now lower than readState.Index' (duration: 68.272185ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:33:54.056768Z","caller":"traceutil/trace.go:171","msg":"trace[2059592016] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"130.687869ms","start":"2026-10-26T21:33:53.926057Z","end":"2026-10-26T21:33:54.056745Z","steps":["trace[2059592016] 'process raft request' (duration: 129.712871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:33:54.056948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.606377ms","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-26T21:33:54.057001Z","caller":"traceutil/trace.go:171","msg":"trace[917180556] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5776; }","duration":"116.70118ms","start":"2026-10-26T21:33:53.940281Z","end":"2026-10-26T21:33:54.056982Z","steps":["trace[917180556] 'agreement among raft nodes before linearized reading' (duration: 116.511915ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:34:07.468636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2943} {"level":"info","ts":"2026-10-26T21:34:07.542704Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2943,"took":"72.484473ms","hash":984905395,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":32505856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T21:34:07.542776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":984905395,"revision":2943,"compact-revision":-1} {"level":"info","ts":"2026-10-26T21:39:07.476405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5869} {"level":"info","ts":"2026-10-26T21:39:07.569901Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5869,"took":"91.414934ms","hash":2445805173,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":25690112,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T21:39:07.569969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2445805173,"revision":5869,"compact-revision":2943} {"level":"info","ts":"2026-10-26T21:39:54.548774Z","caller":"traceutil/trace.go:171","msg":"trace[752537069] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"264.922781ms","start":"2026-10-26T21:39:54.283828Z","end":"2026-10-26T21:39:54.548751Z","steps":["trace[752537069] 'process raft request' (duration: 264.781247ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:39:54.971166Z","caller":"traceutil/trace.go:171","msg":"trace[744346266] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"491.248552ms","start":"2026-10-26T21:39:54.479869Z","end":"2026-10-26T21:39:54.971117Z","steps":["trace[744346266] 'process raft request' (duration: 491.068687ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:39:54.971294Z","caller":"traceutil/trace.go:171","msg":"trace[307276832] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8780; }","duration":"336.246737ms","start":"2026-10-26T21:39:54.635017Z","end":"2026-10-26T21:39:54.971264Z","steps":["trace[307276832] 'read index received' (duration: 335.839046ms)","trace[307276832] 'applied index is now lower than readState.Index' (duration: 405.301µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:39:54.971346Z","caller":"traceutil/trace.go:171","msg":"trace[1434620903] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"484.993259ms","start":"2026-10-26T21:39:54.486324Z","end":"2026-10-26T21:39:54.971317Z","steps":["trace[1434620903] 'process raft request' (duration: 484.752292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:39:54.971366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:39:54.47984Z","time spent":"491.400627ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:39:54.971495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.469294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T21:39:54.97153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:39:54.486301Z","time spent":"485.147663ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:39:54.971568Z","caller":"traceutil/trace.go:171","msg":"trace[555595133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8485; }","duration":"336.593507ms","start":"2026-10-26T21:39:54.634953Z","end":"2026-10-26T21:39:54.971547Z","steps":["trace[555595133] 'agreement among raft nodes before linearized reading' (duration: 336.476984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:39:54.971569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.389262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T21:39:54.971613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:39:54.634924Z","time spent":"336.68106ms","remote":"127.0.0.1:53958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T21:39:54.971617Z","caller":"traceutil/trace.go:171","msg":"trace[513998984] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8485; }","duration":"292.494895ms","start":"2026-10-26T21:39:54.679109Z","end":"2026-10-26T21:39:54.971604Z","steps":["trace[513998984] 'agreement among raft nodes before linearized reading' (duration: 292.34436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:39:54.971687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.981575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-26T21:39:54.971779Z","caller":"traceutil/trace.go:171","msg":"trace[1234172283] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8485; }","duration":"220.114669ms","start":"2026-10-26T21:39:54.751649Z","end":"2026-10-26T21:39:54.971764Z","steps":["trace[1234172283] 'agreement among raft nodes before linearized reading' (duration: 219.884463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:39:54.972302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.801287ms","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-26T21:39:54.972387Z","caller":"traceutil/trace.go:171","msg":"trace[193856891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8485; }","duration":"147.455446ms","start":"2026-10-26T21:39:54.824912Z","end":"2026-10-26T21:39:54.972368Z","steps":["trace[193856891] 'agreement among raft nodes before linearized reading' (duration: 146.553711ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:05.147973Z","caller":"traceutil/trace.go:171","msg":"trace[1502711619] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"104.699881ms","start":"2026-10-26T21:40:05.043253Z","end":"2026-10-26T21:40:05.147952Z","steps":["trace[1502711619] 'process raft request' (duration: 104.545156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:40:05.148044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.767631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:40:05.148214Z","caller":"traceutil/trace.go:171","msg":"trace[492379780] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8537; }","duration":"114.957125ms","start":"2026-10-26T21:40:05.033235Z","end":"2026-10-26T21:40:05.148192Z","steps":["trace[492379780] 'agreement among raft nodes before linearized reading' (duration: 114.75737ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:05.354143Z","caller":"traceutil/trace.go:171","msg":"trace[2048115785] linearizableReadLoop","detail":"{readStateIndex:8838; appliedIndex:8837; }","duration":"206.195183ms","start":"2026-10-26T21:40:05.147924Z","end":"2026-10-26T21:40:05.354119Z","steps":["trace[2048115785] 'read index received' (duration: 201.288658ms)","trace[2048115785] 'applied index is now lower than readState.Index' (duration: 4.905485ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:40:05.354213Z","caller":"traceutil/trace.go:171","msg":"trace[1841813955] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"250.277065ms","start":"2026-10-26T21:40:05.103905Z","end":"2026-10-26T21:40:05.354182Z","steps":["trace[1841813955] 'process raft request' (duration: 245.330348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:40:05.354426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.875621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-26T21:40:05.354485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.931693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T21:40:05.354514Z","caller":"traceutil/trace.go:171","msg":"trace[389011283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8538; }","duration":"216.028546ms","start":"2026-10-26T21:40:05.138463Z","end":"2026-10-26T21:40:05.354492Z","steps":["trace[389011283] 'agreement among raft nodes before linearized reading' (duration: 215.711357ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:05.35455Z","caller":"traceutil/trace.go:171","msg":"trace[549677994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8538; }","duration":"247.041495ms","start":"2026-10-26T21:40:05.107496Z","end":"2026-10-26T21:40:05.354538Z","steps":["trace[549677994] 'agreement among raft nodes before linearized reading' (duration: 246.710746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:40:05.354639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.289467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-26T21:40:05.354696Z","caller":"traceutil/trace.go:171","msg":"trace[2033253488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8538; }","duration":"227.37091ms","start":"2026-10-26T21:40:05.127313Z","end":"2026-10-26T21:40:05.354684Z","steps":["trace[2033253488] 'agreement among raft nodes before linearized reading' (duration: 227.230616ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:05.37918Z","caller":"traceutil/trace.go:171","msg":"trace[1126789426] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"227.448773ms","start":"2026-10-26T21:40:05.151715Z","end":"2026-10-26T21:40:05.379164Z","steps":["trace[1126789426] 'process raft request' (duration: 227.36266ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:06.840074Z","caller":"traceutil/trace.go:171","msg":"trace[871671973] linearizableReadLoop","detail":"{readStateIndex:8846; appliedIndex:8845; }","duration":"203.994303ms","start":"2026-10-26T21:40:06.636056Z","end":"2026-10-26T21:40:06.840051Z","steps":["trace[871671973] 'read index received' (duration: 203.782757ms)","trace[871671973] 'applied index is now lower than readState.Index' (duration: 210.696µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:40:06.840141Z","caller":"traceutil/trace.go:171","msg":"trace[1594614714] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"204.430445ms","start":"2026-10-26T21:40:06.635685Z","end":"2026-10-26T21:40:06.840116Z","steps":["trace[1594614714] 'process raft request' (duration: 204.206029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:40:06.840273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.186388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:40:06.840356Z","caller":"traceutil/trace.go:171","msg":"trace[1780964884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8545; }","duration":"204.334572ms","start":"2026-10-26T21:40:06.636001Z","end":"2026-10-26T21:40:06.840336Z","steps":["trace[1780964884] 'agreement among raft nodes before linearized reading' (duration: 204.167347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:40:35.475908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.473962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:40:35.476128Z","caller":"traceutil/trace.go:171","msg":"trace[894956996] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"220.338315ms","start":"2026-10-26T21:40:35.255769Z","end":"2026-10-26T21:40:35.476107Z","steps":["trace[894956996] 'process raft request' (duration: 220.260483ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:35.476179Z","caller":"traceutil/trace.go:171","msg":"trace[1423979485] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"268.230472ms","start":"2026-10-26T21:40:35.207915Z","end":"2026-10-26T21:40:35.476145Z","steps":["trace[1423979485] 'process raft request' (duration: 79.421631ms)","trace[1423979485] 'compare' (duration: 188.064971ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:40:49.342916Z","caller":"traceutil/trace.go:171","msg":"trace[1765414672] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8865; }","duration":"114.082821ms","start":"2026-10-26T21:40:49.22881Z","end":"2026-10-26T21:40:49.342892Z","steps":["trace[1765414672] 'process raft request' (duration: 108.588959ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:49.361542Z","caller":"traceutil/trace.go:171","msg":"trace[1445927648] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"126.911857ms","start":"2026-10-26T21:40:49.234606Z","end":"2026-10-26T21:40:49.361518Z","steps":["trace[1445927648] 'process raft request' (duration: 126.726232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:40:50.017696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.3522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:40:50.017827Z","caller":"traceutil/trace.go:171","msg":"trace[762798668] transaction","detail":"{read_only:false; response_revision:8874; number_of_response:1; }","duration":"213.864365ms","start":"2026-10-26T21:40:49.803939Z","end":"2026-10-26T21:40:50.017804Z","steps":["trace[762798668] 'process raft request' (duration: 109.31713ms)","trace[762798668] 'compare' (duration: 104.185685ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:40:50.082515Z","caller":"traceutil/trace.go:171","msg":"trace[474610448] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"278.448645ms","start":"2026-10-26T21:40:49.804047Z","end":"2026-10-26T21:40:50.082496Z","steps":["trace[474610448] 'process raft request' (duration: 278.291931ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:50.082758Z","caller":"traceutil/trace.go:171","msg":"trace[512490518] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"278.63892ms","start":"2026-10-26T21:40:49.804102Z","end":"2026-10-26T21:40:50.082741Z","steps":["trace[512490518] 'process raft request' (duration: 278.355112ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:50.082844Z","caller":"traceutil/trace.go:171","msg":"trace[1980318793] linearizableReadLoop","detail":"{readStateIndex:9190; appliedIndex:9187; }","duration":"277.221021ms","start":"2026-10-26T21:40:49.805595Z","end":"2026-10-26T21:40:50.082816Z","steps":["trace[1980318793] 'read index received' (duration: 107.678354ms)","trace[1980318793] 'applied index is now lower than readState.Index' (duration: 169.541807ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:40:50.082918Z","caller":"traceutil/trace.go:171","msg":"trace[540465556] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"259.449808ms","start":"2026-10-26T21:40:49.823434Z","end":"2026-10-26T21:40:50.082883Z","steps":["trace[540465556] 'process raft request' (duration: 259.332655ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:50.082903Z","caller":"traceutil/trace.go:171","msg":"trace[381059253] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"277.135159ms","start":"2026-10-26T21:40:49.805729Z","end":"2026-10-26T21:40:50.082864Z","steps":["trace[381059253] 'process raft request' (duration: 276.893052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:40:50.083274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.666843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229787"} {"level":"info","ts":"2026-10-26T21:40:50.083332Z","caller":"traceutil/trace.go:171","msg":"trace[141371188] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8878; }","duration":"277.764746ms","start":"2026-10-26T21:40:49.805555Z","end":"2026-10-26T21:40:50.08332Z","steps":["trace[141371188] 'agreement among raft nodes before linearized reading' (duration: 277.364245ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:40:56.053998Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241e.wal"} {"level":"info","ts":"2026-10-26T21:42:13.579994Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T21:42:13.588474Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T21:42:13.588666Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T21:44:07.484799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8036} {"level":"info","ts":"2026-10-26T21:44:07.556245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8036,"took":"67.838229ms","hash":1356178364,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":28811264,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T21:44:07.556351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1356178364,"revision":8036,"compact-revision":5869} {"level":"info","ts":"2026-10-26T21:49:07.491891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10494} {"level":"info","ts":"2026-10-26T21:49:07.581222Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10494,"took":"87.159622ms","hash":2476328287,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":27496448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T21:49:07.581295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2476328287,"revision":10494,"compact-revision":8036} {"level":"info","ts":"2026-10-26T21:51:54.269631Z","caller":"traceutil/trace.go:171","msg":"trace[319842355] transaction","detail":"{read_only:false; response_revision:13947; number_of_response:1; }","duration":"120.491605ms","start":"2026-10-26T21:51:54.149111Z","end":"2026-10-26T21:51:54.269603Z","steps":["trace[319842355] 'process raft request' (duration: 120.258599ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:51:57.36894Z","caller":"traceutil/trace.go:171","msg":"trace[1031776282] transaction","detail":"{read_only:false; response_revision:13963; number_of_response:1; }","duration":"108.301927ms","start":"2026-10-26T21:51:57.260606Z","end":"2026-10-26T21:51:57.368908Z","steps":["trace[1031776282] 'process raft request' (duration: 108.06308ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:52:35.09548Z","caller":"traceutil/trace.go:171","msg":"trace[946139027] linearizableReadLoop","detail":"{readStateIndex:14656; appliedIndex:14655; }","duration":"134.06685ms","start":"2026-10-26T21:52:34.961398Z","end":"2026-10-26T21:52:35.095465Z","steps":["trace[946139027] 'read index received' (duration: 133.875605ms)","trace[946139027] 'applied index is now lower than readState.Index' (duration: 190.765µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:52:35.095579Z","caller":"traceutil/trace.go:171","msg":"trace[1438789976] transaction","detail":"{read_only:false; response_revision:14172; number_of_response:1; }","duration":"152.779218ms","start":"2026-10-26T21:52:34.942768Z","end":"2026-10-26T21:52:35.095547Z","steps":["trace[1438789976] 'process raft request' (duration: 152.586312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:52:35.095678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.262796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-26T21:52:35.095706Z","caller":"traceutil/trace.go:171","msg":"trace[168866670] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14172; }","duration":"134.346398ms","start":"2026-10-26T21:52:34.961351Z","end":"2026-10-26T21:52:35.095698Z","steps":["trace[168866670] 'agreement among raft nodes before linearized reading' (duration: 134.204774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:52:36.258578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.7033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T21:52:36.258636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.778787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:52:36.258757Z","caller":"traceutil/trace.go:171","msg":"trace[1704209914] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:14178; }","duration":"446.26655ms","start":"2026-10-26T21:52:35.812466Z","end":"2026-10-26T21:52:36.258733Z","steps":["trace[1704209914] 'count revisions from in-memory index tree' (duration: 445.652173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:52:36.258827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:52:35.812413Z","time spent":"446.396083ms","remote":"127.0.0.1:54172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-10-26T21:52:36.25868Z","caller":"traceutil/trace.go:171","msg":"trace[1241857485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14178; }","duration":"417.847004ms","start":"2026-10-26T21:52:35.840807Z","end":"2026-10-26T21:52:36.258654Z","steps":["trace[1241857485] 'range keys from in-memory index tree' (duration: 417.506774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:52:36.259084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:52:35.840792Z","time spent":"418.201633ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T21:52:36.450868Z","caller":"traceutil/trace.go:171","msg":"trace[1057732491] transaction","detail":"{read_only:false; response_revision:14179; number_of_response:1; }","duration":"187.281633ms","start":"2026-10-26T21:52:36.26356Z","end":"2026-10-26T21:52:36.450842Z","steps":["trace[1057732491] 'process raft request' (duration: 187.14329ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:52:59.82502Z","caller":"traceutil/trace.go:171","msg":"trace[2090291243] transaction","detail":"{read_only:false; response_revision:14493; number_of_response:1; }","duration":"100.752598ms","start":"2026-10-26T21:52:59.72424Z","end":"2026-10-26T21:52:59.824993Z","steps":["trace[2090291243] 'process raft request' (duration: 100.527452ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:52:59.951528Z","caller":"traceutil/trace.go:171","msg":"trace[1178660917] transaction","detail":"{read_only:false; response_revision:14494; number_of_response:1; }","duration":"121.431271ms","start":"2026-10-26T21:52:59.830046Z","end":"2026-10-26T21:52:59.951478Z","steps":["trace[1178660917] 'process raft request' (duration: 111.884017ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:54:07.499488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12754} {"level":"info","ts":"2026-10-26T21:54:07.591108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12754,"took":"86.210996ms","hash":910337843,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":30265344,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T21:54:07.591183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":910337843,"revision":12754,"compact-revision":10494} {"level":"warn","ts":"2026-10-26T21:54:15.956729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.255986ms","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-10-26T21:54:15.956927Z","caller":"traceutil/trace.go:171","msg":"trace[1304206470] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15253; }","duration":"138.521784ms","start":"2026-10-26T21:54:15.818339Z","end":"2026-10-26T21:54:15.95686Z","steps":["trace[1304206470] 'range keys from in-memory index tree' (duration: 137.898087ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:54:17.010492Z","caller":"traceutil/trace.go:171","msg":"trace[284721541] transaction","detail":"{read_only:false; response_revision:15258; number_of_response:1; }","duration":"138.595565ms","start":"2026-10-26T21:54:16.871871Z","end":"2026-10-26T21:54:17.010466Z","steps":["trace[284721541] 'process raft request' (duration: 138.449361ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:54:17.082415Z","caller":"traceutil/trace.go:171","msg":"trace[1345467274] transaction","detail":"{read_only:false; response_revision:15259; number_of_response:1; }","duration":"202.620837ms","start":"2026-10-26T21:54:16.879754Z","end":"2026-10-26T21:54:17.082375Z","steps":["trace[1345467274] 'process raft request' (duration: 157.55612ms)","trace[1345467274] 'compare' (duration: 44.821081ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:59:07.506637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15211} {"level":"info","ts":"2026-10-26T21:59:07.617321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15211,"took":"106.149618ms","hash":2598058266,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":31973376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T21:59:07.617691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2598058266,"revision":15211,"compact-revision":12754} {"level":"info","ts":"2026-10-26T22:00:06.453099Z","caller":"traceutil/trace.go:171","msg":"trace[1725940113] linearizableReadLoop","detail":"{readStateIndex:18486; appliedIndex:18485; }","duration":"170.615862ms","start":"2026-10-26T22:00:06.282459Z","end":"2026-10-26T22:00:06.453074Z","steps":["trace[1725940113] 'read index received' (duration: 170.55089ms)","trace[1725940113] 'applied index is now lower than readState.Index' (duration: 63.852µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:00:06.453404Z","caller":"traceutil/trace.go:171","msg":"trace[1280756574] transaction","detail":"{read_only:false; response_revision:17880; number_of_response:1; }","duration":"172.402411ms","start":"2026-10-26T22:00:06.280983Z","end":"2026-10-26T22:00:06.453385Z","steps":["trace[1280756574] 'process raft request' (duration: 171.933528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:06.453701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.223739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T22:00:06.453784Z","caller":"traceutil/trace.go:171","msg":"trace[613394730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17880; }","duration":"171.337861ms","start":"2026-10-26T22:00:06.282428Z","end":"2026-10-26T22:00:06.453766Z","steps":["trace[613394730] 'agreement among raft nodes before linearized reading' (duration: 171.032953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:06.456702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.715568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T22:00:06.456759Z","caller":"traceutil/trace.go:171","msg":"trace[462509552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17882; }","duration":"173.79972ms","start":"2026-10-26T22:00:06.282946Z","end":"2026-10-26T22:00:06.456746Z","steps":["trace[462509552] 'agreement among raft nodes before linearized reading' (duration: 173.620555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:13.472321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.757407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:00:13.472632Z","caller":"traceutil/trace.go:171","msg":"trace[1466286814] linearizableReadLoop","detail":"{readStateIndex:18531; appliedIndex:18530; }","duration":"138.350698ms","start":"2026-10-26T22:00:13.334264Z","end":"2026-10-26T22:00:13.472614Z","steps":["trace[1466286814] 'read index received' (duration: 36.801µs)","trace[1466286814] 'applied index is now lower than readState.Index' (duration: 138.312247ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:00:13.472809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.535543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:00:13.473094Z","caller":"traceutil/trace.go:171","msg":"trace[1119259418] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:17924; }","duration":"138.821401ms","start":"2026-10-26T22:00:13.334257Z","end":"2026-10-26T22:00:13.473079Z","steps":["trace[1119259418] 'agreement among raft nodes before linearized reading' (duration: 138.511503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:13.473296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.228351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-86rgn\" ","response":"range_response_count:1 size:20910"} {"level":"info","ts":"2026-10-26T22:00:13.473412Z","caller":"traceutil/trace.go:171","msg":"trace[305418017] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-86rgn; range_end:; response_count:1; response_revision:17924; }","duration":"101.374026ms","start":"2026-10-26T22:00:13.372008Z","end":"2026-10-26T22:00:13.473382Z","steps":["trace[305418017] 'agreement among raft nodes before linearized reading' (duration: 100.983165ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:13.478888Z","caller":"traceutil/trace.go:171","msg":"trace[412717821] transaction","detail":"{read_only:false; response_revision:17924; number_of_response:1; }","duration":"319.220524ms","start":"2026-10-26T22:00:13.159627Z","end":"2026-10-26T22:00:13.478847Z","steps":["trace[412717821] 'process raft request' (duration: 107.854864ms)","trace[412717821] 'compare' (duration: 204.430738ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:00:13.479203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.159608Z","time spent":"319.498841ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2788,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:00:13.786492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.937731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:00:13.786622Z","caller":"traceutil/trace.go:171","msg":"trace[1923299000] transaction","detail":"{read_only:false; response_revision:17926; number_of_response:1; }","duration":"285.269375ms","start":"2026-10-26T22:00:13.501329Z","end":"2026-10-26T22:00:13.786599Z","steps":["trace[1923299000] 'process raft request' (duration: 71.126379ms)","trace[1923299000] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/openstack/libvirt-libvirt-default-86rgn; req_size:21021; } (duration: 213.443618ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:00:14.055947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.567354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:00:14.056258Z","caller":"traceutil/trace.go:171","msg":"trace[1285290115] transaction","detail":"{read_only:false; response_revision:17930; number_of_response:1; }","duration":"353.253367ms","start":"2026-10-26T22:00:13.702989Z","end":"2026-10-26T22:00:14.056242Z","steps":["trace[1285290115] 'process raft request' (duration: 353.200465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:14.056338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.702959Z","time spent":"353.338829ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:00:14.056352Z","caller":"traceutil/trace.go:171","msg":"trace[1913743716] transaction","detail":"{read_only:false; response_revision:17927; number_of_response:1; }","duration":"542.634218ms","start":"2026-10-26T22:00:13.513689Z","end":"2026-10-26T22:00:14.056323Z","steps":["trace[1913743716] 'process raft request' (duration: 394.631332ms)","trace[1913743716] 'compare' (duration: 147.325217ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:00:14.056378Z","caller":"traceutil/trace.go:171","msg":"trace[357808821] transaction","detail":"{read_only:false; response_revision:17928; number_of_response:1; }","duration":"542.074793ms","start":"2026-10-26T22:00:13.514268Z","end":"2026-10-26T22:00:14.056343Z","steps":["trace[357808821] 'process raft request' (duration: 541.783145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:14.056422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.513667Z","time spent":"542.72317ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":738,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T22:00:14.056555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.51424Z","time spent":"542.210827ms","remote":"127.0.0.1:54242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1225,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T22:00:14.05661Z","caller":"traceutil/trace.go:171","msg":"trace[1589134406] linearizableReadLoop","detail":"{readStateIndex:18535; appliedIndex:18532; }","duration":"541.61559ms","start":"2026-10-26T22:00:13.514959Z","end":"2026-10-26T22:00:14.056575Z","steps":["trace[1589134406] 'read index received' (duration: 57.552543ms)","trace[1589134406] 'applied index is now lower than readState.Index' (duration: 484.060497ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:00:14.056692Z","caller":"traceutil/trace.go:171","msg":"trace[2109831993] transaction","detail":"{read_only:false; response_revision:17929; number_of_response:1; }","duration":"541.707122ms","start":"2026-10-26T22:00:13.514975Z","end":"2026-10-26T22:00:14.056683Z","steps":["trace[2109831993] 'process raft request' (duration: 541.164157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:14.056748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.514963Z","time spent":"541.752684ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T22:00:14.05691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.518099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29884200-r8g2x\" ","response":"range_response_count:1 size:7043"} {"level":"info","ts":"2026-10-26T22:00:14.056954Z","caller":"traceutil/trace.go:171","msg":"trace[1525800321] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29884200-r8g2x; range_end:; response_count:1; response_revision:17930; }","duration":"540.604291ms","start":"2026-10-26T22:00:13.516339Z","end":"2026-10-26T22:00:14.056944Z","steps":["trace[1525800321] 'agreement among raft nodes before linearized reading' (duration: 540.437387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:14.056984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.516324Z","time spent":"540.652993ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":7065,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29884200-r8g2x\" "} {"level":"warn","ts":"2026-10-26T22:00:14.057028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.069823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129194"} {"level":"info","ts":"2026-10-26T22:00:14.057068Z","caller":"traceutil/trace.go:171","msg":"trace[11286027] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:17930; }","duration":"542.105133ms","start":"2026-10-26T22:00:13.514952Z","end":"2026-10-26T22:00:14.057057Z","steps":["trace[11286027] 'agreement among raft nodes before linearized reading' (duration: 541.98379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:14.057081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.591467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-26T22:00:14.057101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.514895Z","time spent":"542.200276ms","remote":"127.0.0.1:54014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129216,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-10-26T22:00:14.057114Z","caller":"traceutil/trace.go:171","msg":"trace[1124780175] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17930; }","duration":"370.651399ms","start":"2026-10-26T22:00:13.686452Z","end":"2026-10-26T22:00:14.057104Z","steps":["trace[1124780175] 'agreement among raft nodes before linearized reading' (duration: 370.555146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:14.057141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.686432Z","time spent":"370.70314ms","remote":"127.0.0.1:54238","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-26T22:00:14.056913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.968403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12433"} {"level":"warn","ts":"2026-10-26T22:00:14.057199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.815408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-26T22:00:14.057216Z","caller":"traceutil/trace.go:171","msg":"trace[773655751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17930; }","duration":"295.314723ms","start":"2026-10-26T22:00:13.761894Z","end":"2026-10-26T22:00:14.057209Z","steps":["trace[773655751] 'agreement among raft nodes before linearized reading' (duration: 294.919952ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:14.057231Z","caller":"traceutil/trace.go:171","msg":"trace[1262627967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17930; }","duration":"428.88217ms","start":"2026-10-26T22:00:13.62834Z","end":"2026-10-26T22:00:14.057222Z","steps":["trace[1262627967] 'agreement among raft nodes before linearized reading' (duration: 428.784987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:14.057257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:13.628321Z","time spent":"428.930351ms","remote":"127.0.0.1:54238","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-26T22:00:22.968607Z","caller":"traceutil/trace.go:171","msg":"trace[1812419196] transaction","detail":"{read_only:false; response_revision:18108; number_of_response:1; }","duration":"114.806098ms","start":"2026-10-26T22:00:22.853766Z","end":"2026-10-26T22:00:22.968572Z","steps":["trace[1812419196] 'process raft request' (duration: 114.688445ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:22.968597Z","caller":"traceutil/trace.go:171","msg":"trace[2081419210] linearizableReadLoop","detail":"{readStateIndex:18719; appliedIndex:18716; }","duration":"114.51842ms","start":"2026-10-26T22:00:22.854056Z","end":"2026-10-26T22:00:22.968575Z","steps":["trace[2081419210] 'read index received' (duration: 6.654994ms)","trace[2081419210] 'applied index is now lower than readState.Index' (duration: 107.862436ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:00:22.968682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.604632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-86rgn-vnc\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:00:22.96874Z","caller":"traceutil/trace.go:171","msg":"trace[601461790] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-86rgn-vnc; range_end:; response_count:0; response_revision:18110; }","duration":"114.686104ms","start":"2026-10-26T22:00:22.854041Z","end":"2026-10-26T22:00:22.968727Z","steps":["trace[601461790] 'agreement among raft nodes before linearized reading' (duration: 114.580061ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:22.968585Z","caller":"traceutil/trace.go:171","msg":"trace[936871986] transaction","detail":"{read_only:false; response_revision:18107; number_of_response:1; }","duration":"115.030634ms","start":"2026-10-26T22:00:22.853515Z","end":"2026-10-26T22:00:22.968546Z","steps":["trace[936871986] 'process raft request' (duration: 114.813708ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:22.968917Z","caller":"traceutil/trace.go:171","msg":"trace[1057730372] transaction","detail":"{read_only:false; response_revision:18109; number_of_response:1; }","duration":"114.408377ms","start":"2026-10-26T22:00:22.854478Z","end":"2026-10-26T22:00:22.968887Z","steps":["trace[1057730372] 'process raft request' (duration: 114.008305ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:22.968957Z","caller":"traceutil/trace.go:171","msg":"trace[326243714] transaction","detail":"{read_only:false; response_revision:18110; number_of_response:1; }","duration":"113.905273ms","start":"2026-10-26T22:00:22.855034Z","end":"2026-10-26T22:00:22.968939Z","steps":["trace[326243714] 'process raft request' (duration: 113.481541ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:22.974437Z","caller":"traceutil/trace.go:171","msg":"trace[1772521698] transaction","detail":"{read_only:false; response_revision:18112; number_of_response:1; }","duration":"107.418353ms","start":"2026-10-26T22:00:22.866997Z","end":"2026-10-26T22:00:22.974415Z","steps":["trace[1772521698] 'process raft request' (duration: 107.360222ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:22.974881Z","caller":"traceutil/trace.go:171","msg":"trace[1353233837] transaction","detail":"{read_only:false; response_revision:18111; number_of_response:1; }","duration":"113.967454ms","start":"2026-10-26T22:00:22.860898Z","end":"2026-10-26T22:00:22.974865Z","steps":["trace[1353233837] 'process raft request' (duration: 113.331076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:22.975201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.761217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-10-26T22:00:22.97526Z","caller":"traceutil/trace.go:171","msg":"trace[1882187315] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:18112; }","duration":"118.87218ms","start":"2026-10-26T22:00:22.856373Z","end":"2026-10-26T22:00:22.975245Z","steps":["trace[1882187315] 'agreement among raft nodes before linearized reading' (duration: 118.685205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:22.975637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.866793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4799"} {"level":"info","ts":"2026-10-26T22:00:22.976009Z","caller":"traceutil/trace.go:171","msg":"trace[229078941] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:18112; }","duration":"112.981497ms","start":"2026-10-26T22:00:22.862704Z","end":"2026-10-26T22:00:22.975685Z","steps":["trace[229078941] 'agreement among raft nodes before linearized reading' (duration: 112.74798ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:02:51.123784Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:02:51.130124Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T22:02:51.130223Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T22:04:07.514198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17434} {"level":"info","ts":"2026-10-26T22:04:07.614358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17434,"took":"96.333965ms","hash":2371343261,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":34590720,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T22:04:07.614406Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2371343261,"revision":17434,"compact-revision":15211} {"level":"warn","ts":"2026-10-26T22:05:51.371595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.506243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:05:51.371711Z","caller":"traceutil/trace.go:171","msg":"trace[833831582] linearizableReadLoop","detail":"{readStateIndex:21310; appliedIndex:21309; }","duration":"138.175454ms","start":"2026-10-26T22:05:51.23352Z","end":"2026-10-26T22:05:51.371695Z","steps":["trace[833831582] 'read index received' (duration: 67.902µs)","trace[833831582] 'applied index is now lower than readState.Index' (duration: 138.106202ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:05:51.371869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.355519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-10-26T22:05:51.371911Z","caller":"traceutil/trace.go:171","msg":"trace[1236163893] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20617; }","duration":"138.460372ms","start":"2026-10-26T22:05:51.233439Z","end":"2026-10-26T22:05:51.371899Z","steps":["trace[1236163893] 'agreement among raft nodes before linearized reading' (duration: 138.299437ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:05:51.371895Z","caller":"traceutil/trace.go:171","msg":"trace[1127746302] transaction","detail":"{read_only:false; response_revision:20617; number_of_response:1; }","duration":"219.462313ms","start":"2026-10-26T22:05:51.152399Z","end":"2026-10-26T22:05:51.371862Z","steps":["trace[1127746302] 'process raft request' (duration: 76.562118ms)","trace[1127746302] 'compare' (duration: 142.324589ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:05:56.573419Z","caller":"traceutil/trace.go:171","msg":"trace[1749521626] transaction","detail":"{read_only:false; response_revision:20645; number_of_response:1; }","duration":"110.837576ms","start":"2026-10-26T22:05:56.462564Z","end":"2026-10-26T22:05:56.573401Z","steps":["trace[1749521626] 'process raft request' (duration: 110.728363ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:05:56.579363Z","caller":"traceutil/trace.go:171","msg":"trace[1183751590] transaction","detail":"{read_only:false; response_revision:20646; number_of_response:1; }","duration":"115.912307ms","start":"2026-10-26T22:05:56.463424Z","end":"2026-10-26T22:05:56.579336Z","steps":["trace[1183751590] 'process raft request' (duration: 115.748433ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:01.932119Z","caller":"traceutil/trace.go:171","msg":"trace[2014928192] transaction","detail":"{read_only:false; response_revision:20671; number_of_response:1; }","duration":"136.325202ms","start":"2026-10-26T22:06:01.79577Z","end":"2026-10-26T22:06:01.932095Z","steps":["trace[2014928192] 'process raft request' (duration: 136.176858ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:02.094475Z","caller":"traceutil/trace.go:171","msg":"trace[291947080] transaction","detail":"{read_only:false; response_revision:20672; number_of_response:1; }","duration":"153.034455ms","start":"2026-10-26T22:06:01.941418Z","end":"2026-10-26T22:06:02.094453Z","steps":["trace[291947080] 'process raft request' (duration: 152.896391ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:02.15026Z","caller":"traceutil/trace.go:171","msg":"trace[586900363] linearizableReadLoop","detail":"{readStateIndex:21369; appliedIndex:21368; }","duration":"159.670878ms","start":"2026-10-26T22:06:01.990565Z","end":"2026-10-26T22:06:02.150236Z","steps":["trace[586900363] 'read index received' (duration: 104.188603ms)","trace[586900363] 'applied index is now lower than readState.Index' (duration: 55.481265ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:06:02.150321Z","caller":"traceutil/trace.go:171","msg":"trace[1750744115] transaction","detail":"{read_only:false; response_revision:20673; number_of_response:1; }","duration":"173.052479ms","start":"2026-10-26T22:06:01.977234Z","end":"2026-10-26T22:06:02.150286Z","steps":["trace[1750744115] 'process raft request' (duration: 172.846973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:02.150522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.929485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-26T22:06:02.150614Z","caller":"traceutil/trace.go:171","msg":"trace[1630027392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20673; }","duration":"160.077139ms","start":"2026-10-26T22:06:01.990514Z","end":"2026-10-26T22:06:02.150591Z","steps":["trace[1630027392] 'agreement among raft nodes before linearized reading' (duration: 159.858123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:02.150629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.856248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-26T22:06:02.150687Z","caller":"traceutil/trace.go:171","msg":"trace[625692702] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20673; }","duration":"140.94094ms","start":"2026-10-26T22:06:02.009729Z","end":"2026-10-26T22:06:02.15067Z","steps":["trace[625692702] 'agreement among raft nodes before linearized reading' (duration: 140.735705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:02.150739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.046345ms","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-10-26T22:06:02.150815Z","caller":"traceutil/trace.go:171","msg":"trace[780559880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20673; }","duration":"153.181729ms","start":"2026-10-26T22:06:01.997616Z","end":"2026-10-26T22:06:02.150798Z","steps":["trace[780559880] 'agreement among raft nodes before linearized reading' (duration: 152.827539ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:02.375572Z","caller":"traceutil/trace.go:171","msg":"trace[541077656] transaction","detail":"{read_only:false; response_revision:20676; number_of_response:1; }","duration":"218.690691ms","start":"2026-10-26T22:06:02.156835Z","end":"2026-10-26T22:06:02.375526Z","steps":["trace[541077656] 'process raft request' (duration: 218.65279ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:02.375757Z","caller":"traceutil/trace.go:171","msg":"trace[1213772991] transaction","detail":"{read_only:false; response_revision:20674; number_of_response:1; }","duration":"220.81085ms","start":"2026-10-26T22:06:02.154907Z","end":"2026-10-26T22:06:02.375718Z","steps":["trace[1213772991] 'process raft request' (duration: 123.785925ms)","trace[1213772991] 'compare' (duration: 96.598343ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:06:02.375822Z","caller":"traceutil/trace.go:171","msg":"trace[861806315] transaction","detail":"{read_only:false; response_revision:20675; number_of_response:1; }","duration":"219.226326ms","start":"2026-10-26T22:06:02.156584Z","end":"2026-10-26T22:06:02.37581Z","steps":["trace[861806315] 'process raft request' (duration: 218.854016ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:02.755715Z","caller":"traceutil/trace.go:171","msg":"trace[2068522604] linearizableReadLoop","detail":"{readStateIndex:21373; appliedIndex:21372; }","duration":"154.131375ms","start":"2026-10-26T22:06:02.601496Z","end":"2026-10-26T22:06:02.755628Z","steps":["trace[2068522604] 'read index received' (duration: 153.93094ms)","trace[2068522604] 'applied index is now lower than readState.Index' (duration: 199.355µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:06:02.755757Z","caller":"traceutil/trace.go:171","msg":"trace[454024002] transaction","detail":"{read_only:false; response_revision:20677; number_of_response:1; }","duration":"154.451364ms","start":"2026-10-26T22:06:02.601273Z","end":"2026-10-26T22:06:02.755724Z","steps":["trace[454024002] 'process raft request' (duration: 154.175597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:02.75591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.393113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T22:06:02.755963Z","caller":"traceutil/trace.go:171","msg":"trace[69058253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20677; }","duration":"154.503315ms","start":"2026-10-26T22:06:02.601447Z","end":"2026-10-26T22:06:02.755951Z","steps":["trace[69058253] 'agreement among raft nodes before linearized reading' (duration: 154.32207ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:02.98667Z","caller":"traceutil/trace.go:171","msg":"trace[1081662859] transaction","detail":"{read_only:false; response_revision:20678; number_of_response:1; }","duration":"226.420105ms","start":"2026-10-26T22:06:02.760227Z","end":"2026-10-26T22:06:02.986647Z","steps":["trace[1081662859] 'process raft request' (duration: 215.293637ms)","trace[1081662859] 'compare' (duration: 10.997444ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:09:07.525622Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19896} {"level":"info","ts":"2026-10-26T22:09:07.656624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19896,"took":"127.409866ms","hash":597985391,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":30474240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T22:09:07.656701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":597985391,"revision":19896,"compact-revision":17434} {"level":"info","ts":"2026-10-26T22:09:51.188177Z","caller":"traceutil/trace.go:171","msg":"trace[66595475] transaction","detail":"{read_only:false; response_revision:23055; number_of_response:1; }","duration":"207.717937ms","start":"2026-10-26T22:09:50.980425Z","end":"2026-10-26T22:09:51.188143Z","steps":["trace[66595475] 'process raft request' (duration: 207.46529ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:09:51.218771Z","caller":"traceutil/trace.go:171","msg":"trace[1187563570] transaction","detail":"{read_only:false; response_revision:23056; number_of_response:1; }","duration":"201.475224ms","start":"2026-10-26T22:09:51.017273Z","end":"2026-10-26T22:09:51.218748Z","steps":["trace[1187563570] 'process raft request' (duration: 201.249918ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:09:51.218972Z","caller":"traceutil/trace.go:171","msg":"trace[878344667] linearizableReadLoop","detail":"{readStateIndex:23825; appliedIndex:23822; }","duration":"136.475965ms","start":"2026-10-26T22:09:51.082487Z","end":"2026-10-26T22:09:51.218963Z","steps":["trace[878344667] 'read index received' (duration: 105.419056ms)","trace[878344667] 'applied index is now lower than readState.Index' (duration: 31.056099ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:09:51.219125Z","caller":"traceutil/trace.go:171","msg":"trace[1593606072] transaction","detail":"{read_only:false; response_revision:23057; number_of_response:1; }","duration":"163.944146ms","start":"2026-10-26T22:09:51.055168Z","end":"2026-10-26T22:09:51.219112Z","steps":["trace[1593606072] 'process raft request' (duration: 163.524404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:09:51.219277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.778914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.241\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-26T22:09:51.219308Z","caller":"traceutil/trace.go:171","msg":"trace[1583664459] range","detail":"{range_begin:/registry/masterleases/162.253.55.241; range_end:; response_count:1; response_revision:23057; }","duration":"136.855067ms","start":"2026-10-26T22:09:51.082445Z","end":"2026-10-26T22:09:51.2193Z","steps":["trace[1583664459] 'agreement among raft nodes before linearized reading' (duration: 136.736033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:13:04.284071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.534583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:13:04.301939Z","caller":"traceutil/trace.go:171","msg":"trace[1570887499] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"218.166407ms","start":"2026-10-26T22:13:04.083698Z","end":"2026-10-26T22:13:04.301864Z","steps":["trace[1570887499] 'process raft request' (duration: 48.768379ms)","trace[1570887499] 'compare' (duration: 150.911476ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:13:04.578336Z","caller":"traceutil/trace.go:171","msg":"trace[1533525126] linearizableReadLoop","detail":"{readStateIndex:25838; appliedIndex:25836; }","duration":"152.039027ms","start":"2026-10-26T22:13:04.426259Z","end":"2026-10-26T22:13:04.578298Z","steps":["trace[1533525126] 'read index received' (duration: 129.093542ms)","trace[1533525126] 'applied index is now lower than readState.Index' (duration: 22.944305ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:13:04.578365Z","caller":"traceutil/trace.go:171","msg":"trace[623964202] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"275.76099ms","start":"2026-10-26T22:13:04.302557Z","end":"2026-10-26T22:13:04.578318Z","steps":["trace[623964202] 'process raft request' (duration: 252.779384ms)","trace[623964202] 'compare' (duration: 22.504362ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:13:04.57849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.214041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:13:04.578543Z","caller":"traceutil/trace.go:171","msg":"trace[1835744186] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25020; }","duration":"152.309344ms","start":"2026-10-26T22:13:04.426216Z","end":"2026-10-26T22:13:04.578525Z","steps":["trace[1835744186] 'agreement among raft nodes before linearized reading' (duration: 152.205381ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:13:04.57921Z","caller":"traceutil/trace.go:171","msg":"trace[1456001388] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"276.534221ms","start":"2026-10-26T22:13:04.302662Z","end":"2026-10-26T22:13:04.579196Z","steps":["trace[1456001388] 'process raft request' (duration: 275.481452ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:13:04.795545Z","caller":"traceutil/trace.go:171","msg":"trace[538119411] linearizableReadLoop","detail":"{readStateIndex:25839; appliedIndex:25838; }","duration":"170.483577ms","start":"2026-10-26T22:13:04.625031Z","end":"2026-10-26T22:13:04.795515Z","steps":["trace[538119411] 'read index received' (duration: 146.124263ms)","trace[538119411] 'applied index is now lower than readState.Index' (duration: 24.358194ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:13:04.795718Z","caller":"traceutil/trace.go:171","msg":"trace[1368821599] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"171.181847ms","start":"2026-10-26T22:13:04.62447Z","end":"2026-10-26T22:13:04.795652Z","steps":["trace[1368821599] 'process raft request' (duration: 146.661518ms)","trace[1368821599] 'compare' (duration: 24.242481ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:13:04.795839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.785685ms","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-10-26T22:13:04.795922Z","caller":"traceutil/trace.go:171","msg":"trace[1492346781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25021; }","duration":"170.905009ms","start":"2026-10-26T22:13:04.624993Z","end":"2026-10-26T22:13:04.795898Z","steps":["trace[1492346781] 'agreement among raft nodes before linearized reading' (duration: 170.623031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:13:04.834092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.303697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-26T22:13:04.834176Z","caller":"traceutil/trace.go:171","msg":"trace[1380829981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25022; }","duration":"164.42969ms","start":"2026-10-26T22:13:04.669726Z","end":"2026-10-26T22:13:04.834156Z","steps":["trace[1380829981] 'agreement among raft nodes before linearized reading' (duration: 164.220765ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:13:04.834386Z","caller":"traceutil/trace.go:171","msg":"trace[1697728684] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"203.964014ms","start":"2026-10-26T22:13:04.630387Z","end":"2026-10-26T22:13:04.834351Z","steps":["trace[1697728684] 'process raft request' (duration: 203.439619ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:13:04.942301Z","caller":"traceutil/trace.go:171","msg":"trace[394837721] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"129.059641ms","start":"2026-10-26T22:13:04.81322Z","end":"2026-10-26T22:13:04.94228Z","steps":["trace[394837721] 'process raft request' (duration: 126.971623ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:13:04.942796Z","caller":"traceutil/trace.go:171","msg":"trace[1724176601] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"102.941199ms","start":"2026-10-26T22:13:04.839837Z","end":"2026-10-26T22:13:04.942778Z","steps":["trace[1724176601] 'process raft request' (duration: 102.409054ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:13:47.033098Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000668b.wal"} {"level":"info","ts":"2026-10-26T22:14:07.534608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22198} {"level":"info","ts":"2026-10-26T22:14:07.650306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22198,"took":"111.237438ms","hash":1009552181,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":44961792,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-26T22:14:07.650376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1009552181,"revision":22198,"compact-revision":19896} {"level":"info","ts":"2026-10-26T22:14:08.677873Z","caller":"traceutil/trace.go:171","msg":"trace[1840964161] linearizableReadLoop","detail":"{readStateIndex:26427; appliedIndex:26426; }","duration":"106.118696ms","start":"2026-10-26T22:14:08.571728Z","end":"2026-10-26T22:14:08.677846Z","steps":["trace[1840964161] 'read index received' (duration: 105.888549ms)","trace[1840964161] 'applied index is now lower than readState.Index' (duration: 228.977µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:14:08.678257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.512837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-10-26T22:14:08.678273Z","caller":"traceutil/trace.go:171","msg":"trace[673682380] transaction","detail":"{read_only:false; response_revision:25594; number_of_response:1; }","duration":"116.885434ms","start":"2026-10-26T22:14:08.561357Z","end":"2026-10-26T22:14:08.678243Z","steps":["trace[673682380] 'process raft request' (duration: 116.326108ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:14:08.678348Z","caller":"traceutil/trace.go:171","msg":"trace[1259446135] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25594; }","duration":"106.647381ms","start":"2026-10-26T22:14:08.571684Z","end":"2026-10-26T22:14:08.678332Z","steps":["trace[1259446135] 'agreement among raft nodes before linearized reading' (duration: 106.456906ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:19:07.541354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25588} {"level":"info","ts":"2026-10-26T22:19:07.726313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25588,"took":"179.439365ms","hash":4055022573,"current-db-size-bytes":59346944,"current-db-size":"59 MB","current-db-size-in-use-bytes":35930112,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T22:19:07.726398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4055022573,"revision":25588,"compact-revision":22198} {"level":"info","ts":"2026-10-26T22:20:27.779725Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:20:27.786754Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T22:20:27.786862Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T22:24:07.548839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28455} {"level":"info","ts":"2026-10-26T22:24:07.699744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28455,"took":"146.74209ms","hash":1491356521,"current-db-size-bytes":59346944,"current-db-size":"59 MB","current-db-size-in-use-bytes":30478336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T22:24:07.699806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1491356521,"revision":28455,"compact-revision":25588} {"level":"info","ts":"2026-10-26T22:29:07.557049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30678} {"level":"info","ts":"2026-10-26T22:29:07.681866Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30678,"took":"120.75925ms","hash":2525038397,"current-db-size-bytes":59346944,"current-db-size":"59 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:29:07.68194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2525038397,"revision":30678,"compact-revision":28455} {"level":"info","ts":"2026-10-26T22:34:07.565658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32932} {"level":"info","ts":"2026-10-26T22:34:07.720618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32932,"took":"148.127678ms","hash":1019336523,"current-db-size-bytes":60391424,"current-db-size":"60 MB","current-db-size-in-use-bytes":30998528,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:34:07.720705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1019336523,"revision":32932,"compact-revision":30678} {"level":"info","ts":"2026-10-26T22:39:07.589125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35229} {"level":"info","ts":"2026-10-26T22:39:07.728441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35229,"took":"134.131981ms","hash":3294155539,"current-db-size-bytes":60391424,"current-db-size":"60 MB","current-db-size-in-use-bytes":33214464,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T22:39:07.728506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3294155539,"revision":35229,"compact-revision":32932} {"level":"info","ts":"2026-10-26T22:41:58.741411Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:41:58.750604Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T22:41:58.750779Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T22:44:07.596686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37459} {"level":"info","ts":"2026-10-26T22:44:07.725334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37459,"took":"123.793731ms","hash":3284146681,"current-db-size-bytes":60391424,"current-db-size":"60 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:44:07.725426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3284146681,"revision":37459,"compact-revision":35229}