{"level":"warn","ts":"2026-09-19T06:04:19.406407Z","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-19T06:04:19.406511Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-19T06:04:19.40658Z","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-19T06:04:19.406587Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-09-19T06:04:19.406631Z","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-19T06:04:19.407281Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-09-19T06:04:19.407379Z","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.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-19T06:04:19.414388Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.728229ms"} {"level":"info","ts":"2026-09-19T06:04:19.423218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-09-19T06:04:19.423288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T06:04:19.423308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-09-19T06:04:19.423638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T06:04:19.423645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-09-19T06:04:19.423682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-09-19T06:04:19.431324Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T06:04:19.433025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T06:04:19.434433Z","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-19T06:04:19.438043Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T06:04:19.438381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T06:04:19.438428Z","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-19T06:04:19.438459Z","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-19T06:04:19.438468Z","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-19T06:04:19.444133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-09-19T06:04:19.444214Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-09-19T06:04:19.444528Z","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-19T06:04:19.444801Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T06:04:19.444829Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T06:04:19.445162Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-09-19T06:04:19.445181Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-09-19T06:04:20.424879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T06:04:20.424941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T06:04:20.424989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-09-19T06:04:20.425007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-09-19T06:04:20.425018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-09-19T06:04:20.425032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-09-19T06:04:20.425049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-09-19T06:04:20.434998Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:04:20.442725Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:04:20.442751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:04:20.44332Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T06:04:20.443349Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T06:04:20.443501Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T06:04:20.447332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T06:04:20.447348Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-09-19T06:04:20.457305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:04:20.457452Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:04:20.45749Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-19T06:04:26.987467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.752894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:04:26.987619Z","caller":"traceutil/trace.go:171","msg":"trace[18522116] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:331; }","duration":"408.820392ms","start":"2026-09-19T06:04:26.578785Z","end":"2026-09-19T06:04:26.987605Z","steps":["trace[18522116] 'read index received' (duration: 63.612071ms)","trace[18522116] 'applied index is now lower than readState.Index' (duration: 345.207821ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:26.987706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.928842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-19T06:04:26.987724Z","caller":"traceutil/trace.go:171","msg":"trace[570324130] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:319; }","duration":"408.953612ms","start":"2026-09-19T06:04:26.578766Z","end":"2026-09-19T06:04:26.98772Z","steps":["trace[570324130] 'agreement among raft nodes before linearized reading' (duration: 408.887832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:26.987744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:26.578742Z","time spent":"408.997601ms","remote":"127.0.0.1:55022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-19T06:04:26.987962Z","caller":"traceutil/trace.go:171","msg":"trace[250047521] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"419.500934ms","start":"2026-09-19T06:04:26.568456Z","end":"2026-09-19T06:04:26.987957Z","steps":["trace[250047521] 'process raft request' (duration: 419.111546ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:26.988047Z","caller":"traceutil/trace.go:171","msg":"trace[1301590625] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"421.447953ms","start":"2026-09-19T06:04:26.566543Z","end":"2026-09-19T06:04:26.987991Z","steps":["trace[1301590625] 'process raft request' (duration: 75.871674ms)","trace[1301590625] 'compare' (duration: 344.646755ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:26.98818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:26.56845Z","time spent":"419.632944ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:04:26.988237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:26.566532Z","time spent":"421.623862ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:04:27.404711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.879111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:04:27.405452Z","caller":"traceutil/trace.go:171","msg":"trace[1148027675] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"413.642641ms","start":"2026-09-19T06:04:26.991767Z","end":"2026-09-19T06:04:27.40541Z","steps":["trace[1148027675] 'process raft request' (duration: 185.955474ms)","trace[1148027675] 'compare' (duration: 226.651983ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:27.405589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:26.991742Z","time spent":"413.785881ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T06:04:58.533687Z","caller":"traceutil/trace.go:171","msg":"trace[1927870488] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"130.823246ms","start":"2026-09-19T06:04:58.402818Z","end":"2026-09-19T06:04:58.533641Z","steps":["trace[1927870488] 'process raft request' (duration: 130.53085ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:00.922658Z","caller":"traceutil/trace.go:171","msg":"trace[837897263] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"133.597872ms","start":"2026-09-19T06:05:00.789009Z","end":"2026-09-19T06:05:00.922606Z","steps":["trace[837897263] 'process raft request' (duration: 74.851578ms)","trace[837897263] 'compare' (duration: 58.403517ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:05:00.922807Z","caller":"traceutil/trace.go:171","msg":"trace[1470730028] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"133.412298ms","start":"2026-09-19T06:05:00.789341Z","end":"2026-09-19T06:05:00.922753Z","steps":["trace[1470730028] 'process raft request' (duration: 133.184483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:00.923022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.559869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-09-19T06:05:00.922932Z","caller":"traceutil/trace.go:171","msg":"trace[2052512101] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:585; }","duration":"101.422566ms","start":"2026-09-19T06:05:00.821436Z","end":"2026-09-19T06:05:00.922859Z","steps":["trace[2052512101] 'read index received' (duration: 42.341016ms)","trace[2052512101] 'applied index is now lower than readState.Index' (duration: 59.07307ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:05:00.923108Z","caller":"traceutil/trace.go:171","msg":"trace[1823565396] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:562; }","duration":"101.65133ms","start":"2026-09-19T06:05:00.821428Z","end":"2026-09-19T06:05:00.92308Z","steps":["trace[1823565396] 'agreement among raft nodes before linearized reading' (duration: 101.515478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:00.925155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.605997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-09-19T06:05:00.925267Z","caller":"traceutil/trace.go:171","msg":"trace[510560188] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:565; }","duration":"103.742399ms","start":"2026-09-19T06:05:00.821501Z","end":"2026-09-19T06:05:00.925243Z","steps":["trace[510560188] 'agreement among raft nodes before linearized reading' (duration: 103.461484ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:01.129331Z","caller":"traceutil/trace.go:171","msg":"trace[1378701224] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"140.717092ms","start":"2026-09-19T06:05:00.988582Z","end":"2026-09-19T06:05:01.129299Z","steps":["trace[1378701224] 'process raft request' (duration: 59.047674ms)","trace[1378701224] 'compare' (duration: 81.350142ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:05:01.156723Z","caller":"traceutil/trace.go:171","msg":"trace[633775270] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"152.742691ms","start":"2026-09-19T06:05:01.003899Z","end":"2026-09-19T06:05:01.156642Z","steps":["trace[633775270] 'process raft request' (duration: 151.724191ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:01.157238Z","caller":"traceutil/trace.go:171","msg":"trace[1265197630] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"134.288815ms","start":"2026-09-19T06:05:01.022898Z","end":"2026-09-19T06:05:01.157187Z","steps":["trace[1265197630] 'process raft request' (duration: 132.894748ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:01.159423Z","caller":"traceutil/trace.go:171","msg":"trace[394918288] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"104.477849ms","start":"2026-09-19T06:05:01.054894Z","end":"2026-09-19T06:05:01.159372Z","steps":["trace[394918288] 'read index received' (duration: 101.130274ms)","trace[394918288] 'applied index is now lower than readState.Index' (duration: 3.346345ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:05:01.159833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.915859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:05:01.15987Z","caller":"traceutil/trace.go:171","msg":"trace[1377017636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"104.998379ms","start":"2026-09-19T06:05:01.054854Z","end":"2026-09-19T06:05:01.159852Z","steps":["trace[1377017636] 'agreement among raft nodes before linearized reading' (duration: 104.606952ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:01.160474Z","caller":"traceutil/trace.go:171","msg":"trace[1529636004] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"128.024905ms","start":"2026-09-19T06:05:01.032402Z","end":"2026-09-19T06:05:01.160427Z","steps":["trace[1529636004] 'process raft request' (duration: 123.580788ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:01.342219Z","caller":"traceutil/trace.go:171","msg":"trace[706773452] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"157.80789ms","start":"2026-09-19T06:05:01.184381Z","end":"2026-09-19T06:05:01.342188Z","steps":["trace[706773452] 'process raft request' (duration: 157.677678ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:01.342215Z","caller":"traceutil/trace.go:171","msg":"trace[2118260251] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"171.372462ms","start":"2026-09-19T06:05:01.170771Z","end":"2026-09-19T06:05:01.342144Z","steps":["trace[2118260251] 'process raft request' (duration: 115.548223ms)","trace[2118260251] 'compare' (duration: 55.3701ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:05:01.350454Z","caller":"traceutil/trace.go:171","msg":"trace[15476836] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"145.44199ms","start":"2026-09-19T06:05:01.20499Z","end":"2026-09-19T06:05:01.350432Z","steps":["trace[15476836] 'process raft request' (duration: 144.831678ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:01.350644Z","caller":"traceutil/trace.go:171","msg":"trace[23510348] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"115.493032ms","start":"2026-09-19T06:05:01.235144Z","end":"2026-09-19T06:05:01.350637Z","steps":["trace[23510348] 'process raft request' (duration: 114.8527ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:07.775795Z","caller":"traceutil/trace.go:171","msg":"trace[431599659] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"289.944541ms","start":"2026-09-19T06:05:07.485824Z","end":"2026-09-19T06:05:07.775768Z","steps":["trace[431599659] 'process raft request' (duration: 275.627601ms)","trace[431599659] 'compare' (duration: 14.030824ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:05:07.835346Z","caller":"traceutil/trace.go:171","msg":"trace[1966948301] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"336.998152ms","start":"2026-09-19T06:05:07.498311Z","end":"2026-09-19T06:05:07.83531Z","steps":["trace[1966948301] 'process raft request' (duration: 331.739294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:07.83549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:07.498249Z","time spent":"337.165156ms","remote":"127.0.0.1:54990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:05:08.252349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.114175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:05:08.252639Z","caller":"traceutil/trace.go:171","msg":"trace[369118555] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"194.419007ms","start":"2026-09-19T06:05:08.058201Z","end":"2026-09-19T06:05:08.25262Z","steps":["trace[369118555] 'read index received' (duration: 161.331592ms)","trace[369118555] 'applied index is now lower than readState.Index' (duration: 33.084585ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:05:08.252745Z","caller":"traceutil/trace.go:171","msg":"trace[903634321] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"355.567766ms","start":"2026-09-19T06:05:07.897132Z","end":"2026-09-19T06:05:08.2527Z","steps":["trace[903634321] 'process raft request' (duration: 355.424233ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:08.252789Z","caller":"traceutil/trace.go:171","msg":"trace[1833340153] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"411.917025ms","start":"2026-09-19T06:05:07.840833Z","end":"2026-09-19T06:05:08.25275Z","steps":["trace[1833340153] 'process raft request' (duration: 180.286248ms)","trace[1833340153] 'compare' (duration: 230.960593ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:05:08.25283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.612363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:05:08.252897Z","caller":"traceutil/trace.go:171","msg":"trace[266404026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"194.759296ms","start":"2026-09-19T06:05:08.058123Z","end":"2026-09-19T06:05:08.252882Z","steps":["trace[266404026] 'agreement among raft nodes before linearized reading' (duration: 194.608332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:08.252914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:07.897109Z","time spent":"355.72938ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:05:08.252938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:07.840788Z","time spent":"412.078489ms","remote":"127.0.0.1:55154","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-09-19T06:05:09.217322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.055299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T06:05:09.217448Z","caller":"traceutil/trace.go:171","msg":"trace[1323619728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"791.229473ms","start":"2026-09-19T06:05:08.426184Z","end":"2026-09-19T06:05:09.217414Z","steps":["trace[1323619728] 'range keys from in-memory index tree' (duration: 790.839454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:09.217428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.210719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-19T06:05:09.217514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:08.426166Z","time spent":"791.334156ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T06:05:09.217522Z","caller":"traceutil/trace.go:171","msg":"trace[1895770919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"514.364013ms","start":"2026-09-19T06:05:08.703133Z","end":"2026-09-19T06:05:09.217497Z","steps":["trace[1895770919] 'range keys from in-memory index tree' (duration: 513.980804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:09.217565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:08.703111Z","time spent":"514.444585ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T06:05:09.219917Z","caller":"traceutil/trace.go:171","msg":"trace[2050775697] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"505.006291ms","start":"2026-09-19T06:05:08.714855Z","end":"2026-09-19T06:05:09.219861Z","steps":["trace[2050775697] 'process raft request' (duration: 504.191531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:09.220086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:08.714815Z","time spent":"505.172105ms","remote":"127.0.0.1:54990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:05:12.672728Z","caller":"traceutil/trace.go:171","msg":"trace[477348304] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"113.185682ms","start":"2026-09-19T06:05:12.559373Z","end":"2026-09-19T06:05:12.672558Z","steps":["trace[477348304] 'process raft request' (duration: 112.769452ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:12.735231Z","caller":"traceutil/trace.go:171","msg":"trace[486518472] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"168.649649ms","start":"2026-09-19T06:05:12.566543Z","end":"2026-09-19T06:05:12.735193Z","steps":["trace[486518472] 'process raft request' (duration: 168.373162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:26.695822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.796934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:05:26.695927Z","caller":"traceutil/trace.go:171","msg":"trace[987336612] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:815; }","duration":"288.96919ms","start":"2026-09-19T06:05:26.406936Z","end":"2026-09-19T06:05:26.695905Z","steps":["trace[987336612] 'count revisions from in-memory index tree' (duration: 288.684591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:26.69616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.022071ms","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-19T06:05:26.69623Z","caller":"traceutil/trace.go:171","msg":"trace[338014803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"328.141094ms","start":"2026-09-19T06:05:26.368072Z","end":"2026-09-19T06:05:26.696213Z","steps":["trace[338014803] 'range keys from in-memory index tree' (duration: 327.839626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:05:26.696261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:05:26.36805Z","time spent":"328.202456ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-19T06:05:26.711521Z","caller":"traceutil/trace.go:171","msg":"trace[2017145632] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"275.427901ms","start":"2026-09-19T06:05:26.436069Z","end":"2026-09-19T06:05:26.711497Z","steps":["trace[2017145632] 'process raft request' (duration: 275.314317ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:05:26.731237Z","caller":"traceutil/trace.go:171","msg":"trace[1976450964] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"106.636085ms","start":"2026-09-19T06:05:26.624547Z","end":"2026-09-19T06:05:26.731183Z","steps":["trace[1976450964] 'process raft request' (duration: 106.361347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:00.225944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.867203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"warn","ts":"2026-09-19T06:06:00.226102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.368342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:06:00.226127Z","caller":"traceutil/trace.go:171","msg":"trace[902584728] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1229; }","duration":"223.151085ms","start":"2026-09-19T06:06:00.002944Z","end":"2026-09-19T06:06:00.226095Z","steps":["trace[902584728] 'range keys from in-memory index tree' (duration: 222.720538ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:00.22616Z","caller":"traceutil/trace.go:171","msg":"trace[1386573448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"168.473697ms","start":"2026-09-19T06:06:00.057673Z","end":"2026-09-19T06:06:00.226146Z","steps":["trace[1386573448] 'range keys from in-memory index tree' (duration: 167.771689ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:13.17158Z","caller":"traceutil/trace.go:171","msg":"trace[1652478813] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"105.050812ms","start":"2026-09-19T06:06:13.066484Z","end":"2026-09-19T06:06:13.171535Z","steps":["trace[1652478813] 'process raft request' (duration: 104.635274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:13.171672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.011449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:06:13.17175Z","caller":"traceutil/trace.go:171","msg":"trace[2143067604] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1338; }","duration":"100.188666ms","start":"2026-09-19T06:06:13.071538Z","end":"2026-09-19T06:06:13.171727Z","steps":["trace[2143067604] 'agreement among raft nodes before linearized reading' (duration: 99.942656ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:21.980193Z","caller":"traceutil/trace.go:171","msg":"trace[1001602748] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"145.439357ms","start":"2026-09-19T06:06:21.834716Z","end":"2026-09-19T06:06:21.980155Z","steps":["trace[1001602748] 'process raft request' (duration: 145.201306ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:21.984331Z","caller":"traceutil/trace.go:171","msg":"trace[2099573262] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"148.625715ms","start":"2026-09-19T06:06:21.835681Z","end":"2026-09-19T06:06:21.984307Z","steps":["trace[2099573262] 'process raft request' (duration: 148.438647ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:24.162382Z","caller":"traceutil/trace.go:171","msg":"trace[1034916072] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"174.218429ms","start":"2026-09-19T06:06:23.988096Z","end":"2026-09-19T06:06:24.162314Z","steps":["trace[1034916072] 'process raft request' (duration: 171.832075ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:24.166879Z","caller":"traceutil/trace.go:171","msg":"trace[666049185] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1541; }","duration":"111.936313ms","start":"2026-09-19T06:06:24.054918Z","end":"2026-09-19T06:06:24.166854Z","steps":["trace[666049185] 'read index received' (duration: 105.134506ms)","trace[666049185] 'applied index is now lower than readState.Index' (duration: 6.800527ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:06:24.167074Z","caller":"traceutil/trace.go:171","msg":"trace[668285502] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"177.000512ms","start":"2026-09-19T06:06:23.990059Z","end":"2026-09-19T06:06:24.167059Z","steps":["trace[668285502] 'process raft request' (duration: 176.633846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:24.167257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.873783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:06:24.167404Z","caller":"traceutil/trace.go:171","msg":"trace[1237393332] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1494; }","duration":"102.141185ms","start":"2026-09-19T06:06:24.06523Z","end":"2026-09-19T06:06:24.167371Z","steps":["trace[1237393332] 'agreement among raft nodes before linearized reading' (duration: 101.931555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:24.167691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.77001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:06:24.167732Z","caller":"traceutil/trace.go:171","msg":"trace[1116693995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1494; }","duration":"112.859984ms","start":"2026-09-19T06:06:24.054861Z","end":"2026-09-19T06:06:24.167721Z","steps":["trace[1116693995] 'agreement among raft nodes before linearized reading' (duration: 112.792131ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:26.150185Z","caller":"traceutil/trace.go:171","msg":"trace[2046577062] linearizableReadLoop","detail":"{readStateIndex:1556; appliedIndex:1554; }","duration":"159.569892ms","start":"2026-09-19T06:06:25.990472Z","end":"2026-09-19T06:06:26.150042Z","steps":["trace[2046577062] 'read index received' (duration: 84.90477ms)","trace[2046577062] 'applied index is now lower than readState.Index' (duration: 74.663842ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:06:26.150348Z","caller":"traceutil/trace.go:171","msg":"trace[183474540] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"167.948671ms","start":"2026-09-19T06:06:25.982349Z","end":"2026-09-19T06:06:26.150297Z","steps":["trace[183474540] 'process raft request' (duration: 167.078882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:26.150307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.794883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" ","response":"range_response_count:1 size:1035"} {"level":"info","ts":"2026-09-19T06:06:26.150731Z","caller":"traceutil/trace.go:171","msg":"trace[1156291678] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:1; response_revision:1506; }","duration":"160.220011ms","start":"2026-09-19T06:06:25.990465Z","end":"2026-09-19T06:06:26.150685Z","steps":["trace[1156291678] 'agreement among raft nodes before linearized reading' (duration: 159.614035ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:51.248675Z","caller":"traceutil/trace.go:171","msg":"trace[143727894] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"325.775056ms","start":"2026-09-19T06:06:50.922877Z","end":"2026-09-19T06:06:51.248652Z","steps":["trace[143727894] 'process raft request' (duration: 325.579637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:51.248743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.632567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:06:51.248785Z","caller":"traceutil/trace.go:171","msg":"trace[454309723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1908; }","duration":"191.734242ms","start":"2026-09-19T06:06:51.057041Z","end":"2026-09-19T06:06:51.248775Z","steps":["trace[454309723] 'agreement among raft nodes before linearized reading' (duration: 191.649598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:51.248795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:06:50.922808Z","time spent":"325.925414ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:06:51.248662Z","caller":"traceutil/trace.go:171","msg":"trace[1349961027] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1984; }","duration":"191.539552ms","start":"2026-09-19T06:06:51.057096Z","end":"2026-09-19T06:06:51.248636Z","steps":["trace[1349961027] 'read index received' (duration: 191.339173ms)","trace[1349961027] 'applied index is now lower than readState.Index' (duration: 198.569µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:06:51.249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.759538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-09-19T06:06:51.249014Z","caller":"traceutil/trace.go:171","msg":"trace[1214569595] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1908; }","duration":"163.822501ms","start":"2026-09-19T06:06:51.085186Z","end":"2026-09-19T06:06:51.249008Z","steps":["trace[1214569595] 'agreement among raft nodes before linearized reading' (duration: 163.748648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:51.249218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.868516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-367d22e8-4a17-4ca1-867a-81b8ea8b84d3\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-19T06:06:51.249234Z","caller":"traceutil/trace.go:171","msg":"trace[1143733901] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-367d22e8-4a17-4ca1-867a-81b8ea8b84d3; range_end:; response_count:1; response_revision:1908; }","duration":"149.941238ms","start":"2026-09-19T06:06:51.099287Z","end":"2026-09-19T06:06:51.249228Z","steps":["trace[1143733901] 'agreement among raft nodes before linearized reading' (duration: 149.895816ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:51.49532Z","caller":"traceutil/trace.go:171","msg":"trace[1273033308] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"238.996121ms","start":"2026-09-19T06:06:51.256298Z","end":"2026-09-19T06:06:51.495294Z","steps":["trace[1273033308] 'process raft request' (duration: 155.176156ms)","trace[1273033308] 'compare' (duration: 83.698529ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:06:51.776776Z","caller":"traceutil/trace.go:171","msg":"trace[1443959454] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"226.504274ms","start":"2026-09-19T06:06:51.55024Z","end":"2026-09-19T06:06:51.776745Z","steps":["trace[1443959454] 'read index received' (duration: 182.528609ms)","trace[1443959454] 'applied index is now lower than readState.Index' (duration: 43.974295ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:06:51.776883Z","caller":"traceutil/trace.go:171","msg":"trace[1319759142] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"517.868053ms","start":"2026-09-19T06:06:51.258938Z","end":"2026-09-19T06:06:51.776806Z","steps":["trace[1319759142] 'process raft request' (duration: 473.985883ms)","trace[1319759142] 'compare' (duration: 43.669179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:06:51.777061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:06:51.258915Z","time spent":"518.046761ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3726,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:06:51.777069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.812078ms","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-19T06:06:51.777108Z","caller":"traceutil/trace.go:171","msg":"trace[1575325858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1910; }","duration":"226.918233ms","start":"2026-09-19T06:06:51.550178Z","end":"2026-09-19T06:06:51.777096Z","steps":["trace[1575325858] 'agreement among raft nodes before linearized reading' (duration: 226.739505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:51.777323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.144141ms","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-19T06:06:51.777361Z","caller":"traceutil/trace.go:171","msg":"trace[303242290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1910; }","duration":"143.228924ms","start":"2026-09-19T06:06:51.634121Z","end":"2026-09-19T06:06:51.77735Z","steps":["trace[303242290] 'agreement among raft nodes before linearized reading' (duration: 143.076247ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:59.760038Z","caller":"traceutil/trace.go:171","msg":"trace[2059070559] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"222.69086ms","start":"2026-09-19T06:06:59.537284Z","end":"2026-09-19T06:06:59.759975Z","steps":["trace[2059070559] 'process raft request' (duration: 222.284222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:26.575944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.808146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:26.576048Z","caller":"traceutil/trace.go:171","msg":"trace[380907852] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"578.329115ms","start":"2026-09-19T06:07:25.997702Z","end":"2026-09-19T06:07:26.576031Z","steps":["trace[380907852] 'process raft request' (duration: 420.3433ms)","trace[380907852] 'compare' (duration: 157.604596ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:26.576092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:25.99769Z","time spent":"578.381008ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:26.903777Z","caller":"traceutil/trace.go:171","msg":"trace[1435557990] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"904.003535ms","start":"2026-09-19T06:07:25.999748Z","end":"2026-09-19T06:07:26.903752Z","steps":["trace[1435557990] 'process raft request' (duration: 883.965873ms)","trace[1435557990] 'compare' (duration: 18.971918ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:26.903812Z","caller":"traceutil/trace.go:171","msg":"trace[539097959] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"695.505622ms","start":"2026-09-19T06:07:26.208256Z","end":"2026-09-19T06:07:26.903762Z","steps":["trace[539097959] 'process raft request' (duration: 695.342664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:26.904004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:25.99973Z","time spent":"904.13327ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:26.904049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.208216Z","time spent":"695.712582ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:26.903777Z","caller":"traceutil/trace.go:171","msg":"trace[1451280563] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2238; }","duration":"845.07166ms","start":"2026-09-19T06:07:26.058663Z","end":"2026-09-19T06:07:26.903735Z","steps":["trace[1451280563] 'read index received' (duration: 359.408547ms)","trace[1451280563] 'applied index is now lower than readState.Index' (duration: 485.662223ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:26.904288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.625516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:26.904407Z","caller":"traceutil/trace.go:171","msg":"trace[1487862726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2156; }","duration":"845.785114ms","start":"2026-09-19T06:07:26.058605Z","end":"2026-09-19T06:07:26.90439Z","steps":["trace[1487862726] 'agreement among raft nodes before linearized reading' (duration: 845.232817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:26.904787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.058567Z","time spent":"846.202475ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T06:07:28.138842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.476978ms","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-19T06:07:28.138977Z","caller":"traceutil/trace.go:171","msg":"trace[1594902439] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2156; }","duration":"996.629756ms","start":"2026-09-19T06:07:27.142326Z","end":"2026-09-19T06:07:28.138956Z","steps":["trace[1594902439] 'range keys from in-memory index tree' (duration: 996.438296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.139367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055989155s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:28.13958Z","caller":"traceutil/trace.go:171","msg":"trace[2001638315] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2241; }","duration":"1.235380973s","start":"2026-09-19T06:07:26.904186Z","end":"2026-09-19T06:07:28.139567Z","steps":["trace[2001638315] 'read index received' (duration: 179.004328ms)","trace[2001638315] 'applied index is now lower than readState.Index' (duration: 1.056375925s)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:28.139674Z","caller":"traceutil/trace.go:171","msg":"trace[794502619] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"1.389949559s","start":"2026-09-19T06:07:26.749716Z","end":"2026-09-19T06:07:28.139665Z","steps":["trace[794502619] 'process raft request' (duration: 333.569274ms)","trace[794502619] 'compare' (duration: 1.05527002s)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:28.139719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.749686Z","time spent":"1.390007111s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:28.139817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419423537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T06:07:28.139913Z","caller":"traceutil/trace.go:171","msg":"trace[1182775332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2158; }","duration":"1.419523661s","start":"2026-09-19T06:07:26.720338Z","end":"2026-09-19T06:07:28.139862Z","steps":["trace[1182775332] 'agreement among raft nodes before linearized reading' (duration: 1.419315821s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.139961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475410216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-19T06:07:28.139967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.720313Z","time spent":"1.419639707s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-19T06:07:28.139984Z","caller":"traceutil/trace.go:171","msg":"trace[1777535210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2158; }","duration":"1.475432577s","start":"2026-09-19T06:07:26.664544Z","end":"2026-09-19T06:07:28.139976Z","steps":["trace[1777535210] 'agreement among raft nodes before linearized reading' (duration: 1.475314721s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.140005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.664479Z","time spent":"1.475522731s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T06:07:28.140087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613739897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-19T06:07:28.140156Z","caller":"traceutil/trace.go:171","msg":"trace[1074535832] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2158; }","duration":"1.613843932s","start":"2026-09-19T06:07:26.52629Z","end":"2026-09-19T06:07:28.140134Z","steps":["trace[1074535832] 'agreement among raft nodes before linearized reading' (duration: 1.613687555s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.140219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.526268Z","time spent":"1.613938837s","remote":"127.0.0.1:54946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-19T06:07:28.140262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588986523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:28.140285Z","caller":"traceutil/trace.go:171","msg":"trace[912512599] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2158; }","duration":"1.589040966s","start":"2026-09-19T06:07:26.551239Z","end":"2026-09-19T06:07:28.14028Z","steps":["trace[912512599] 'agreement among raft nodes before linearized reading' (duration: 1.589000174s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.140303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.551221Z","time spent":"1.589075368s","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:07:28.140111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.743529187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:07:28.140612Z","caller":"traceutil/trace.go:171","msg":"trace[572594498] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"1.336567987s","start":"2026-09-19T06:07:26.804004Z","end":"2026-09-19T06:07:28.140572Z","steps":["trace[572594498] 'process raft request' (duration: 1.335477444s)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:28.140683Z","caller":"traceutil/trace.go:171","msg":"trace[1719200322] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2158; }","duration":"1.744121847s","start":"2026-09-19T06:07:26.396543Z","end":"2026-09-19T06:07:28.140665Z","steps":["trace[1719200322] 'agreement among raft nodes before linearized reading' (duration: 1.743543428s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.140735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.396523Z","time spent":"1.744194201s","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:07:28.140763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.803984Z","time spent":"1.336695394s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:28.141021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384282048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-19T06:07:28.141068Z","caller":"traceutil/trace.go:171","msg":"trace[214038498] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2158; }","duration":"1.384347542s","start":"2026-09-19T06:07:26.756703Z","end":"2026-09-19T06:07:28.141051Z","steps":["trace[214038498] 'agreement among raft nodes before linearized reading' (duration: 1.384223296s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.141106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.756692Z","time spent":"1.384403304s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-19T06:07:28.141371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417130304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T06:07:28.141413Z","caller":"traceutil/trace.go:171","msg":"trace[129342650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2158; }","duration":"1.417206587s","start":"2026-09-19T06:07:26.724193Z","end":"2026-09-19T06:07:28.1414Z","steps":["trace[129342650] 'agreement among raft nodes before linearized reading' (duration: 1.417092481s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.141449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.724174Z","time spent":"1.41726485s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T06:07:28.155413Z","caller":"traceutil/trace.go:171","msg":"trace[1992686551] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"573.112675ms","start":"2026-09-19T06:07:27.582281Z","end":"2026-09-19T06:07:28.155393Z","steps":["trace[1992686551] 'process raft request' (duration: 573.00957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.155584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.582252Z","time spent":"573.224431ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:28.155628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249566177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:28.156016Z","caller":"traceutil/trace.go:171","msg":"trace[2006838066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2159; }","duration":"1.25004257s","start":"2026-09-19T06:07:26.905948Z","end":"2026-09-19T06:07:28.155991Z","steps":["trace[2006838066] 'agreement among raft nodes before linearized reading' (duration: 1.249550435s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.156096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.64169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"warn","ts":"2026-09-19T06:07:28.155855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.656114ms","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-19T06:07:28.156186Z","caller":"traceutil/trace.go:171","msg":"trace[816278496] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2159; }","duration":"660.765656ms","start":"2026-09-19T06:07:27.495385Z","end":"2026-09-19T06:07:28.156151Z","steps":["trace[816278496] 'agreement among raft nodes before linearized reading' (duration: 660.554015ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:28.15619Z","caller":"traceutil/trace.go:171","msg":"trace[756018656] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2159; }","duration":"178.021703ms","start":"2026-09-19T06:07:27.978149Z","end":"2026-09-19T06:07:28.156171Z","steps":["trace[756018656] 'agreement among raft nodes before linearized reading' (duration: 177.594702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.156216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:27.495363Z","time spent":"660.84812ms","remote":"127.0.0.1:55106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2014,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-09-19T06:07:28.156085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:26.905931Z","time spent":"1.250136505s","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T06:07:28.665936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673545449820156034,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T06:07:28.77014Z","caller":"traceutil/trace.go:171","msg":"trace[132811076] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"623.052384ms","start":"2026-09-19T06:07:28.147061Z","end":"2026-09-19T06:07:28.770114Z","steps":["trace[132811076] 'process raft request' (duration: 622.97654ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:28.770268Z","caller":"traceutil/trace.go:171","msg":"trace[187065219] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"623.208501ms","start":"2026-09-19T06:07:28.146999Z","end":"2026-09-19T06:07:28.770208Z","steps":["trace[187065219] 'process raft request' (duration: 622.850173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.770304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.147036Z","time spent":"623.1799ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:28.770368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.146971Z","time spent":"623.347388ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:28.770721Z","caller":"traceutil/trace.go:171","msg":"trace[542961711] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"623.505676ms","start":"2026-09-19T06:07:28.147197Z","end":"2026-09-19T06:07:28.770703Z","steps":["trace[542961711] 'process raft request' (duration: 622.884495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.770834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.147187Z","time spent":"623.59819ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:28.770878Z","caller":"traceutil/trace.go:171","msg":"trace[1682893956] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"623.600411ms","start":"2026-09-19T06:07:28.14726Z","end":"2026-09-19T06:07:28.77086Z","steps":["trace[1682893956] 'process raft request' (duration: 623.315987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.771234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.147249Z","time spent":"623.935527ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:28.991892Z","caller":"traceutil/trace.go:171","msg":"trace[892182163] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"830.79106ms","start":"2026-09-19T06:07:28.161069Z","end":"2026-09-19T06:07:28.99186Z","steps":["trace[892182163] 'process raft request' (duration: 800.660377ms)","trace[892182163] 'compare' (duration: 29.844179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:28.991978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.629265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:07:28.991997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.161038Z","time spent":"830.892916ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:28.991841Z","caller":"traceutil/trace.go:171","msg":"trace[1096197819] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2244; }","duration":"826.474167ms","start":"2026-09-19T06:07:28.165337Z","end":"2026-09-19T06:07:28.991811Z","steps":["trace[1096197819] 'read index received' (duration: 603.916546ms)","trace[1096197819] 'applied index is now lower than readState.Index' (duration: 222.556311ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:28.992028Z","caller":"traceutil/trace.go:171","msg":"trace[2125669117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2164; }","duration":"826.718599ms","start":"2026-09-19T06:07:28.165295Z","end":"2026-09-19T06:07:28.992013Z","steps":["trace[2125669117] 'agreement among raft nodes before linearized reading' (duration: 826.627294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.99207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.165282Z","time spent":"826.777672ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T06:07:28.992287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.704556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:28.992397Z","caller":"traceutil/trace.go:171","msg":"trace[1811339050] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2164; }","duration":"438.883255ms","start":"2026-09-19T06:07:28.553485Z","end":"2026-09-19T06:07:28.992369Z","steps":["trace[1811339050] 'agreement among raft nodes before linearized reading' (duration: 438.686085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.99247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.553464Z","time spent":"438.99044ms","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:07:28.992593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.030733ms","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-19T06:07:28.992648Z","caller":"traceutil/trace.go:171","msg":"trace[671779548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2164; }","duration":"413.117218ms","start":"2026-09-19T06:07:28.579516Z","end":"2026-09-19T06:07:28.992633Z","steps":["trace[671779548] 'agreement among raft nodes before linearized reading' (duration: 412.969331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:28.992716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:28.579501Z","time spent":"413.203712ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T06:09:09.818123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.921438ms","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-19T06:09:09.818221Z","caller":"traceutil/trace.go:171","msg":"trace[1761285216] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2809; }","duration":"235.092679ms","start":"2026-09-19T06:09:09.583105Z","end":"2026-09-19T06:09:09.818198Z","steps":["trace[1761285216] 'range keys from in-memory index tree' (duration: 234.772516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:09.818322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.794592ms","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-19T06:09:09.81839Z","caller":"traceutil/trace.go:171","msg":"trace[168139864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2809; }","duration":"237.896572ms","start":"2026-09-19T06:09:09.580481Z","end":"2026-09-19T06:09:09.818378Z","steps":["trace[168139864] 'range keys from in-memory index tree' (duration: 237.67307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:09.818526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.976176ms","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-19T06:09:09.818705Z","caller":"traceutil/trace.go:171","msg":"trace[282843622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2809; }","duration":"192.027486ms","start":"2026-09-19T06:09:09.626522Z","end":"2026-09-19T06:09:09.81855Z","steps":["trace[282843622] 'range keys from in-memory index tree' (duration: 191.378411ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:02.123787Z","caller":"traceutil/trace.go:171","msg":"trace[1189838753] linearizableReadLoop","detail":"{readStateIndex:5220; appliedIndex:5219; }","duration":"221.150378ms","start":"2026-09-19T06:14:01.902607Z","end":"2026-09-19T06:14:02.123758Z","steps":["trace[1189838753] 'read index received' (duration: 183.554935ms)","trace[1189838753] 'applied index is now lower than readState.Index' (duration: 37.574522ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:02.123834Z","caller":"traceutil/trace.go:171","msg":"trace[701927848] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"318.544552ms","start":"2026-09-19T06:14:01.805249Z","end":"2026-09-19T06:14:02.123793Z","steps":["trace[701927848] 'process raft request' (duration: 280.902758ms)","trace[701927848] 'compare' (duration: 37.240087ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:02.124029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:01.80523Z","time spent":"318.71548ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:14:02.12409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.464222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-fqzpw\" ","response":"range_response_count:1 size:5138"} {"level":"info","ts":"2026-09-19T06:14:02.124132Z","caller":"traceutil/trace.go:171","msg":"trace[1224478456] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-fqzpw; range_end:; response_count:1; response_revision:5024; }","duration":"221.561346ms","start":"2026-09-19T06:14:01.902557Z","end":"2026-09-19T06:14:02.124118Z","steps":["trace[1224478456] 'agreement among raft nodes before linearized reading' (duration: 221.372858ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:02.16429Z","caller":"traceutil/trace.go:171","msg":"trace[1155870274] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"258.670669ms","start":"2026-09-19T06:14:01.905601Z","end":"2026-09-19T06:14:02.164272Z","steps":["trace[1155870274] 'process raft request' (duration: 258.563725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:02.164318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.805879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:02.164292Z","caller":"traceutil/trace.go:171","msg":"trace[1095434309] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"260.385851ms","start":"2026-09-19T06:14:01.903864Z","end":"2026-09-19T06:14:02.16425Z","steps":["trace[1095434309] 'process raft request' (duration: 260.146281ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:02.16445Z","caller":"traceutil/trace.go:171","msg":"trace[1217293016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5026; }","duration":"107.954565ms","start":"2026-09-19T06:14:02.056438Z","end":"2026-09-19T06:14:02.164393Z","steps":["trace[1217293016] 'agreement among raft nodes before linearized reading' (duration: 107.790418ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:02.333576Z","caller":"traceutil/trace.go:171","msg":"trace[1757154106] linearizableReadLoop","detail":"{readStateIndex:5223; appliedIndex:5222; }","duration":"167.819702ms","start":"2026-09-19T06:14:02.165726Z","end":"2026-09-19T06:14:02.333546Z","steps":["trace[1757154106] 'read index received' (duration: 163.379424ms)","trace[1757154106] 'applied index is now lower than readState.Index' (duration: 4.438718ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:02.33372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.965208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:02.333753Z","caller":"traceutil/trace.go:171","msg":"trace[796789513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5027; }","duration":"168.051571ms","start":"2026-09-19T06:14:02.16569Z","end":"2026-09-19T06:14:02.333742Z","steps":["trace[796789513] 'agreement among raft nodes before linearized reading' (duration: 167.949227ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:02.333739Z","caller":"traceutil/trace.go:171","msg":"trace[1870383693] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"201.179585ms","start":"2026-09-19T06:14:02.132389Z","end":"2026-09-19T06:14:02.333568Z","steps":["trace[1870383693] 'process raft request' (duration: 196.745207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:08.680817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.394611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-09-19T06:14:08.680924Z","caller":"traceutil/trace.go:171","msg":"trace[920355369] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5078; }","duration":"280.536857ms","start":"2026-09-19T06:14:08.400365Z","end":"2026-09-19T06:14:08.680902Z","steps":["trace[920355369] 'range keys from in-memory index tree' (duration: 280.212023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:08.680993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.782423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-19T06:14:08.68109Z","caller":"traceutil/trace.go:171","msg":"trace[1890014695] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5078; }","duration":"208.891418ms","start":"2026-09-19T06:14:08.472172Z","end":"2026-09-19T06:14:08.681063Z","steps":["trace[1890014695] 'range keys from in-memory index tree' (duration: 208.4759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:08.681213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.774917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7b55bc5745-bllqh.18d6a434846abe91\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-09-19T06:14:08.68125Z","caller":"traceutil/trace.go:171","msg":"trace[1802752581] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7b55bc5745-bllqh.18d6a434846abe91; range_end:; response_count:1; response_revision:5078; }","duration":"280.821039ms","start":"2026-09-19T06:14:08.400418Z","end":"2026-09-19T06:14:08.681239Z","steps":["trace[1802752581] 'range keys from in-memory index tree' (duration: 280.217453ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:08.94773Z","caller":"traceutil/trace.go:171","msg":"trace[2053486691] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5277; }","duration":"155.539058ms","start":"2026-09-19T06:14:08.792151Z","end":"2026-09-19T06:14:08.94769Z","steps":["trace[2053486691] 'read index received' (duration: 150.347577ms)","trace[2053486691] 'applied index is now lower than readState.Index' (duration: 5.190161ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:08.948089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.919485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-19T06:14:08.948212Z","caller":"traceutil/trace.go:171","msg":"trace[117753714] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5080; }","duration":"156.091352ms","start":"2026-09-19T06:14:08.792111Z","end":"2026-09-19T06:14:08.948203Z","steps":["trace[117753714] 'agreement among raft nodes before linearized reading' (duration: 155.854972ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:08.948378Z","caller":"traceutil/trace.go:171","msg":"trace[1131852921] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"253.318039ms","start":"2026-09-19T06:14:08.695044Z","end":"2026-09-19T06:14:08.948362Z","steps":["trace[1131852921] 'process raft request' (duration: 247.527542ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:09.023934Z","caller":"traceutil/trace.go:171","msg":"trace[613746139] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"149.598427ms","start":"2026-09-19T06:14:08.874312Z","end":"2026-09-19T06:14:09.02391Z","steps":["trace[613746139] 'process raft request' (duration: 149.417839ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:09.097413Z","caller":"traceutil/trace.go:171","msg":"trace[2139216893] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"138.022176ms","start":"2026-09-19T06:14:08.959347Z","end":"2026-09-19T06:14:09.097369Z","steps":["trace[2139216893] 'process raft request' (duration: 137.742383ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:09.097481Z","caller":"traceutil/trace.go:171","msg":"trace[579335139] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"138.075287ms","start":"2026-09-19T06:14:08.959382Z","end":"2026-09-19T06:14:09.097457Z","steps":["trace[579335139] 'process raft request' (duration: 137.747673ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:09.097593Z","caller":"traceutil/trace.go:171","msg":"trace[301803893] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"139.563012ms","start":"2026-09-19T06:14:08.958017Z","end":"2026-09-19T06:14:09.09758Z","steps":["trace[301803893] 'process raft request' (duration: 139.036119ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:09.097567Z","caller":"traceutil/trace.go:171","msg":"trace[1531794632] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"139.563762ms","start":"2026-09-19T06:14:08.957929Z","end":"2026-09-19T06:14:09.097493Z","steps":["trace[1531794632] 'process raft request' (duration: 112.783932ms)","trace[1531794632] 'compare' (duration: 26.207525ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:09.097758Z","caller":"traceutil/trace.go:171","msg":"trace[954813638] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"135.520289ms","start":"2026-09-19T06:14:08.962229Z","end":"2026-09-19T06:14:09.097749Z","steps":["trace[954813638] 'process raft request' (duration: 134.941054ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:09.356456Z","caller":"traceutil/trace.go:171","msg":"trace[391038989] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"177.774699ms","start":"2026-09-19T06:14:09.178643Z","end":"2026-09-19T06:14:09.356418Z","steps":["trace[391038989] 'process raft request' (duration: 108.731919ms)","trace[391038989] 'compare' (duration: 68.57379ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:11.35944Z","caller":"traceutil/trace.go:171","msg":"trace[1764402212] linearizableReadLoop","detail":"{readStateIndex:5355; appliedIndex:5354; }","duration":"212.982841ms","start":"2026-09-19T06:14:11.146426Z","end":"2026-09-19T06:14:11.359408Z","steps":["trace[1764402212] 'read index received' (duration: 142.359211ms)","trace[1764402212] 'applied index is now lower than readState.Index' (duration: 70.62223ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:11.359977Z","caller":"traceutil/trace.go:171","msg":"trace[1262930068] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"214.804288ms","start":"2026-09-19T06:14:11.14505Z","end":"2026-09-19T06:14:11.359855Z","steps":["trace[1262930068] 'process raft request' (duration: 143.783351ms)","trace[1262930068] 'compare' (duration: 68.834114ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:11.360087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.636999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-09-19T06:14:11.361092Z","caller":"traceutil/trace.go:171","msg":"trace[859352146] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5156; }","duration":"214.701144ms","start":"2026-09-19T06:14:11.14637Z","end":"2026-09-19T06:14:11.361071Z","steps":["trace[859352146] 'agreement among raft nodes before linearized reading' (duration: 213.533804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:11.361958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.068639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-09-19T06:14:11.36203Z","caller":"traceutil/trace.go:171","msg":"trace[1971114530] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5156; }","duration":"139.225395ms","start":"2026-09-19T06:14:11.222785Z","end":"2026-09-19T06:14:11.362011Z","steps":["trace[1971114530] 'agreement among raft nodes before linearized reading' (duration: 138.042096ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:13.81919Z","caller":"traceutil/trace.go:171","msg":"trace[419118146] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"308.76225ms","start":"2026-09-19T06:14:13.510404Z","end":"2026-09-19T06:14:13.819166Z","steps":["trace[419118146] 'process raft request' (duration: 240.864121ms)","trace[419118146] 'compare' (duration: 67.609967ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:13.819304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:13.510374Z","time spent":"308.866265ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:14:13.866752Z","caller":"traceutil/trace.go:171","msg":"trace[638601796] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5383; }","duration":"139.202013ms","start":"2026-09-19T06:14:13.727524Z","end":"2026-09-19T06:14:13.866726Z","steps":["trace[638601796] 'read index received' (duration: 23.738673ms)","trace[638601796] 'applied index is now lower than readState.Index' (duration: 115.46233ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:13.866863Z","caller":"traceutil/trace.go:171","msg":"trace[13624660] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"232.37218ms","start":"2026-09-19T06:14:13.634428Z","end":"2026-09-19T06:14:13.8668Z","steps":["trace[13624660] 'process raft request' (duration: 232.124499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:13.867081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.512317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-19T06:14:13.867178Z","caller":"traceutil/trace.go:171","msg":"trace[82061062] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:5186; }","duration":"139.669333ms","start":"2026-09-19T06:14:13.727483Z","end":"2026-09-19T06:14:13.867153Z","steps":["trace[82061062] 'agreement among raft nodes before linearized reading' (duration: 139.343359ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:19.679856Z","caller":"traceutil/trace.go:171","msg":"trace[1066247034] linearizableReadLoop","detail":"{readStateIndex:5486; appliedIndex:5485; }","duration":"240.351908ms","start":"2026-09-19T06:14:19.439487Z","end":"2026-09-19T06:14:19.679839Z","steps":["trace[1066247034] 'read index received' (duration: 148.463471ms)","trace[1066247034] 'applied index is now lower than readState.Index' (duration: 91.887607ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:19.680012Z","caller":"traceutil/trace.go:171","msg":"trace[295100932] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"278.466462ms","start":"2026-09-19T06:14:19.40152Z","end":"2026-09-19T06:14:19.679986Z","steps":["trace[295100932] 'process raft request' (duration: 186.489011ms)","trace[295100932] 'compare' (duration: 91.585144ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:19.680042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.535477ms","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":"warn","ts":"2026-09-19T06:14:19.6801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.688048ms","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-19T06:14:19.680106Z","caller":"traceutil/trace.go:171","msg":"trace[1381439834] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5282; }","duration":"240.652181ms","start":"2026-09-19T06:14:19.439438Z","end":"2026-09-19T06:14:19.68009Z","steps":["trace[1381439834] 'agreement among raft nodes before linearized reading' (duration: 240.536316ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:19.680125Z","caller":"traceutil/trace.go:171","msg":"trace[876758292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5282; }","duration":"210.73548ms","start":"2026-09-19T06:14:19.469383Z","end":"2026-09-19T06:14:19.680119Z","steps":["trace[876758292] 'agreement among raft nodes before linearized reading' (duration: 210.639266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:19.680217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.621596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13875"} {"level":"info","ts":"2026-09-19T06:14:19.680233Z","caller":"traceutil/trace.go:171","msg":"trace[724708585] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5282; }","duration":"165.662088ms","start":"2026-09-19T06:14:19.514566Z","end":"2026-09-19T06:14:19.680228Z","steps":["trace[724708585] 'agreement among raft nodes before linearized reading' (duration: 165.617966ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:19.851633Z","caller":"traceutil/trace.go:171","msg":"trace[1024975481] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"124.234354ms","start":"2026-09-19T06:14:19.727367Z","end":"2026-09-19T06:14:19.851601Z","steps":["trace[1024975481] 'process raft request' (duration: 55.433506ms)","trace[1024975481] 'compare' (duration: 68.61577ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:20.068778Z","caller":"traceutil/trace.go:171","msg":"trace[1321803880] linearizableReadLoop","detail":"{readStateIndex:5495; appliedIndex:5494; }","duration":"141.198001ms","start":"2026-09-19T06:14:19.927557Z","end":"2026-09-19T06:14:20.068755Z","steps":["trace[1321803880] 'read index received' (duration: 121.763638ms)","trace[1321803880] 'applied index is now lower than readState.Index' (duration: 19.433433ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:20.069057Z","caller":"traceutil/trace.go:171","msg":"trace[802921969] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"195.183475ms","start":"2026-09-19T06:14:19.873858Z","end":"2026-09-19T06:14:20.069041Z","steps":["trace[802921969] 'process raft request' (duration: 175.48927ms)","trace[802921969] 'compare' (duration: 19.272286ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:20.069293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.603975ms","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-19T06:14:20.069349Z","caller":"traceutil/trace.go:171","msg":"trace[1980738633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5291; }","duration":"147.71672ms","start":"2026-09-19T06:14:19.921617Z","end":"2026-09-19T06:14:20.069334Z","steps":["trace[1980738633] 'agreement among raft nodes before linearized reading' (duration: 147.431338ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:20.121064Z","caller":"traceutil/trace.go:171","msg":"trace[1265862661] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"175.633879ms","start":"2026-09-19T06:14:19.945408Z","end":"2026-09-19T06:14:20.121042Z","steps":["trace[1265862661] 'process raft request' (duration: 175.460441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:20.121299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.543178ms","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-19T06:14:20.121451Z","caller":"traceutil/trace.go:171","msg":"trace[955511279] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5292; }","duration":"190.786178ms","start":"2026-09-19T06:14:19.930585Z","end":"2026-09-19T06:14:20.121371Z","steps":["trace[955511279] 'agreement among raft nodes before linearized reading' (duration: 190.460334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:20.121584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.795527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-19T06:14:20.12178Z","caller":"traceutil/trace.go:171","msg":"trace[931796756] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5292; }","duration":"194.11445ms","start":"2026-09-19T06:14:19.927645Z","end":"2026-09-19T06:14:20.12176Z","steps":["trace[931796756] 'agreement among raft nodes before linearized reading' (duration: 193.557947ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:20.477997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2864} {"level":"info","ts":"2026-09-19T06:14:20.56484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2864,"took":"85.655822ms","hash":958161148,"current-db-size-bytes":52199424,"current-db-size":"52 MB","current-db-size-in-use-bytes":27779072,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T06:14:20.564946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":958161148,"revision":2864,"compact-revision":-1} {"level":"info","ts":"2026-09-19T06:14:24.841466Z","caller":"traceutil/trace.go:171","msg":"trace[313272180] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"197.948827ms","start":"2026-09-19T06:14:24.643498Z","end":"2026-09-19T06:14:24.841447Z","steps":["trace[313272180] 'process raft request' (duration: 197.867764ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:24.841596Z","caller":"traceutil/trace.go:171","msg":"trace[594667958] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"197.980669ms","start":"2026-09-19T06:14:24.643585Z","end":"2026-09-19T06:14:24.841566Z","steps":["trace[594667958] 'process raft request' (duration: 197.829082ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:24.841502Z","caller":"traceutil/trace.go:171","msg":"trace[321972879] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"198.027601ms","start":"2026-09-19T06:14:24.643434Z","end":"2026-09-19T06:14:24.841462Z","steps":["trace[321972879] 'process raft request' (duration: 197.804381ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:19:20.489688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5300} {"level":"info","ts":"2026-09-19T06:19:20.580584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5300,"took":"89.395075ms","hash":4291083836,"current-db-size-bytes":52199424,"current-db-size":"52 MB","current-db-size-in-use-bytes":19415040,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T06:19:20.580652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4291083836,"revision":5300,"compact-revision":2864}