{"level":"warn","ts":"2026-10-06T13:05:57.792897Z","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-06T13:05:57.793042Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.44: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.44:2380","--initial-cluster=instance=https://162.253.55.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.44: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-06T13:05:57.793124Z","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-06T13:05:57.793148Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-10-06T13:05:57.793175Z","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-06T13:05:57.793861Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"]} {"level":"info","ts":"2026-10-06T13:05:57.794021Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.44: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-06T13:05:57.801035Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.654569ms"} {"level":"info","ts":"2026-10-06T13:05:57.808587Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4cb6cb294c6743","cluster-id":"77f9414eebec887e"} {"level":"info","ts":"2026-10-06T13:05:57.808725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=()"} {"level":"info","ts":"2026-10-06T13:05:57.808803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 0"} {"level":"info","ts":"2026-10-06T13:05:57.808839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4cb6cb294c6743 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-06T13:05:57.808906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 1"} {"level":"info","ts":"2026-10-06T13:05:57.808988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"warn","ts":"2026-10-06T13:05:57.820282Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-06T13:05:57.832864Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-06T13:05:57.843157Z","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-06T13:05:57.845463Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4cb6cb294c6743","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-06T13:05:57.84571Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4cb6cb294c6743","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-06T13:05:57.845855Z","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-06T13:05:57.845916Z","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-06T13:05:57.845926Z","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-06T13:05:57.846724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"info","ts":"2026-10-06T13:05:57.846947Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","added-peer-id":"b4cb6cb294c6743","added-peer-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-10-06T13:05:57.850805Z","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-06T13:05:57.851114Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4cb6cb294c6743","initial-advertise-peer-urls":["https://162.253.55.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-06T13:05:57.851171Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-06T13:05:57.851705Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-10-06T13:05:57.851799Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-10-06T13:05:58.309338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 is starting a new election at term 1"} {"level":"info","ts":"2026-10-06T13:05:58.309396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-06T13:05:58.30943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgPreVoteResp from b4cb6cb294c6743 at term 1"} {"level":"info","ts":"2026-10-06T13:05:58.309476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became candidate at term 2"} {"level":"info","ts":"2026-10-06T13:05:58.309489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgVoteResp from b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-10-06T13:05:58.309513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became leader at term 2"} {"level":"info","ts":"2026-10-06T13:05:58.309526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4cb6cb294c6743 elected leader b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-10-06T13:05:58.310552Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T13:05:58.311339Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4cb6cb294c6743","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.44:2379]}","request-path":"/0/members/b4cb6cb294c6743/attributes","cluster-id":"77f9414eebec887e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-06T13:05:58.311487Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T13:05:58.311566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T13:05:58.31211Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T13:05:58.312243Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T13:05:58.312284Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T13:05:58.314366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.44:2379"} {"level":"info","ts":"2026-10-06T13:05:58.314507Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-06T13:05:58.314572Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-06T13:05:58.314961Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-06T13:06:08.354913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.810545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-10-06T13:06:08.35503Z","caller":"traceutil/trace.go:171","msg":"trace[130179544] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:353; }","duration":"112.970668ms","start":"2026-10-06T13:06:08.242036Z","end":"2026-10-06T13:06:08.355006Z","steps":["trace[130179544] 'range keys from in-memory index tree' (duration: 112.697243ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:14.030479Z","caller":"traceutil/trace.go:171","msg":"trace[413558880] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"163.286808ms","start":"2026-10-06T13:06:13.867165Z","end":"2026-10-06T13:06:14.030452Z","steps":["trace[413558880] 'process raft request' (duration: 163.121615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:06:14.292295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.681483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T13:06:14.292574Z","caller":"traceutil/trace.go:171","msg":"trace[765915318] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"256.790684ms","start":"2026-10-06T13:06:14.035724Z","end":"2026-10-06T13:06:14.292514Z","steps":["trace[765915318] 'process raft request' (duration: 75.238282ms)","trace[765915318] 'compare' (duration: 180.319136ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:06:14.292786Z","caller":"traceutil/trace.go:171","msg":"trace[172523898] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"256.699671ms","start":"2026-10-06T13:06:14.036055Z","end":"2026-10-06T13:06:14.292755Z","steps":["trace[172523898] 'process raft request' (duration: 256.348264ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:14.292857Z","caller":"traceutil/trace.go:171","msg":"trace[1146596114] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:384; }","duration":"146.182597ms","start":"2026-10-06T13:06:14.146521Z","end":"2026-10-06T13:06:14.292704Z","steps":["trace[1146596114] 'read index received' (duration: 52.432µs)","trace[1146596114] 'applied index is now lower than readState.Index' (duration: 146.129235ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:06:14.292849Z","caller":"traceutil/trace.go:171","msg":"trace[1288440354] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"256.573129ms","start":"2026-10-06T13:06:14.036197Z","end":"2026-10-06T13:06:14.29277Z","steps":["trace[1288440354] 'process raft request' (duration: 256.282133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:06:14.293071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.545244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T13:06:14.29316Z","caller":"traceutil/trace.go:171","msg":"trace[876174441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"146.636836ms","start":"2026-10-06T13:06:14.146506Z","end":"2026-10-06T13:06:14.293143Z","steps":["trace[876174441] 'agreement among raft nodes before linearized reading' (duration: 146.472523ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:24.549108Z","caller":"traceutil/trace.go:171","msg":"trace[658198634] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"124.745804ms","start":"2026-10-06T13:06:24.424336Z","end":"2026-10-06T13:06:24.549082Z","steps":["trace[658198634] 'process raft request' (duration: 124.643742ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:35.989219Z","caller":"traceutil/trace.go:171","msg":"trace[1179090976] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"176.307713ms","start":"2026-10-06T13:06:35.812887Z","end":"2026-10-06T13:06:35.989195Z","steps":["trace[1179090976] 'process raft request' (duration: 176.141069ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023007Z","caller":"traceutil/trace.go:171","msg":"trace[179463236] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"205.734447ms","start":"2026-10-06T13:06:35.817239Z","end":"2026-10-06T13:06:36.022973Z","steps":["trace[179463236] 'process raft request' (duration: 205.473042ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023221Z","caller":"traceutil/trace.go:171","msg":"trace[922207520] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"150.39221ms","start":"2026-10-06T13:06:35.872816Z","end":"2026-10-06T13:06:36.023208Z","steps":["trace[922207520] 'process raft request' (duration: 150.225357ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023192Z","caller":"traceutil/trace.go:171","msg":"trace[118139802] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"164.270853ms","start":"2026-10-06T13:06:35.858879Z","end":"2026-10-06T13:06:36.023149Z","steps":["trace[118139802] 'process raft request' (duration: 164.060829ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023311Z","caller":"traceutil/trace.go:171","msg":"trace[676512618] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"146.442835ms","start":"2026-10-06T13:06:35.87686Z","end":"2026-10-06T13:06:36.023303Z","steps":["trace[676512618] 'process raft request' (duration: 146.245152ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.02331Z","caller":"traceutil/trace.go:171","msg":"trace[168345309] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"158.632467ms","start":"2026-10-06T13:06:35.864554Z","end":"2026-10-06T13:06:36.023186Z","steps":["trace[168345309] 'process raft request' (duration: 158.431953ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023329Z","caller":"traceutil/trace.go:171","msg":"trace[1832291798] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"164.298604ms","start":"2026-10-06T13:06:35.858808Z","end":"2026-10-06T13:06:36.023106Z","steps":["trace[1832291798] 'process raft request' (duration: 164.073059ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023437Z","caller":"traceutil/trace.go:171","msg":"trace[1639068332] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"146.500107ms","start":"2026-10-06T13:06:35.876927Z","end":"2026-10-06T13:06:36.023428Z","steps":["trace[1639068332] 'process raft request' (duration: 146.244942ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023816Z","caller":"traceutil/trace.go:171","msg":"trace[95761601] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"129.062867ms","start":"2026-10-06T13:06:35.894737Z","end":"2026-10-06T13:06:36.023799Z","steps":["trace[95761601] 'process raft request' (duration: 128.524287ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023819Z","caller":"traceutil/trace.go:171","msg":"trace[963272476] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"111.465575ms","start":"2026-10-06T13:06:35.912308Z","end":"2026-10-06T13:06:36.023774Z","steps":["trace[963272476] 'process raft request' (duration: 111.279091ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:36.023099Z","caller":"traceutil/trace.go:171","msg":"trace[1834721389] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"195.229368ms","start":"2026-10-06T13:06:35.827816Z","end":"2026-10-06T13:06:36.023046Z","steps":["trace[1834721389] 'process raft request' (duration: 195.018365ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:42.511512Z","caller":"traceutil/trace.go:171","msg":"trace[1866182238] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"230.63685ms","start":"2026-10-06T13:06:42.280845Z","end":"2026-10-06T13:06:42.511482Z","steps":["trace[1866182238] 'process raft request' (duration: 228.842886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:06:43.180968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.894122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-06T13:06:43.181187Z","caller":"traceutil/trace.go:171","msg":"trace[337816776] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"548.242228ms","start":"2026-10-06T13:06:42.632926Z","end":"2026-10-06T13:06:43.181168Z","steps":["trace[337816776] 'read index received' (duration: 276.04893ms)","trace[337816776] 'applied index is now lower than readState.Index' (duration: 272.192188ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:06:43.181293Z","caller":"traceutil/trace.go:171","msg":"trace[2021399559] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"560.574905ms","start":"2026-10-06T13:06:42.620708Z","end":"2026-10-06T13:06:43.181283Z","steps":["trace[2021399559] 'process raft request' (duration: 560.371301ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:06:43.181328Z","caller":"traceutil/trace.go:171","msg":"trace[1414650382] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"569.687623ms","start":"2026-10-06T13:06:42.611608Z","end":"2026-10-06T13:06:43.181296Z","steps":["trace[1414650382] 'process raft request' (duration: 297.372583ms)","trace[1414650382] 'compare' (duration: 271.376733ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:06:43.181386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:06:42.620678Z","time spent":"560.643616ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T13:06:43.181495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:06:42.61158Z","time spent":"569.848376ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-06T13:06:43.181728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.799318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gs2mx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-06T13:06:43.181765Z","caller":"traceutil/trace.go:171","msg":"trace[996149410] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gs2mx; range_end:; response_count:1; response_revision:587; }","duration":"548.86427ms","start":"2026-10-06T13:06:42.63289Z","end":"2026-10-06T13:06:43.181754Z","steps":["trace[996149410] 'agreement among raft nodes before linearized reading' (duration: 548.707627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:06:43.181802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:06:42.632879Z","time spent":"548.91653ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-gs2mx\" "} {"level":"warn","ts":"2026-10-06T13:06:43.181853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.385028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-06T13:06:43.181898Z","caller":"traceutil/trace.go:171","msg":"trace[573463054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"523.47736ms","start":"2026-10-06T13:06:42.65841Z","end":"2026-10-06T13:06:43.181887Z","steps":["trace[573463054] 'agreement among raft nodes before linearized reading' (duration: 523.383508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:06:43.181933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:06:42.658377Z","time spent":"523.548082ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-06T13:06:43.182224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.92108ms","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-06T13:06:43.182264Z","caller":"traceutil/trace.go:171","msg":"trace[954424273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"241.029933ms","start":"2026-10-06T13:06:42.941224Z","end":"2026-10-06T13:06:43.182254Z","steps":["trace[954424273] 'agreement among raft nodes before linearized reading' (duration: 240.962221ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:08:23.373555Z","caller":"traceutil/trace.go:171","msg":"trace[2085709545] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"168.580011ms","start":"2026-10-06T13:08:23.20464Z","end":"2026-10-06T13:08:23.37322Z","steps":["trace[2085709545] 'process raft request' (duration: 159.986828ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:08:23.573679Z","caller":"traceutil/trace.go:171","msg":"trace[674803076] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"362.6177ms","start":"2026-10-06T13:08:23.211033Z","end":"2026-10-06T13:08:23.573651Z","steps":["trace[674803076] 'process raft request' (duration: 351.749659ms)","trace[674803076] 'compare' (duration: 10.681355ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:08:23.57402Z","caller":"traceutil/trace.go:171","msg":"trace[197806741] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"352.146345ms","start":"2026-10-06T13:08:23.221837Z","end":"2026-10-06T13:08:23.573984Z","steps":["trace[197806741] 'process raft request' (duration: 351.742571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:08:23.574694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:08:23.221813Z","time spent":"352.805327ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T13:08:23.57413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:08:23.211019Z","time spent":"363.009214ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T13:08:23.574867Z","caller":"traceutil/trace.go:171","msg":"trace[463864838] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1942; }","duration":"238.769765ms","start":"2026-10-06T13:08:23.336062Z","end":"2026-10-06T13:08:23.574832Z","steps":["trace[463864838] 'read index received' (duration: 28.541693ms)","trace[463864838] 'applied index is now lower than readState.Index' (duration: 210.193581ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:08:23.574911Z","caller":"traceutil/trace.go:171","msg":"trace[1617734583] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"226.204546ms","start":"2026-10-06T13:08:23.348692Z","end":"2026-10-06T13:08:23.574896Z","steps":["trace[1617734583] 'process raft request' (duration: 225.912397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:08:23.575104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.018715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-06T13:08:23.575591Z","caller":"traceutil/trace.go:171","msg":"trace[1621606054] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1873; }","duration":"239.555343ms","start":"2026-10-06T13:08:23.33602Z","end":"2026-10-06T13:08:23.575575Z","steps":["trace[1621606054] 'agreement among raft nodes before linearized reading' (duration: 238.916871ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:08:38.798636Z","caller":"traceutil/trace.go:171","msg":"trace[2093362369] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"175.133195ms","start":"2026-10-06T13:08:38.623459Z","end":"2026-10-06T13:08:38.798592Z","steps":["trace[2093362369] 'process raft request' (duration: 175.021421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:09:02.290713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.071713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T13:09:02.29101Z","caller":"traceutil/trace.go:171","msg":"trace[469298975] linearizableReadLoop","detail":"{readStateIndex:2214; appliedIndex:2213; }","duration":"204.796128ms","start":"2026-10-06T13:09:02.086188Z","end":"2026-10-06T13:09:02.290984Z","steps":["trace[469298975] 'read index received' (duration: 15.23011ms)","trace[469298975] 'applied index is now lower than readState.Index' (duration: 189.564728ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:09:02.291081Z","caller":"traceutil/trace.go:171","msg":"trace[1211737276] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"238.149878ms","start":"2026-10-06T13:09:02.052917Z","end":"2026-10-06T13:09:02.291067Z","steps":["trace[1211737276] 'process raft request' (duration: 48.58541ms)","trace[1211737276] 'compare' (duration: 188.701281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:09:02.291621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.395327ms","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-06T13:09:02.291768Z","caller":"traceutil/trace.go:171","msg":"trace[1358802676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2133; }","duration":"205.611622ms","start":"2026-10-06T13:09:02.08613Z","end":"2026-10-06T13:09:02.291741Z","steps":["trace[1358802676] 'agreement among raft nodes before linearized reading' (duration: 205.268322ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:09:02.302457Z","caller":"traceutil/trace.go:171","msg":"trace[2025994365] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"147.536692ms","start":"2026-10-06T13:09:02.154864Z","end":"2026-10-06T13:09:02.3024Z","steps":["trace[2025994365] 'process raft request' (duration: 147.246484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:09:02.302495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.575281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T13:09:02.302559Z","caller":"traceutil/trace.go:171","msg":"trace[1594602462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2134; }","duration":"155.664474ms","start":"2026-10-06T13:09:02.146876Z","end":"2026-10-06T13:09:02.30254Z","steps":["trace[1594602462] 'agreement among raft nodes before linearized reading' (duration: 155.53065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:09:02.303116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.97383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-06T13:09:02.30319Z","caller":"traceutil/trace.go:171","msg":"trace[808036676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2134; }","duration":"216.057412ms","start":"2026-10-06T13:09:02.087106Z","end":"2026-10-06T13:09:02.303164Z","steps":["trace[808036676] 'agreement among raft nodes before linearized reading' (duration: 215.245618ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:09:54.129158Z","caller":"traceutil/trace.go:171","msg":"trace[457180278] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"153.804364ms","start":"2026-10-06T13:09:53.975324Z","end":"2026-10-06T13:09:54.129128Z","steps":["trace[457180278] 'process raft request' (duration: 153.610279ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:09:54.129479Z","caller":"traceutil/trace.go:171","msg":"trace[757937484] linearizableReadLoop","detail":"{readStateIndex:2613; appliedIndex:2613; }","duration":"124.040656ms","start":"2026-10-06T13:09:54.005222Z","end":"2026-10-06T13:09:54.129263Z","steps":["trace[757937484] 'read index received' (duration: 124.032435ms)","trace[757937484] 'applied index is now lower than readState.Index' (duration: 6.3µs)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:09:54.12962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.375764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-06T13:09:54.129657Z","caller":"traceutil/trace.go:171","msg":"trace[27329021] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2519; }","duration":"124.499147ms","start":"2026-10-06T13:09:54.005148Z","end":"2026-10-06T13:09:54.129647Z","steps":["trace[27329021] 'agreement among raft nodes before linearized reading' (duration: 124.125878ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:10:26.435734Z","caller":"traceutil/trace.go:171","msg":"trace[630408408] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"168.671099ms","start":"2026-10-06T13:10:26.267042Z","end":"2026-10-06T13:10:26.435714Z","steps":["trace[630408408] 'process raft request' (duration: 168.478855ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:10:26.447114Z","caller":"traceutil/trace.go:171","msg":"trace[1390550453] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2795; }","duration":"113.868487ms","start":"2026-10-06T13:10:26.333223Z","end":"2026-10-06T13:10:26.447092Z","steps":["trace[1390550453] 'read index received' (duration: 102.388366ms)","trace[1390550453] 'applied index is now lower than readState.Index' (duration: 11.479051ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:10:26.447162Z","caller":"traceutil/trace.go:171","msg":"trace[516869833] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"178.328867ms","start":"2026-10-06T13:10:26.26881Z","end":"2026-10-06T13:10:26.447139Z","steps":["trace[516869833] 'process raft request' (duration: 178.166523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:10:26.447479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.233355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-06T13:10:26.447523Z","caller":"traceutil/trace.go:171","msg":"trace[1943859578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2696; }","duration":"114.296716ms","start":"2026-10-06T13:10:26.333215Z","end":"2026-10-06T13:10:26.447512Z","steps":["trace[1943859578] 'agreement among raft nodes before linearized reading' (duration: 113.988529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:14:57.994752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.497116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-06T13:14:57.994893Z","caller":"traceutil/trace.go:171","msg":"trace[1260700836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4716; }","duration":"223.666749ms","start":"2026-10-06T13:14:57.7712Z","end":"2026-10-06T13:14:57.994866Z","steps":["trace[1260700836] 'range keys from in-memory index tree' (duration: 223.321733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:14:57.994991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.897326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-06T13:14:57.99513Z","caller":"traceutil/trace.go:171","msg":"trace[610992677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4716; }","duration":"119.09771ms","start":"2026-10-06T13:14:57.876004Z","end":"2026-10-06T13:14:57.995101Z","steps":["trace[610992677] 'range keys from in-memory index tree' (duration: 118.657472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:14:57.995173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.378796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-06T13:14:57.995451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.300292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-10-06T13:14:57.995534Z","caller":"traceutil/trace.go:171","msg":"trace[388717017] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:0; response_revision:4716; }","duration":"289.116269ms","start":"2026-10-06T13:14:57.706258Z","end":"2026-10-06T13:14:57.995374Z","steps":["trace[388717017] 'range keys from in-memory index tree' (duration: 288.327875ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:14:57.995654Z","caller":"traceutil/trace.go:171","msg":"trace[694784720] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4716; }","duration":"162.562126ms","start":"2026-10-06T13:14:57.833013Z","end":"2026-10-06T13:14:57.995575Z","steps":["trace[694784720] 'range keys from in-memory index tree' (duration: 161.933156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:14:57.99646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.796564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-06T13:14:57.996555Z","caller":"traceutil/trace.go:171","msg":"trace[657166670] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4716; }","duration":"288.930265ms","start":"2026-10-06T13:14:57.707602Z","end":"2026-10-06T13:14:57.996533Z","steps":["trace[657166670] 'range keys from in-memory index tree' (duration: 288.681341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:06.051432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.986837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-06T13:15:06.051518Z","caller":"traceutil/trace.go:171","msg":"trace[1711835346] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4924; }","duration":"214.119078ms","start":"2026-10-06T13:15:05.83738Z","end":"2026-10-06T13:15:06.051499Z","steps":["trace[1711835346] 'range keys from in-memory index tree' (duration: 213.808463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:06.052017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.101872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T13:15:06.052436Z","caller":"traceutil/trace.go:171","msg":"trace[981129105] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"189.313466ms","start":"2026-10-06T13:15:05.862928Z","end":"2026-10-06T13:15:06.052241Z","steps":["trace[981129105] 'process raft request' (duration: 45.890909ms)","trace[981129105] 'compare' (duration: 142.99042ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:15:06.194166Z","caller":"traceutil/trace.go:171","msg":"trace[209324771] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"126.039366ms","start":"2026-10-06T13:15:06.068102Z","end":"2026-10-06T13:15:06.194141Z","steps":["trace[209324771] 'process raft request' (duration: 83.237033ms)","trace[209324771] 'compare' (duration: 42.477648ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:15:06.194336Z","caller":"traceutil/trace.go:171","msg":"trace[1389238472] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"124.435058ms","start":"2026-10-06T13:15:06.069875Z","end":"2026-10-06T13:15:06.194311Z","steps":["trace[1389238472] 'process raft request' (duration: 124.166774ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:15:06.546982Z","caller":"traceutil/trace.go:171","msg":"trace[718466215] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"138.226525ms","start":"2026-10-06T13:15:06.408726Z","end":"2026-10-06T13:15:06.546953Z","steps":["trace[718466215] 'process raft request' (duration: 45.96262ms)","trace[718466215] 'compare' (duration: 91.600513ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:15:22.478649Z","caller":"traceutil/trace.go:171","msg":"trace[1753530887] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5327; }","duration":"472.251909ms","start":"2026-10-06T13:15:22.006347Z","end":"2026-10-06T13:15:22.478599Z","steps":["trace[1753530887] 'read index received' (duration: 472.181808ms)","trace[1753530887] 'applied index is now lower than readState.Index' (duration: 67.221µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:15:22.478736Z","caller":"traceutil/trace.go:171","msg":"trace[2100484460] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"473.162375ms","start":"2026-10-06T13:15:22.005546Z","end":"2026-10-06T13:15:22.478708Z","steps":["trace[2100484460] 'process raft request' (duration: 472.88866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:22.478891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:15:22.005536Z","time spent":"473.270867ms","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T13:15:22.478976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.602195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-10-06T13:15:22.479064Z","caller":"traceutil/trace.go:171","msg":"trace[169041148] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5135; }","duration":"472.727908ms","start":"2026-10-06T13:15:22.006318Z","end":"2026-10-06T13:15:22.479046Z","steps":["trace[169041148] 'agreement among raft nodes before linearized reading' (duration: 472.468313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:22.479103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:15:22.006308Z","time spent":"472.787329ms","remote":"127.0.0.1:40062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1475,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-10-06T13:15:22.540183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.292896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T13:15:22.540261Z","caller":"traceutil/trace.go:171","msg":"trace[1246943234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5136; }","duration":"394.421159ms","start":"2026-10-06T13:15:22.145822Z","end":"2026-10-06T13:15:22.540243Z","steps":["trace[1246943234] 'agreement among raft nodes before linearized reading' (duration: 394.291007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:22.540297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T13:15:22.145803Z","time spent":"394.48637ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-06T13:15:22.540292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.454835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-06T13:15:22.540404Z","caller":"traceutil/trace.go:171","msg":"trace[1039534988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5136; }","duration":"219.621887ms","start":"2026-10-06T13:15:22.32076Z","end":"2026-10-06T13:15:22.540382Z","steps":["trace[1039534988] 'agreement among raft nodes before linearized reading' (duration: 219.345942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:22.540477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.797033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-06T13:15:22.540567Z","caller":"traceutil/trace.go:171","msg":"trace[1260794874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5136; }","duration":"240.954705ms","start":"2026-10-06T13:15:22.299595Z","end":"2026-10-06T13:15:22.54055Z","steps":["trace[1260794874] 'agreement among raft nodes before linearized reading' (duration: 240.758442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:22.540857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.743374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-06T13:15:22.540922Z","caller":"traceutil/trace.go:171","msg":"trace[1155135841] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5136; }","duration":"150.837786ms","start":"2026-10-06T13:15:22.390066Z","end":"2026-10-06T13:15:22.540903Z","steps":["trace[1155135841] 'agreement among raft nodes before linearized reading' (duration: 150.688864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:22.541784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.583384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16544"} {"level":"info","ts":"2026-10-06T13:15:22.542788Z","caller":"traceutil/trace.go:171","msg":"trace[1458125956] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5136; }","duration":"171.679365ms","start":"2026-10-06T13:15:22.370146Z","end":"2026-10-06T13:15:22.541825Z","steps":["trace[1458125956] 'agreement among raft nodes before linearized reading' (duration: 170.77458ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:15:22.760515Z","caller":"traceutil/trace.go:171","msg":"trace[234062254] linearizableReadLoop","detail":"{readStateIndex:5334; appliedIndex:5333; }","duration":"137.007321ms","start":"2026-10-06T13:15:22.623449Z","end":"2026-10-06T13:15:22.760456Z","steps":["trace[234062254] 'read index received' (duration: 43.267329ms)","trace[234062254] 'applied index is now lower than readState.Index' (duration: 93.738842ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T13:15:22.760571Z","caller":"traceutil/trace.go:171","msg":"trace[1747146240] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"195.700113ms","start":"2026-10-06T13:15:22.564839Z","end":"2026-10-06T13:15:22.760539Z","steps":["trace[1747146240] 'process raft request' (duration: 101.913769ms)","trace[1747146240] 'compare' (duration: 93.5503ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T13:15:22.760877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.54148ms","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-06T13:15:22.760949Z","caller":"traceutil/trace.go:171","msg":"trace[1015866138] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5141; }","duration":"199.694662ms","start":"2026-10-06T13:15:22.561232Z","end":"2026-10-06T13:15:22.760927Z","steps":["trace[1015866138] 'agreement among raft nodes before linearized reading' (duration: 199.461478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:22.760905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.957081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-10-06T13:15:22.761064Z","caller":"traceutil/trace.go:171","msg":"trace[466319957] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5141; }","duration":"114.148045ms","start":"2026-10-06T13:15:22.646896Z","end":"2026-10-06T13:15:22.761044Z","steps":["trace[466319957] 'agreement among raft nodes before linearized reading' (duration: 113.89725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T13:15:22.761151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.495633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-06T13:15:22.761209Z","caller":"traceutil/trace.go:171","msg":"trace[480460684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5141; }","duration":"113.756438ms","start":"2026-10-06T13:15:22.647436Z","end":"2026-10-06T13:15:22.761193Z","steps":["trace[480460684] 'agreement among raft nodes before linearized reading' (duration: 113.422061ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T13:15:58.370818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2865} {"level":"info","ts":"2026-10-06T13:15:58.496079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2865,"took":"123.961119ms","hash":927945061,"current-db-size-bytes":51732480,"current-db-size":"52 MB","current-db-size-in-use-bytes":28266496,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-06T13:15:58.496155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":927945061,"revision":2865,"compact-revision":-1} {"level":"info","ts":"2026-10-06T13:20:58.376956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5444} {"level":"info","ts":"2026-10-06T13:20:58.492977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5444,"took":"114.406067ms","hash":2871609130,"current-db-size-bytes":51732480,"current-db-size":"52 MB","current-db-size-in-use-bytes":18411520,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-06T13:20:58.493043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2871609130,"revision":5444,"compact-revision":2865}