{"level":"warn","ts":"2026-09-03T05:12:36.287968Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-03T05:12:36.288088Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.123: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.123:2380","--initial-cluster=instance=https://162.253.55.123:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.123:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.123:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-03T05:12:36.288179Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-03T05:12:36.288193Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.123:2380"]} {"level":"info","ts":"2026-09-03T05:12:36.288247Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-03T05:12:36.289087Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"]} {"level":"info","ts":"2026-09-03T05:12:36.289174Z","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.123:2380"],"listen-peer-urls":["https://162.253.55.123:2380"],"advertise-client-urls":["https://162.253.55.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.123:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-03T05:12:36.294892Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.460123ms"} {"level":"info","ts":"2026-09-03T05:12:36.302486Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dd65ba9af29ebfc9","cluster-id":"67ca29879725fb76"} {"level":"info","ts":"2026-09-03T05:12:36.302592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-03T05:12:36.302621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became follower at term 0"} {"level":"info","ts":"2026-09-03T05:12:36.302638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dd65ba9af29ebfc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-03T05:12:36.302649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became follower at term 1"} {"level":"info","ts":"2026-09-03T05:12:36.302696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=(15953362429688070089)"} {"level":"warn","ts":"2026-09-03T05:12:36.312929Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-03T05:12:36.31436Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-03T05:12:36.315739Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-03T05:12:36.317845Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dd65ba9af29ebfc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-03T05:12:36.317921Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dd65ba9af29ebfc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-03T05:12:36.31824Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-03T05:12:36.318376Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-03T05:12:36.318386Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-03T05:12:36.32022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=(15953362429688070089)"} {"level":"info","ts":"2026-09-03T05:12:36.320522Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"67ca29879725fb76","local-member-id":"dd65ba9af29ebfc9","added-peer-id":"dd65ba9af29ebfc9","added-peer-peer-urls":["https://162.253.55.123:2380"]} {"level":"info","ts":"2026-09-03T05:12:36.321901Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-03T05:12:36.322148Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dd65ba9af29ebfc9","initial-advertise-peer-urls":["https://162.253.55.123:2380"],"listen-peer-urls":["https://162.253.55.123:2380"],"advertise-client-urls":["https://162.253.55.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-03T05:12:36.322175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-03T05:12:36.322246Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.123:2380"} {"level":"info","ts":"2026-09-03T05:12:36.322257Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.123:2380"} {"level":"info","ts":"2026-09-03T05:12:36.503157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-03T05:12:36.503257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-03T05:12:36.503493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 received MsgPreVoteResp from dd65ba9af29ebfc9 at term 1"} {"level":"info","ts":"2026-09-03T05:12:36.503523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became candidate at term 2"} {"level":"info","ts":"2026-09-03T05:12:36.503539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 received MsgVoteResp from dd65ba9af29ebfc9 at term 2"} {"level":"info","ts":"2026-09-03T05:12:36.50356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became leader at term 2"} {"level":"info","ts":"2026-09-03T05:12:36.503584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dd65ba9af29ebfc9 elected leader dd65ba9af29ebfc9 at term 2"} {"level":"info","ts":"2026-09-03T05:12:36.506437Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T05:12:36.508068Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dd65ba9af29ebfc9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.123:2379]}","request-path":"/0/members/dd65ba9af29ebfc9/attributes","cluster-id":"67ca29879725fb76","publish-timeout":"7s"} {"level":"info","ts":"2026-09-03T05:12:36.508244Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-03T05:12:36.508305Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-03T05:12:36.50855Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-03T05:12:36.508633Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-03T05:12:36.509116Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"67ca29879725fb76","local-member-id":"dd65ba9af29ebfc9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T05:12:36.509417Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T05:12:36.509516Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T05:12:36.512837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-03T05:12:36.513298Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.123:2379"} {"level":"warn","ts":"2026-09-03T05:12:52.741788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.442329ms","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-09-03T05:12:52.741968Z","caller":"traceutil/trace.go:171","msg":"trace[822131000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"100.707762ms","start":"2026-09-03T05:12:52.641235Z","end":"2026-09-03T05:12:52.741942Z","steps":["trace[822131000] 'range keys from in-memory index tree' (duration: 100.278477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:12:52.742068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.994398ms","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-09-03T05:12:52.742213Z","caller":"traceutil/trace.go:171","msg":"trace[152560911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"248.349071ms","start":"2026-09-03T05:12:52.493833Z","end":"2026-09-03T05:12:52.742182Z","steps":["trace[152560911] 'range keys from in-memory index tree' (duration: 247.877857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:12:52.742215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.025811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T05:12:52.742339Z","caller":"traceutil/trace.go:171","msg":"trace[1152398323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"126.212204ms","start":"2026-09-03T05:12:52.616086Z","end":"2026-09-03T05:12:52.742298Z","steps":["trace[1152398323] 'range keys from in-memory index tree' (duration: 125.91394ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:12:52.915959Z","caller":"traceutil/trace.go:171","msg":"trace[2072328532] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"168.574032ms","start":"2026-09-03T05:12:52.747353Z","end":"2026-09-03T05:12:52.915927Z","steps":["trace[2072328532] 'process raft request' (duration: 168.37574ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:12:52.927489Z","caller":"traceutil/trace.go:171","msg":"trace[1775784733] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"178.629762ms","start":"2026-09-03T05:12:52.74885Z","end":"2026-09-03T05:12:52.92748Z","steps":["trace[1775784733] 'process raft request' (duration: 178.46857ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:13:23.902884Z","caller":"traceutil/trace.go:171","msg":"trace[277199796] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"122.598357ms","start":"2026-09-03T05:13:23.780228Z","end":"2026-09-03T05:13:23.902826Z","steps":["trace[277199796] 'process raft request' (duration: 121.672745ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:13:24.141903Z","caller":"traceutil/trace.go:171","msg":"trace[1522442800] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"161.052837ms","start":"2026-09-03T05:13:23.980759Z","end":"2026-09-03T05:13:24.141812Z","steps":["trace[1522442800] 'process raft request' (duration: 160.896691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:13:24.720973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.074633ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T05:13:24.721273Z","caller":"traceutil/trace.go:171","msg":"trace[368243464] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:643; }","duration":"452.373944ms","start":"2026-09-03T05:13:24.26883Z","end":"2026-09-03T05:13:24.721204Z","steps":["trace[368243464] 'range keys from in-memory index tree' (duration: 452.00472ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:13:24.722085Z","caller":"traceutil/trace.go:171","msg":"trace[1054852049] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"518.945997ms","start":"2026-09-03T05:13:24.203116Z","end":"2026-09-03T05:13:24.722062Z","steps":["trace[1054852049] 'process raft request' (duration: 455.484345ms)","trace[1054852049] 'compare' (duration: 62.535949ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T05:13:24.722084Z","caller":"traceutil/trace.go:171","msg":"trace[949603476] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"189.981022ms","start":"2026-09-03T05:13:24.532037Z","end":"2026-09-03T05:13:24.722018Z","steps":["trace[949603476] 'read index received' (duration: 126.50388ms)","trace[949603476] 'applied index is now lower than readState.Index' (duration: 63.474882ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T05:13:24.722278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.20818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T05:13:24.722554Z","caller":"traceutil/trace.go:171","msg":"trace[1606660770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"190.49585ms","start":"2026-09-03T05:13:24.531958Z","end":"2026-09-03T05:13:24.722454Z","steps":["trace[1606660770] 'agreement among raft nodes before linearized reading' (duration: 190.174869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:13:24.723702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T05:13:24.203083Z","time spent":"519.060021ms","remote":"127.0.0.1:35140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T05:13:24.760259Z","caller":"traceutil/trace.go:171","msg":"trace[174584514] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"171.877607ms","start":"2026-09-03T05:13:24.58835Z","end":"2026-09-03T05:13:24.760228Z","steps":["trace[174584514] 'process raft request' (duration: 170.977175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:13:41.725777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.139498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T05:13:41.72597Z","caller":"traceutil/trace.go:171","msg":"trace[276655647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"193.370568ms","start":"2026-09-03T05:13:41.532526Z","end":"2026-09-03T05:13:41.725897Z","steps":["trace[276655647] 'range keys from in-memory index tree' (duration: 192.930899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:15:02.619596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.968216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-03T05:15:02.619732Z","caller":"traceutil/trace.go:171","msg":"trace[72698713] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1886; }","duration":"112.153088ms","start":"2026-09-03T05:15:02.507528Z","end":"2026-09-03T05:15:02.619681Z","steps":["trace[72698713] 'range keys from in-memory index tree' (duration: 111.761714ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:15:43.136613Z","caller":"traceutil/trace.go:171","msg":"trace[1628305795] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"125.869613ms","start":"2026-09-03T05:15:43.010719Z","end":"2026-09-03T05:15:43.136589Z","steps":["trace[1628305795] 'process raft request' (duration: 78.042024ms)","trace[1628305795] 'compare' (duration: 47.274001ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T05:15:43.136876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.185833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T05:15:43.136946Z","caller":"traceutil/trace.go:171","msg":"trace[2101146900] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2166; }","duration":"118.357455ms","start":"2026-09-03T05:15:43.01857Z","end":"2026-09-03T05:15:43.136927Z","steps":["trace[2101146900] 'agreement among raft nodes before linearized reading' (duration: 118.174852ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:15:43.136382Z","caller":"traceutil/trace.go:171","msg":"trace[1024366832] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"117.705389ms","start":"2026-09-03T05:15:43.018637Z","end":"2026-09-03T05:15:43.136343Z","steps":["trace[1024366832] 'read index received' (duration: 70.098155ms)","trace[1024366832] 'applied index is now lower than readState.Index' (duration: 47.605854ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T05:18:00.378851Z","caller":"traceutil/trace.go:171","msg":"trace[1617440108] transaction","detail":"{read_only:false; response_revision:2990; number_of_response:1; }","duration":"114.570268ms","start":"2026-09-03T05:18:00.264252Z","end":"2026-09-03T05:18:00.378822Z","steps":["trace[1617440108] 'process raft request' (duration: 113.901555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:18:05.913378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.619274ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-03T05:18:05.91354Z","caller":"traceutil/trace.go:171","msg":"trace[170958444] linearizableReadLoop","detail":"{readStateIndex:3135; appliedIndex:3134; }","duration":"130.243214ms","start":"2026-09-03T05:18:05.783271Z","end":"2026-09-03T05:18:05.913514Z","steps":["trace[170958444] 'read index received' (duration: 35.93µs)","trace[170958444] 'applied index is now lower than readState.Index' (duration: 130.204934ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T05:18:05.913752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.4618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-03T05:18:05.914082Z","caller":"traceutil/trace.go:171","msg":"trace[996826983] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3019; }","duration":"130.558762ms","start":"2026-09-03T05:18:05.783211Z","end":"2026-09-03T05:18:05.91377Z","steps":["trace[996826983] 'agreement among raft nodes before linearized reading' (duration: 130.361578ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:22:36.946006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2870} {"level":"info","ts":"2026-09-03T05:22:37.017624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2870,"took":"70.174778ms","hash":2861276131,"current-db-size-bytes":44048384,"current-db-size":"44 MB","current-db-size-in-use-bytes":20164608,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-03T05:22:37.017731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2861276131,"revision":2870,"compact-revision":-1} {"level":"info","ts":"2026-09-03T05:22:45.705729Z","caller":"traceutil/trace.go:171","msg":"trace[634966384] linearizableReadLoop","detail":"{readStateIndex:5274; appliedIndex:5273; }","duration":"159.992553ms","start":"2026-09-03T05:22:45.545713Z","end":"2026-09-03T05:22:45.705706Z","steps":["trace[634966384] 'read index received' (duration: 78.066507ms)","trace[634966384] 'applied index is now lower than readState.Index' (duration: 81.924995ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T05:22:45.705841Z","caller":"traceutil/trace.go:171","msg":"trace[1272477585] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"163.602478ms","start":"2026-09-03T05:22:45.542225Z","end":"2026-09-03T05:22:45.705828Z","steps":["trace[1272477585] 'process raft request' (duration: 81.545975ms)","trace[1272477585] 'compare' (duration: 81.588566ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T05:22:45.70618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.420068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-03T05:22:45.706275Z","caller":"traceutil/trace.go:171","msg":"trace[1502838115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5072; }","duration":"160.613178ms","start":"2026-09-03T05:22:45.545643Z","end":"2026-09-03T05:22:45.706256Z","steps":["trace[1502838115] 'agreement among raft nodes before linearized reading' (duration: 160.237947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:22:45.714705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.814783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T05:22:45.714767Z","caller":"traceutil/trace.go:171","msg":"trace[1734466959] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5073; }","duration":"138.905808ms","start":"2026-09-03T05:22:45.575845Z","end":"2026-09-03T05:22:45.714751Z","steps":["trace[1734466959] 'agreement among raft nodes before linearized reading' (duration: 138.794262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:22:53.407573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.142307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T05:22:53.407747Z","caller":"traceutil/trace.go:171","msg":"trace[287268231] linearizableReadLoop","detail":"{readStateIndex:5498; appliedIndex:5497; }","duration":"118.941847ms","start":"2026-09-03T05:22:53.288775Z","end":"2026-09-03T05:22:53.407717Z","steps":["trace[287268231] 'read index received' (duration: 5.404337ms)","trace[287268231] 'applied index is now lower than readState.Index' (duration: 113.53558ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T05:22:53.407794Z","caller":"traceutil/trace.go:171","msg":"trace[376497116] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"170.003596ms","start":"2026-09-03T05:22:53.237738Z","end":"2026-09-03T05:22:53.407741Z","steps":["trace[376497116] 'process raft request' (duration: 56.539532ms)","trace[376497116] 'compare' (duration: 112.887841ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T05:22:53.407923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.134887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T05:22:53.407973Z","caller":"traceutil/trace.go:171","msg":"trace[509199514] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5291; }","duration":"119.217352ms","start":"2026-09-03T05:22:53.28874Z","end":"2026-09-03T05:22:53.407958Z","steps":["trace[509199514] 'agreement among raft nodes before linearized reading' (duration: 119.115826ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:23:06.079275Z","caller":"traceutil/trace.go:171","msg":"trace[1366102536] linearizableReadLoop","detail":"{readStateIndex:5673; appliedIndex:5672; }","duration":"242.802948ms","start":"2026-09-03T05:23:05.836444Z","end":"2026-09-03T05:23:06.079247Z","steps":["trace[1366102536] 'read index received' (duration: 242.742474ms)","trace[1366102536] 'applied index is now lower than readState.Index' (duration: 59.574µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T05:23:06.079321Z","caller":"traceutil/trace.go:171","msg":"trace[1277027656] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"360.991115ms","start":"2026-09-03T05:23:05.718307Z","end":"2026-09-03T05:23:06.079298Z","steps":["trace[1277027656] 'process raft request' (duration: 360.796964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:23:06.079464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T05:23:05.718275Z","time spent":"361.098102ms","remote":"127.0.0.1:35202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T05:23:06.079518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.072074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-03T05:23:06.079552Z","caller":"traceutil/trace.go:171","msg":"trace[956078066] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5460; }","duration":"243.112166ms","start":"2026-09-03T05:23:05.836427Z","end":"2026-09-03T05:23:06.079539Z","steps":["trace[956078066] 'agreement among raft nodes before linearized reading' (duration: 242.952987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:23:06.096785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.079377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-03T05:23:06.096838Z","caller":"traceutil/trace.go:171","msg":"trace[990401146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5461; }","duration":"253.154841ms","start":"2026-09-03T05:23:05.84367Z","end":"2026-09-03T05:23:06.096825Z","steps":["trace[990401146] 'agreement among raft nodes before linearized reading' (duration: 252.993992ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:23:06.09694Z","caller":"traceutil/trace.go:171","msg":"trace[1064521717] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"217.655315ms","start":"2026-09-03T05:23:05.879274Z","end":"2026-09-03T05:23:06.09693Z","steps":["trace[1064521717] 'process raft request' (duration: 217.275554ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:23:06.627453Z","caller":"traceutil/trace.go:171","msg":"trace[122581128] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"164.983998ms","start":"2026-09-03T05:23:06.462437Z","end":"2026-09-03T05:23:06.627421Z","steps":["trace[122581128] 'process raft request' (duration: 147.280152ms)","trace[122581128] 'compare' (duration: 17.430409ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T05:23:06.62799Z","caller":"traceutil/trace.go:171","msg":"trace[1692324834] linearizableReadLoop","detail":"{readStateIndex:5682; appliedIndex:5679; }","duration":"129.997819ms","start":"2026-09-03T05:23:06.497964Z","end":"2026-09-03T05:23:06.627961Z","steps":["trace[1692324834] 'read index received' (duration: 111.70036ms)","trace[1692324834] 'applied index is now lower than readState.Index' (duration: 18.291779ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T05:23:06.628064Z","caller":"traceutil/trace.go:171","msg":"trace[1812810372] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"165.56149ms","start":"2026-09-03T05:23:06.462462Z","end":"2026-09-03T05:23:06.628023Z","steps":["trace[1812810372] 'process raft request' (duration: 164.877581ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:23:06.628147Z","caller":"traceutil/trace.go:171","msg":"trace[70408387] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"165.571261ms","start":"2026-09-03T05:23:06.462562Z","end":"2026-09-03T05:23:06.628133Z","steps":["trace[70408387] 'process raft request' (duration: 165.262533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T05:23:06.62833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.34591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-03T05:23:06.62858Z","caller":"traceutil/trace.go:171","msg":"trace[918026275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5469; }","duration":"130.651767ms","start":"2026-09-03T05:23:06.497913Z","end":"2026-09-03T05:23:06.628565Z","steps":["trace[918026275] 'agreement among raft nodes before linearized reading' (duration: 130.253384ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:23:06.776081Z","caller":"traceutil/trace.go:171","msg":"trace[1515397308] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"139.038768ms","start":"2026-09-03T05:23:06.637016Z","end":"2026-09-03T05:23:06.776054Z","steps":["trace[1515397308] 'process raft request' (duration: 109.233808ms)","trace[1515397308] 'compare' (duration: 29.62163ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T05:23:06.776432Z","caller":"traceutil/trace.go:171","msg":"trace[788164127] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"138.392721ms","start":"2026-09-03T05:23:06.638012Z","end":"2026-09-03T05:23:06.776404Z","steps":["trace[788164127] 'process raft request' (duration: 138.305436ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:23:06.776703Z","caller":"traceutil/trace.go:171","msg":"trace[1945478471] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"139.600511ms","start":"2026-09-03T05:23:06.637092Z","end":"2026-09-03T05:23:06.776692Z","steps":["trace[1945478471] 'process raft request' (duration: 138.905711ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:23:44.069453Z","caller":"traceutil/trace.go:171","msg":"trace[214497413] transaction","detail":"{read_only:false; response_revision:5799; number_of_response:1; }","duration":"170.152887ms","start":"2026-09-03T05:23:43.899263Z","end":"2026-09-03T05:23:44.069416Z","steps":["trace[214497413] 'process raft request' (duration: 169.912802ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T05:27:36.953972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4626} {"level":"info","ts":"2026-09-03T05:27:37.015132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4626,"took":"58.925236ms","hash":13119294,"current-db-size-bytes":44048384,"current-db-size":"44 MB","current-db-size-in-use-bytes":33972224,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-03T05:27:37.015226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":13119294,"revision":4626,"compact-revision":2870}