{"level":"warn","ts":"2026-10-14T02:19:35.925085Z","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-14T02:19:35.925176Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.138: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.138:2380","--initial-cluster=instance=https://162.253.55.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.138: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-14T02:19:35.925252Z","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-14T02:19:35.925263Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-10-14T02:19:35.925287Z","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-14T02:19:35.926118Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"]} {"level":"info","ts":"2026-10-14T02:19:35.926204Z","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.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.138: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-14T02:19:35.930343Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.903839ms"} {"level":"info","ts":"2026-10-14T02:19:35.948373Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ee8e5a779fc25cea","cluster-id":"8f8e3b77da0cbcef"} {"level":"info","ts":"2026-10-14T02:19:35.948468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T02:19:35.948494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 0"} {"level":"info","ts":"2026-10-14T02:19:35.948504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ee8e5a779fc25cea [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T02:19:35.94852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 1"} {"level":"info","ts":"2026-10-14T02:19:35.948582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"warn","ts":"2026-10-14T02:19:35.96088Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T02:19:35.963595Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T02:19:35.964777Z","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-14T02:19:35.967739Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ee8e5a779fc25cea","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T02:19:35.968484Z","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-14T02:19:35.968454Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ee8e5a779fc25cea","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T02:19:35.970169Z","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-14T02:19:35.970218Z","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-14T02:19:35.971361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"info","ts":"2026-10-14T02:19:35.97149Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","added-peer-id":"ee8e5a779fc25cea","added-peer-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-10-14T02:19:35.972289Z","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-14T02:19:35.972544Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ee8e5a779fc25cea","initial-advertise-peer-urls":["https://162.253.55.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T02:19:35.972597Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T02:19:35.972705Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-10-14T02:19:35.972735Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-10-14T02:19:36.849215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T02:19:36.849269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T02:19:36.849299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgPreVoteResp from ee8e5a779fc25cea at term 1"} {"level":"info","ts":"2026-10-14T02:19:36.849334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became candidate at term 2"} {"level":"info","ts":"2026-10-14T02:19:36.849349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgVoteResp from ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-10-14T02:19:36.849364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became leader at term 2"} {"level":"info","ts":"2026-10-14T02:19:36.849376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ee8e5a779fc25cea elected leader ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-10-14T02:19:36.85045Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:19:36.851351Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ee8e5a779fc25cea","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.138:2379]}","request-path":"/0/members/ee8e5a779fc25cea/attributes","cluster-id":"8f8e3b77da0cbcef","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T02:19:36.851359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T02:19:36.851527Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T02:19:36.851634Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T02:19:36.851722Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T02:19:36.852315Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:19:36.85257Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:19:36.852595Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T02:19:36.854554Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.138:2379"} {"level":"info","ts":"2026-10-14T02:19:36.854717Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-14T02:19:40.04089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.962304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:19:40.041012Z","caller":"traceutil/trace.go:171","msg":"trace[681820123] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"547.523924ms","start":"2026-10-14T02:19:39.49347Z","end":"2026-10-14T02:19:40.040994Z","steps":["trace[681820123] 'process raft request' (duration: 166.919756ms)","trace[681820123] 'compare' (duration: 379.837599ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:19:40.041108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:19:39.493456Z","time spent":"547.601598ms","remote":"127.0.0.1:38404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":975,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T02:19:40.236414Z","caller":"traceutil/trace.go:171","msg":"trace[384696437] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"190.299718ms","start":"2026-10-14T02:19:40.046089Z","end":"2026-10-14T02:19:40.236388Z","steps":["trace[384696437] 'process raft request' (duration: 166.624223ms)","trace[384696437] 'compare' (duration: 23.55707ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:20:22.109691Z","caller":"traceutil/trace.go:171","msg":"trace[641616503] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"234.64015ms","start":"2026-10-14T02:20:21.875Z","end":"2026-10-14T02:20:22.10964Z","steps":["trace[641616503] 'process raft request' (duration: 231.000076ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.109805Z","caller":"traceutil/trace.go:171","msg":"trace[1719848411] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"234.674671ms","start":"2026-10-14T02:20:21.875091Z","end":"2026-10-14T02:20:22.109766Z","steps":["trace[1719848411] 'process raft request' (duration: 234.353728ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.109943Z","caller":"traceutil/trace.go:171","msg":"trace[1653783561] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"234.738533ms","start":"2026-10-14T02:20:21.875152Z","end":"2026-10-14T02:20:22.10989Z","steps":["trace[1653783561] 'process raft request' (duration: 234.541535ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.110052Z","caller":"traceutil/trace.go:171","msg":"trace[1400318179] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"234.848089ms","start":"2026-10-14T02:20:21.875165Z","end":"2026-10-14T02:20:22.110013Z","steps":["trace[1400318179] 'process raft request' (duration: 234.63801ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.110201Z","caller":"traceutil/trace.go:171","msg":"trace[1460014380] transaction","detail":"{read_only:false; number_of_response:0; response_revision:521; }","duration":"232.488589ms","start":"2026-10-14T02:20:21.877674Z","end":"2026-10-14T02:20:22.110163Z","steps":["trace[1460014380] 'process raft request' (duration: 232.301611ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.110311Z","caller":"traceutil/trace.go:171","msg":"trace[2075286586] transaction","detail":"{read_only:false; number_of_response:0; response_revision:521; }","duration":"232.330863ms","start":"2026-10-14T02:20:21.877968Z","end":"2026-10-14T02:20:22.110298Z","steps":["trace[2075286586] 'process raft request' (duration: 232.141865ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.110442Z","caller":"traceutil/trace.go:171","msg":"trace[866540409] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"228.4416ms","start":"2026-10-14T02:20:21.881977Z","end":"2026-10-14T02:20:22.110419Z","steps":["trace[866540409] 'process raft request' (duration: 228.176559ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.110561Z","caller":"traceutil/trace.go:171","msg":"trace[104549244] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:543; }","duration":"190.083717ms","start":"2026-10-14T02:20:21.920444Z","end":"2026-10-14T02:20:22.110528Z","steps":["trace[104549244] 'read index received' (duration: 185.541546ms)","trace[104549244] 'applied index is now lower than readState.Index' (duration: 4.541261ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:20:22.110659Z","caller":"traceutil/trace.go:171","msg":"trace[448221434] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"227.567972ms","start":"2026-10-14T02:20:21.883078Z","end":"2026-10-14T02:20:22.110646Z","steps":["trace[448221434] 'process raft request' (duration: 227.169326ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.11075Z","caller":"traceutil/trace.go:171","msg":"trace[216937173] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"226.412384ms","start":"2026-10-14T02:20:21.884329Z","end":"2026-10-14T02:20:22.110742Z","steps":["trace[216937173] 'process raft request' (duration: 225.983336ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.110817Z","caller":"traceutil/trace.go:171","msg":"trace[851566240] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"217.357503ms","start":"2026-10-14T02:20:21.893452Z","end":"2026-10-14T02:20:22.110809Z","steps":["trace[851566240] 'process raft request' (duration: 217.005859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:20:22.110917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.41286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-10-14T02:20:22.110962Z","caller":"traceutil/trace.go:171","msg":"trace[1684585466] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:525; }","duration":"190.525515ms","start":"2026-10-14T02:20:21.92042Z","end":"2026-10-14T02:20:22.110946Z","steps":["trace[1684585466] 'agreement among raft nodes before linearized reading' (duration: 190.39897ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:22.119014Z","caller":"traceutil/trace.go:171","msg":"trace[1363449970] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"133.653205ms","start":"2026-10-14T02:20:21.985334Z","end":"2026-10-14T02:20:22.118987Z","steps":["trace[1363449970] 'process raft request' (duration: 133.328322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:20:22.119401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.465451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:20:22.120658Z","caller":"traceutil/trace.go:171","msg":"trace[3137729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"150.525914ms","start":"2026-10-14T02:20:21.9689Z","end":"2026-10-14T02:20:22.119426Z","steps":["trace[3137729] 'agreement among raft nodes before linearized reading' (duration: 150.467882ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:28.552374Z","caller":"traceutil/trace.go:171","msg":"trace[897874618] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"321.223079ms","start":"2026-10-14T02:20:28.231124Z","end":"2026-10-14T02:20:28.552347Z","steps":["trace[897874618] 'process raft request' (duration: 319.844421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:20:28.552722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:20:28.231087Z","time spent":"321.359765ms","remote":"127.0.0.1:38192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T02:20:28.757649Z","caller":"traceutil/trace.go:171","msg":"trace[1809675100] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"121.962884ms","start":"2026-10-14T02:20:28.635635Z","end":"2026-10-14T02:20:28.757598Z","steps":["trace[1809675100] 'process raft request' (duration: 99.038231ms)","trace[1809675100] 'compare' (duration: 22.63158ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:20:29.378702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.278541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:20:29.379694Z","caller":"traceutil/trace.go:171","msg":"trace[2121987176] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"544.362288ms","start":"2026-10-14T02:20:28.835291Z","end":"2026-10-14T02:20:29.379653Z","steps":["trace[2121987176] 'process raft request' (duration: 41.007551ms)","trace[2121987176] 'compare' (duration: 502.106633ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:20:29.37987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:20:28.835275Z","time spent":"544.504093ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T02:20:29.380233Z","caller":"traceutil/trace.go:171","msg":"trace[1386489121] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"245.662976ms","start":"2026-10-14T02:20:29.134557Z","end":"2026-10-14T02:20:29.38022Z","steps":["trace[1386489121] 'process raft request' (duration: 244.525588ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:29.409513Z","caller":"traceutil/trace.go:171","msg":"trace[474143599] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"258.919429ms","start":"2026-10-14T02:20:29.150564Z","end":"2026-10-14T02:20:29.409483Z","steps":["trace[474143599] 'process raft request' (duration: 258.271092ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:20:43.960433Z","caller":"traceutil/trace.go:171","msg":"trace[25249997] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"105.051942ms","start":"2026-10-14T02:20:43.855327Z","end":"2026-10-14T02:20:43.960379Z","steps":["trace[25249997] 'process raft request' (duration: 104.571652ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:08.486628Z","caller":"traceutil/trace.go:171","msg":"trace[1276288159] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"184.720906ms","start":"2026-10-14T02:22:08.30187Z","end":"2026-10-14T02:22:08.486591Z","steps":["trace[1276288159] 'process raft request' (duration: 184.500642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:22:08.665002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.550677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:22:08.665107Z","caller":"traceutil/trace.go:171","msg":"trace[1816345334] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"135.009369ms","start":"2026-10-14T02:22:08.53008Z","end":"2026-10-14T02:22:08.66509Z","steps":["trace[1816345334] 'read index received' (duration: 42.92µs)","trace[1816345334] 'applied index is now lower than readState.Index' (duration: 134.965099ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:22:08.665429Z","caller":"traceutil/trace.go:171","msg":"trace[1599311792] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"174.532822ms","start":"2026-10-14T02:22:08.490855Z","end":"2026-10-14T02:22:08.665388Z","steps":["trace[1599311792] 'process raft request' (duration: 13.491598ms)","trace[1599311792] 'compare' (duration: 160.270264ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:22:08.665468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.319743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3189038c-b819-40b1-9461-bb40962f2cdf\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-10-14T02:22:08.66548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.692859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-10-14T02:22:08.665531Z","caller":"traceutil/trace.go:171","msg":"trace[1480069073] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1857; }","duration":"130.75635ms","start":"2026-10-14T02:22:08.534759Z","end":"2026-10-14T02:22:08.665515Z","steps":["trace[1480069073] 'agreement among raft nodes before linearized reading' (duration: 130.604337ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:08.665528Z","caller":"traceutil/trace.go:171","msg":"trace[2063729843] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3189038c-b819-40b1-9461-bb40962f2cdf; range_end:; response_count:1; response_revision:1857; }","duration":"135.462506ms","start":"2026-10-14T02:22:08.530049Z","end":"2026-10-14T02:22:08.665512Z","steps":["trace[2063729843] 'agreement among raft nodes before linearized reading' (duration: 135.157621ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:14.524999Z","caller":"traceutil/trace.go:171","msg":"trace[999407733] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"102.334279ms","start":"2026-10-14T02:22:14.422637Z","end":"2026-10-14T02:22:14.524971Z","steps":["trace[999407733] 'process raft request' (duration: 72.932652ms)","trace[999407733] 'compare' (duration: 29.281025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:22:41.049397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.888171ms","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-14T02:22:41.049488Z","caller":"traceutil/trace.go:171","msg":"trace[1710101049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2126; }","duration":"239.025754ms","start":"2026-10-14T02:22:40.810439Z","end":"2026-10-14T02:22:41.049465Z","steps":["trace[1710101049] 'range keys from in-memory index tree' (duration: 238.715437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:22:41.04947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.626084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-10-14T02:22:41.049543Z","caller":"traceutil/trace.go:171","msg":"trace[1783831751] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2126; }","duration":"172.719436ms","start":"2026-10-14T02:22:40.876806Z","end":"2026-10-14T02:22:41.049525Z","steps":["trace[1783831751] 'range keys from in-memory index tree' (duration: 172.516413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:22:41.05008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.221144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:22:41.050188Z","caller":"traceutil/trace.go:171","msg":"trace[508858997] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"127.574061ms","start":"2026-10-14T02:22:40.922598Z","end":"2026-10-14T02:22:41.050172Z","steps":["trace[508858997] 'read index received' (duration: 15.131923ms)","trace[508858997] 'applied index is now lower than readState.Index' (duration: 112.440538ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:22:41.050189Z","caller":"traceutil/trace.go:171","msg":"trace[1237434635] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"167.707095ms","start":"2026-10-14T02:22:40.882464Z","end":"2026-10-14T02:22:41.050171Z","steps":["trace[1237434635] 'process raft request' (duration: 55.290667ms)","trace[1237434635] 'compare' (duration: 112.107772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:22:41.050398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.791406ms","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-14T02:22:41.050437Z","caller":"traceutil/trace.go:171","msg":"trace[1551160785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2127; }","duration":"127.850137ms","start":"2026-10-14T02:22:40.922573Z","end":"2026-10-14T02:22:41.050423Z","steps":["trace[1551160785] 'agreement among raft nodes before linearized reading' (duration: 127.688513ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:22:41.297438Z","caller":"traceutil/trace.go:171","msg":"trace[650267462] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"241.480475ms","start":"2026-10-14T02:22:41.05593Z","end":"2026-10-14T02:22:41.29741Z","steps":["trace[650267462] 'process raft request' (duration: 241.316261ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:23:24.384359Z","caller":"traceutil/trace.go:171","msg":"trace[1483142080] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"100.881381ms","start":"2026-10-14T02:23:24.283447Z","end":"2026-10-14T02:23:24.384328Z","steps":["trace[1483142080] 'process raft request' (duration: 99.258169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:23:33.705553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.332317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-10-14T02:23:33.705623Z","caller":"traceutil/trace.go:171","msg":"trace[555695483] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2588; }","duration":"233.43861ms","start":"2026-10-14T02:23:33.472168Z","end":"2026-10-14T02:23:33.705606Z","steps":["trace[555695483] 'range keys from in-memory index tree' (duration: 233.176033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:23:33.705616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.4381ms","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-14T02:23:33.705726Z","caller":"traceutil/trace.go:171","msg":"trace[9869397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2588; }","duration":"183.585174ms","start":"2026-10-14T02:23:33.522119Z","end":"2026-10-14T02:23:33.705704Z","steps":["trace[9869397] 'range keys from in-memory index tree' (duration: 183.245915ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:24:00.462958Z","caller":"traceutil/trace.go:171","msg":"trace[294846872] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"138.406192ms","start":"2026-10-14T02:24:00.324523Z","end":"2026-10-14T02:24:00.462929Z","steps":["trace[294846872] 'process raft request' (duration: 138.233197ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:24:00.463553Z","caller":"traceutil/trace.go:171","msg":"trace[1301872455] linearizableReadLoop","detail":"{readStateIndex:2887; appliedIndex:2887; }","duration":"101.374157ms","start":"2026-10-14T02:24:00.36211Z","end":"2026-10-14T02:24:00.463484Z","steps":["trace[1301872455] 'read index received' (duration: 101.362007ms)","trace[1301872455] 'applied index is now lower than readState.Index' (duration: 10.23µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:24:00.463921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.772759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-10-14T02:24:00.46415Z","caller":"traceutil/trace.go:171","msg":"trace[1952132443] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2775; }","duration":"102.004307ms","start":"2026-10-14T02:24:00.362059Z","end":"2026-10-14T02:24:00.464064Z","steps":["trace[1952132443] 'agreement among raft nodes before linearized reading' (duration: 101.537613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:24:00.783678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.409034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:24:00.78375Z","caller":"traceutil/trace.go:171","msg":"trace[1391292101] linearizableReadLoop","detail":"{readStateIndex:2889; appliedIndex:2888; }","duration":"234.921955ms","start":"2026-10-14T02:24:00.548816Z","end":"2026-10-14T02:24:00.783738Z","steps":["trace[1391292101] 'read index received' (duration: 105.308335ms)","trace[1391292101] 'applied index is now lower than readState.Index' (duration: 129.61261ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:24:00.783918Z","caller":"traceutil/trace.go:171","msg":"trace[353635774] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"251.806809ms","start":"2026-10-14T02:24:00.532104Z","end":"2026-10-14T02:24:00.783911Z","steps":["trace[353635774] 'process raft request' (duration: 122.094787ms)","trace[353635774] 'compare' (duration: 129.194187ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:24:00.784397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.659015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T02:24:00.784419Z","caller":"traceutil/trace.go:171","msg":"trace[1799825732] range","detail":"{range_begin:/registry/masterleases/162.253.55.138; range_end:; response_count:1; response_revision:2777; }","duration":"102.691786ms","start":"2026-10-14T02:24:00.681722Z","end":"2026-10-14T02:24:00.784413Z","steps":["trace[1799825732] 'agreement among raft nodes before linearized reading' (duration: 102.576912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:24:00.784516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.658067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-14T02:24:00.784532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.699165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-10-14T02:24:00.784605Z","caller":"traceutil/trace.go:171","msg":"trace[1235464269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2777; }","duration":"235.792131ms","start":"2026-10-14T02:24:00.548791Z","end":"2026-10-14T02:24:00.784583Z","steps":["trace[1235464269] 'agreement among raft nodes before linearized reading' (duration: 235.516833ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:24:00.784611Z","caller":"traceutil/trace.go:171","msg":"trace[722931915] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2777; }","duration":"114.812608ms","start":"2026-10-14T02:24:00.669781Z","end":"2026-10-14T02:24:00.784594Z","steps":["trace[722931915] 'agreement among raft nodes before linearized reading' (duration: 114.55095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:24:00.784818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.829427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:24:00.784889Z","caller":"traceutil/trace.go:171","msg":"trace[1692930454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2777; }","duration":"128.922469ms","start":"2026-10-14T02:24:00.655938Z","end":"2026-10-14T02:24:00.784861Z","steps":["trace[1692930454] 'agreement among raft nodes before linearized reading' (duration: 128.190077ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:24:01.019767Z","caller":"traceutil/trace.go:171","msg":"trace[2014944570] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"231.924427ms","start":"2026-10-14T02:24:00.787807Z","end":"2026-10-14T02:24:01.019732Z","steps":["trace[2014944570] 'process raft request' (duration: 156.670398ms)","trace[2014944570] 'compare' (duration: 75.072823ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:24:01.019851Z","caller":"traceutil/trace.go:171","msg":"trace[634953331] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2890; }","duration":"212.005532ms","start":"2026-10-14T02:24:00.807826Z","end":"2026-10-14T02:24:01.019831Z","steps":["trace[634953331] 'read index received' (duration: 136.805595ms)","trace[634953331] 'applied index is now lower than readState.Index' (duration: 75.198677ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:24:01.019927Z","caller":"traceutil/trace.go:171","msg":"trace[2015221768] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"208.920779ms","start":"2026-10-14T02:24:00.810981Z","end":"2026-10-14T02:24:01.019902Z","steps":["trace[2015221768] 'process raft request' (duration: 208.826516ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:24:01.020003Z","caller":"traceutil/trace.go:171","msg":"trace[220058366] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"211.033412ms","start":"2026-10-14T02:24:00.808948Z","end":"2026-10-14T02:24:01.019982Z","steps":["trace[220058366] 'process raft request' (duration: 210.760024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:24:01.020068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.924597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:24:01.020125Z","caller":"traceutil/trace.go:171","msg":"trace[323153647] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2780; }","duration":"230.01029ms","start":"2026-10-14T02:24:00.790092Z","end":"2026-10-14T02:24:01.020102Z","steps":["trace[323153647] 'agreement among raft nodes before linearized reading' (duration: 229.882486ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:24:01.170423Z","caller":"traceutil/trace.go:171","msg":"trace[1858748552] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"142.397864ms","start":"2026-10-14T02:24:01.028001Z","end":"2026-10-14T02:24:01.170399Z","steps":["trace[1858748552] 'process raft request' (duration: 142.2589ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:24:01.17082Z","caller":"traceutil/trace.go:171","msg":"trace[1979965306] linearizableReadLoop","detail":"{readStateIndex:2894; appliedIndex:2893; }","duration":"110.89524ms","start":"2026-10-14T02:24:01.059895Z","end":"2026-10-14T02:24:01.170791Z","steps":["trace[1979965306] 'read index received' (duration: 110.303783ms)","trace[1979965306] 'applied index is now lower than readState.Index' (duration: 589.347µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:24:01.171131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.21544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-10-14T02:24:01.171215Z","caller":"traceutil/trace.go:171","msg":"trace[2054467417] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2781; }","duration":"111.339854ms","start":"2026-10-14T02:24:01.059863Z","end":"2026-10-14T02:24:01.171203Z","steps":["trace[2054467417] 'agreement among raft nodes before linearized reading' (duration: 111.065695ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:26.707001Z","caller":"traceutil/trace.go:171","msg":"trace[1214619874] linearizableReadLoop","detail":"{readStateIndex:4576; appliedIndex:4575; }","duration":"112.014376ms","start":"2026-10-14T02:26:26.594967Z","end":"2026-10-14T02:26:26.706981Z","steps":["trace[1214619874] 'read index received' (duration: 47.566266ms)","trace[1214619874] 'applied index is now lower than readState.Index' (duration: 64.44721ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:26:26.707217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.239064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T02:26:26.707248Z","caller":"traceutil/trace.go:171","msg":"trace[1008666748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4383; }","duration":"112.314946ms","start":"2026-10-14T02:26:26.594921Z","end":"2026-10-14T02:26:26.707236Z","steps":["trace[1008666748] 'agreement among raft nodes before linearized reading' (duration: 112.16091ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:26.707266Z","caller":"traceutil/trace.go:171","msg":"trace[1964554633] transaction","detail":"{read_only:false; response_revision:4383; number_of_response:1; }","duration":"132.93982ms","start":"2026-10-14T02:26:26.574283Z","end":"2026-10-14T02:26:26.707223Z","steps":["trace[1964554633] 'process raft request' (duration: 68.285753ms)","trace[1964554633] 'compare' (duration: 64.20029ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:26:33.734094Z","caller":"traceutil/trace.go:171","msg":"trace[1042042081] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"154.986997ms","start":"2026-10-14T02:26:33.579078Z","end":"2026-10-14T02:26:33.734065Z","steps":["trace[1042042081] 'process raft request' (duration: 122.024774ms)","trace[1042042081] 'compare' (duration: 32.823848ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:26:33.956035Z","caller":"traceutil/trace.go:171","msg":"trace[1459461531] transaction","detail":"{read_only:false; response_revision:4532; number_of_response:1; }","duration":"100.355006ms","start":"2026-10-14T02:26:33.855654Z","end":"2026-10-14T02:26:33.956009Z","steps":["trace[1459461531] 'process raft request' (duration: 100.201651ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:34.033309Z","caller":"traceutil/trace.go:171","msg":"trace[2020093887] transaction","detail":"{read_only:false; response_revision:4533; number_of_response:1; }","duration":"164.996367ms","start":"2026-10-14T02:26:33.86827Z","end":"2026-10-14T02:26:34.033267Z","steps":["trace[2020093887] 'process raft request' (duration: 127.048935ms)","trace[2020093887] 'compare' (duration: 37.657921ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:26:43.042728Z","caller":"traceutil/trace.go:171","msg":"trace[1470196044] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"100.815119ms","start":"2026-10-14T02:26:42.941887Z","end":"2026-10-14T02:26:43.042703Z","steps":["trace[1470196044] 'process raft request' (duration: 100.603371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:47.168518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.413237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:26:47.168614Z","caller":"traceutil/trace.go:171","msg":"trace[16774444] linearizableReadLoop","detail":"{readStateIndex:4957; appliedIndex:4956; }","duration":"482.832722ms","start":"2026-10-14T02:26:46.685765Z","end":"2026-10-14T02:26:47.168598Z","steps":["trace[16774444] 'read index received' (duration: 171.166015ms)","trace[16774444] 'applied index is now lower than readState.Index' (duration: 311.665327ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T02:26:47.168682Z","caller":"traceutil/trace.go:171","msg":"trace[1036960570] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"537.653807ms","start":"2026-10-14T02:26:46.631017Z","end":"2026-10-14T02:26:47.168671Z","steps":["trace[1036960570] 'process raft request' (duration: 226.008262ms)","trace[1036960570] 'compare' (duration: 311.154448ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:26:47.16874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:26:46.631Z","time spent":"537.71115ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T02:26:47.169037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.276088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-14T02:26:47.169072Z","caller":"traceutil/trace.go:171","msg":"trace[1740436829] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4749; }","duration":"483.349001ms","start":"2026-10-14T02:26:46.685712Z","end":"2026-10-14T02:26:47.169061Z","steps":["trace[1740436829] 'agreement among raft nodes before linearized reading' (duration: 483.223487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:47.169097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:26:46.685691Z","time spent":"483.399954ms","remote":"127.0.0.1:38202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T02:26:47.175195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.864197ms","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-14T02:26:47.175243Z","caller":"traceutil/trace.go:171","msg":"trace[775963303] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4750; }","duration":"346.94793ms","start":"2026-10-14T02:26:46.828283Z","end":"2026-10-14T02:26:47.175231Z","steps":["trace[775963303] 'agreement among raft nodes before linearized reading' (duration: 346.816095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:47.175272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:26:46.828264Z","time spent":"347.000302ms","remote":"127.0.0.1:38240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-14T02:26:47.175235Z","caller":"traceutil/trace.go:171","msg":"trace[634302729] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"147.761177ms","start":"2026-10-14T02:26:47.027435Z","end":"2026-10-14T02:26:47.175197Z","steps":["trace[634302729] 'process raft request' (duration: 147.536449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:47.175308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.293599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"warn","ts":"2026-10-14T02:26:47.175307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.72653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T02:26:47.175385Z","caller":"traceutil/trace.go:171","msg":"trace[1126933056] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4750; }","duration":"442.398813ms","start":"2026-10-14T02:26:46.732973Z","end":"2026-10-14T02:26:47.175372Z","steps":["trace[1126933056] 'agreement among raft nodes before linearized reading' (duration: 442.234557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:47.175416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:26:46.732956Z","time spent":"442.452846ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10540,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-14T02:26:47.175404Z","caller":"traceutil/trace.go:171","msg":"trace[568529185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4750; }","duration":"331.862086ms","start":"2026-10-14T02:26:46.843524Z","end":"2026-10-14T02:26:47.175386Z","steps":["trace[568529185] 'agreement among raft nodes before linearized reading' (duration: 331.572755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:47.175463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:26:46.843513Z","time spent":"331.940008ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T02:26:47.175638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.292043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T02:26:47.175628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.643294ms","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-14T02:26:47.175685Z","caller":"traceutil/trace.go:171","msg":"trace[1777002690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4750; }","duration":"213.387737ms","start":"2026-10-14T02:26:46.962283Z","end":"2026-10-14T02:26:47.175671Z","steps":["trace[1777002690] 'agreement among raft nodes before linearized reading' (duration: 213.296743ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:26:47.175699Z","caller":"traceutil/trace.go:171","msg":"trace[1965662319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4750; }","duration":"379.758528ms","start":"2026-10-14T02:26:46.795924Z","end":"2026-10-14T02:26:47.175683Z","steps":["trace[1965662319] 'agreement among raft nodes before linearized reading' (duration: 379.620353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:26:47.175732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:26:46.795903Z","time spent":"379.821301ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T02:29:36.879668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3023} {"level":"info","ts":"2026-10-14T02:29:36.997787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3023,"took":"116.640172ms","hash":3769803223,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":31002624,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T02:29:36.997858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3769803223,"revision":3023,"compact-revision":-1} {"level":"warn","ts":"2026-10-14T02:30:42.274011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.68061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-14T02:30:42.274109Z","caller":"traceutil/trace.go:171","msg":"trace[1946109808] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6814; }","duration":"115.830792ms","start":"2026-10-14T02:30:42.158258Z","end":"2026-10-14T02:30:42.274089Z","steps":["trace[1946109808] 'range keys from in-memory index tree' (duration: 115.495939ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:30:42.414182Z","caller":"traceutil/trace.go:171","msg":"trace[579836954] transaction","detail":"{read_only:false; response_revision:6815; number_of_response:1; }","duration":"131.661994ms","start":"2026-10-14T02:30:42.282468Z","end":"2026-10-14T02:30:42.41413Z","steps":["trace[579836954] 'process raft request' (duration: 131.12151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:30:42.890313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.936893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T02:30:42.890864Z","caller":"traceutil/trace.go:171","msg":"trace[1559221746] transaction","detail":"{read_only:false; response_revision:6816; number_of_response:1; }","duration":"468.352458ms","start":"2026-10-14T02:30:42.422495Z","end":"2026-10-14T02:30:42.890847Z","steps":["trace[1559221746] 'process raft request' (duration: 157.789861ms)","trace[1559221746] 'compare' (duration: 308.942956ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:30:42.89106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:30:42.422479Z","time spent":"468.43031ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T02:30:42.890436Z","caller":"traceutil/trace.go:171","msg":"trace[1738751497] linearizableReadLoop","detail":"{readStateIndex:7130; appliedIndex:7129; }","duration":"455.222195ms","start":"2026-10-14T02:30:42.435195Z","end":"2026-10-14T02:30:42.890417Z","steps":["trace[1738751497] 'read index received' (duration: 145.03211ms)","trace[1738751497] 'applied index is now lower than readState.Index' (duration: 310.188655ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T02:30:42.891984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.775357ms","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-14T02:30:42.892048Z","caller":"traceutil/trace.go:171","msg":"trace[1283367877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6816; }","duration":"456.867597ms","start":"2026-10-14T02:30:42.435165Z","end":"2026-10-14T02:30:42.892032Z","steps":["trace[1283367877] 'agreement among raft nodes before linearized reading' (duration: 456.645126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:30:42.89227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:30:42.435151Z","time spent":"457.102239ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T02:30:42.893001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.121114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T02:30:42.893057Z","caller":"traceutil/trace.go:171","msg":"trace[400294827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6816; }","duration":"413.204434ms","start":"2026-10-14T02:30:42.479839Z","end":"2026-10-14T02:30:42.893043Z","steps":["trace[400294827] 'agreement among raft nodes before linearized reading' (duration: 413.072013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:30:42.894267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:30:42.479827Z","time spent":"414.427283ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T02:30:42.893786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.817008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-14T02:30:42.894543Z","caller":"traceutil/trace.go:171","msg":"trace[479495199] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6816; }","duration":"354.596963ms","start":"2026-10-14T02:30:42.539929Z","end":"2026-10-14T02:30:42.894526Z","steps":["trace[479495199] 'agreement among raft nodes before linearized reading' (duration: 353.740517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:30:42.893987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.573244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T02:30:42.894848Z","caller":"traceutil/trace.go:171","msg":"trace[1969348986] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:6816; }","duration":"291.579202ms","start":"2026-10-14T02:30:42.603253Z","end":"2026-10-14T02:30:42.894832Z","steps":["trace[1969348986] 'agreement among raft nodes before linearized reading' (duration: 290.581334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:30:42.894121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.449331ms","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-14T02:30:42.895216Z","caller":"traceutil/trace.go:171","msg":"trace[501793563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6816; }","duration":"366.524309ms","start":"2026-10-14T02:30:42.528634Z","end":"2026-10-14T02:30:42.895158Z","steps":["trace[501793563] 'agreement among raft nodes before linearized reading' (duration: 365.396111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:30:42.89545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:30:42.528624Z","time spent":"366.757671ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T02:30:42.89419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.724961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T02:30:42.895626Z","caller":"traceutil/trace.go:171","msg":"trace[1387840867] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:6816; }","duration":"389.172713ms","start":"2026-10-14T02:30:42.506431Z","end":"2026-10-14T02:30:42.895604Z","steps":["trace[1387840867] 'agreement among raft nodes before linearized reading' (duration: 387.728392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T02:30:42.895764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:30:42.506419Z","time spent":"389.324083ms","remote":"127.0.0.1:46192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-10-14T02:30:42.895328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T02:30:42.539917Z","time spent":"354.760574ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-10-14T02:30:43.029501Z","caller":"traceutil/trace.go:171","msg":"trace[1163579249] transaction","detail":"{read_only:false; response_revision:6819; number_of_response:1; }","duration":"126.796652ms","start":"2026-10-14T02:30:42.90264Z","end":"2026-10-14T02:30:43.029436Z","steps":["trace[1163579249] 'process raft request' (duration: 125.472092ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:30:43.029962Z","caller":"traceutil/trace.go:171","msg":"trace[729627013] transaction","detail":"{read_only:false; response_revision:6818; number_of_response:1; }","duration":"128.012442ms","start":"2026-10-14T02:30:42.901937Z","end":"2026-10-14T02:30:43.029949Z","steps":["trace[729627013] 'process raft request' (duration: 124.155944ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T02:30:43.030159Z","caller":"traceutil/trace.go:171","msg":"trace[1204618412] transaction","detail":"{read_only:false; response_revision:6820; number_of_response:1; }","duration":"125.239332ms","start":"2026-10-14T02:30:42.90466Z","end":"2026-10-14T02:30:43.029899Z","steps":["trace[1204618412] 'process raft request' (duration: 123.518589ms)"],"step_count":1}