{"level":"warn","ts":"2026-09-18T02:21:43.197603Z","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-18T02:21:43.197688Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.59: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.59:2380","--initial-cluster=instance=https://162.253.55.59:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.59:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.59: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-18T02:21:43.197769Z","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-18T02:21:43.197797Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.59:2380"]} {"level":"info","ts":"2026-09-18T02:21:43.19782Z","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-18T02:21:43.199169Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"]} {"level":"info","ts":"2026-09-18T02:21:43.19924Z","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.59:2380"],"listen-peer-urls":["https://162.253.55.59:2380"],"advertise-client-urls":["https://162.253.55.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.59: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-18T02:21:43.228288Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"28.740859ms"} {"level":"info","ts":"2026-09-18T02:21:43.241291Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f5132dc9ac390b66","cluster-id":"458d1cb56ab56fc4"} {"level":"info","ts":"2026-09-18T02:21:43.241444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T02:21:43.24149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became follower at term 0"} {"level":"info","ts":"2026-09-18T02:21:43.241524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f5132dc9ac390b66 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T02:21:43.241547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became follower at term 1"} {"level":"info","ts":"2026-09-18T02:21:43.241613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=(17659508908050942822)"} {"level":"warn","ts":"2026-09-18T02:21:43.250878Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T02:21:43.252751Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T02:21:43.255804Z","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-18T02:21:43.258552Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f5132dc9ac390b66","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T02:21:43.259977Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f5132dc9ac390b66","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T02:21:43.259982Z","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-18T02:21:43.260106Z","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-18T02:21:43.260127Z","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-18T02:21:43.260771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=(17659508908050942822)"} {"level":"info","ts":"2026-09-18T02:21:43.260861Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"458d1cb56ab56fc4","local-member-id":"f5132dc9ac390b66","added-peer-id":"f5132dc9ac390b66","added-peer-peer-urls":["https://162.253.55.59:2380"]} {"level":"info","ts":"2026-09-18T02:21:43.262332Z","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-18T02:21:43.26252Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f5132dc9ac390b66","initial-advertise-peer-urls":["https://162.253.55.59:2380"],"listen-peer-urls":["https://162.253.55.59:2380"],"advertise-client-urls":["https://162.253.55.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T02:21:43.262544Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T02:21:43.262621Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.59:2380"} {"level":"info","ts":"2026-09-18T02:21:43.262634Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.59:2380"} {"level":"info","ts":"2026-09-18T02:21:43.842431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T02:21:43.842484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T02:21:43.842506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 received MsgPreVoteResp from f5132dc9ac390b66 at term 1"} {"level":"info","ts":"2026-09-18T02:21:43.842518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became candidate at term 2"} {"level":"info","ts":"2026-09-18T02:21:43.842524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 received MsgVoteResp from f5132dc9ac390b66 at term 2"} {"level":"info","ts":"2026-09-18T02:21:43.842533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became leader at term 2"} {"level":"info","ts":"2026-09-18T02:21:43.842541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f5132dc9ac390b66 elected leader f5132dc9ac390b66 at term 2"} {"level":"info","ts":"2026-09-18T02:21:43.844426Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:21:43.845386Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T02:21:43.845394Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f5132dc9ac390b66","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.59:2379]}","request-path":"/0/members/f5132dc9ac390b66/attributes","cluster-id":"458d1cb56ab56fc4","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T02:21:43.846245Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"458d1cb56ab56fc4","local-member-id":"f5132dc9ac390b66","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:21:43.84636Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:21:43.846389Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:21:43.84641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T02:21:43.846804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.59:2379"} {"level":"info","ts":"2026-09-18T02:21:43.847398Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T02:21:43.847449Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T02:21:43.849521Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T02:21:50.302367Z","caller":"traceutil/trace.go:171","msg":"trace[387364598] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"129.609201ms","start":"2026-09-18T02:21:50.172722Z","end":"2026-09-18T02:21:50.302331Z","steps":["trace[387364598] 'process raft request' (duration: 33.31578ms)","trace[387364598] 'compare' (duration: 96.069608ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:50.302462Z","caller":"traceutil/trace.go:171","msg":"trace[665544814] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"123.751727ms","start":"2026-09-18T02:21:50.17868Z","end":"2026-09-18T02:21:50.302431Z","steps":["trace[665544814] 'process raft request' (duration: 123.540183ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:50.492394Z","caller":"traceutil/trace.go:171","msg":"trace[1856522585] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"157.303172ms","start":"2026-09-18T02:21:50.335063Z","end":"2026-09-18T02:21:50.492366Z","steps":["trace[1856522585] 'read index received' (duration: 95.348356ms)","trace[1856522585] 'applied index is now lower than readState.Index' (duration: 61.953836ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:50.492901Z","caller":"traceutil/trace.go:171","msg":"trace[523434282] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"169.634627ms","start":"2026-09-18T02:21:50.323195Z","end":"2026-09-18T02:21:50.492829Z","steps":["trace[523434282] 'process raft request' (duration: 107.206474ms)","trace[523434282] 'compare' (duration: 61.830473ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:50.492547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.460854ms","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-18T02:21:50.493162Z","caller":"traceutil/trace.go:171","msg":"trace[1885548876] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:322; }","duration":"158.093014ms","start":"2026-09-18T02:21:50.335051Z","end":"2026-09-18T02:21:50.493144Z","steps":["trace[1885548876] 'agreement among raft nodes before linearized reading' (duration: 157.413403ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:50.710352Z","caller":"traceutil/trace.go:171","msg":"trace[978093911] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:337; }","duration":"182.257777ms","start":"2026-09-18T02:21:50.528068Z","end":"2026-09-18T02:21:50.710326Z","steps":["trace[978093911] 'read index received' (duration: 88.03222ms)","trace[978093911] 'applied index is now lower than readState.Index' (duration: 94.224387ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:50.710432Z","caller":"traceutil/trace.go:171","msg":"trace[584705492] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"201.655366ms","start":"2026-09-18T02:21:50.508733Z","end":"2026-09-18T02:21:50.710388Z","steps":["trace[584705492] 'process raft request' (duration: 107.433629ms)","trace[584705492] 'compare' (duration: 94.026544ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:50.710491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.405439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:21:50.710543Z","caller":"traceutil/trace.go:171","msg":"trace[1175408166] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:324; }","duration":"182.522361ms","start":"2026-09-18T02:21:50.528001Z","end":"2026-09-18T02:21:50.710523Z","steps":["trace[1175408166] 'agreement among raft nodes before linearized reading' (duration: 182.41718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:50.950085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.064192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:50.95021Z","caller":"traceutil/trace.go:171","msg":"trace[932791648] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"172.523684ms","start":"2026-09-18T02:21:50.777664Z","end":"2026-09-18T02:21:50.950188Z","steps":["trace[932791648] 'read index received' (duration: 64.661958ms)","trace[932791648] 'applied index is now lower than readState.Index' (duration: 107.859926ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:50.950434Z","caller":"traceutil/trace.go:171","msg":"trace[1894989651] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"197.877796ms","start":"2026-09-18T02:21:50.752543Z","end":"2026-09-18T02:21:50.95042Z","steps":["trace[1894989651] 'process raft request' (duration: 89.857269ms)","trace[1894989651] 'compare' (duration: 106.92193ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:50.950963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.291485ms","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-18T02:21:50.951Z","caller":"traceutil/trace.go:171","msg":"trace[976570178] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:327; }","duration":"173.362137ms","start":"2026-09-18T02:21:50.777627Z","end":"2026-09-18T02:21:50.95099Z","steps":["trace[976570178] 'agreement among raft nodes before linearized reading' (duration: 172.609336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:51.255688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.77773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:51.255829Z","caller":"traceutil/trace.go:171","msg":"trace[1660365564] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"301.294354ms","start":"2026-09-18T02:21:50.954512Z","end":"2026-09-18T02:21:51.255806Z","steps":["trace[1660365564] 'process raft request' (duration: 95.318961ms)","trace[1660365564] 'compare' (duration: 205.590437ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:51.255904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:50.954484Z","time spent":"301.397495ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:21:51.274367Z","caller":"traceutil/trace.go:171","msg":"trace[1863733656] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"312.957497ms","start":"2026-09-18T02:21:50.961388Z","end":"2026-09-18T02:21:51.274346Z","steps":["trace[1863733656] 'process raft request' (duration: 312.766214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:51.274515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:50.961369Z","time spent":"313.059138ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:51.481491Z","caller":"traceutil/trace.go:171","msg":"trace[1458975816] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"123.340138ms","start":"2026-09-18T02:21:51.35812Z","end":"2026-09-18T02:21:51.48146Z","steps":["trace[1458975816] 'read index received' (duration: 85.324358ms)","trace[1458975816] 'applied index is now lower than readState.Index' (duration: 38.01444ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:51.481587Z","caller":"traceutil/trace.go:171","msg":"trace[1834251074] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"124.981264ms","start":"2026-09-18T02:21:51.356575Z","end":"2026-09-18T02:21:51.481556Z","steps":["trace[1834251074] 'process raft request' (duration: 86.870953ms)","trace[1834251074] 'compare' (duration: 37.874528ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:51.481647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.500431ms","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-18T02:21:51.481696Z","caller":"traceutil/trace.go:171","msg":"trace[79686215] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:332; }","duration":"123.579652ms","start":"2026-09-18T02:21:51.358105Z","end":"2026-09-18T02:21:51.481685Z","steps":["trace[79686215] 'agreement among raft nodes before linearized reading' (duration: 123.461411ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:51.802194Z","caller":"traceutil/trace.go:171","msg":"trace[2050704836] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"155.04932ms","start":"2026-09-18T02:21:51.647119Z","end":"2026-09-18T02:21:51.802169Z","steps":["trace[2050704836] 'read index received' (duration: 154.948568ms)","trace[2050704836] 'applied index is now lower than readState.Index' (duration: 99.442µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:51.80246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.313584ms","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-18T02:21:51.802502Z","caller":"traceutil/trace.go:171","msg":"trace[1844667519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:335; }","duration":"155.413845ms","start":"2026-09-18T02:21:51.647077Z","end":"2026-09-18T02:21:51.802491Z","steps":["trace[1844667519] 'agreement among raft nodes before linearized reading' (duration: 155.257422ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:51.802284Z","caller":"traceutil/trace.go:171","msg":"trace[916631931] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"173.762065ms","start":"2026-09-18T02:21:51.628489Z","end":"2026-09-18T02:21:51.802251Z","steps":["trace[916631931] 'process raft request' (duration: 173.527682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:52.839316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.8843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:52.839443Z","caller":"traceutil/trace.go:171","msg":"trace[826858482] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"611.339346ms","start":"2026-09-18T02:21:52.228085Z","end":"2026-09-18T02:21:52.839424Z","steps":["trace[826858482] 'read index received' (duration: 321.320934ms)","trace[826858482] 'applied index is now lower than readState.Index' (duration: 290.016122ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:52.839531Z","caller":"traceutil/trace.go:171","msg":"trace[82549508] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"626.936811ms","start":"2026-09-18T02:21:52.212584Z","end":"2026-09-18T02:21:52.839521Z","steps":["trace[82549508] 'process raft request' (duration: 336.759026ms)","trace[82549508] 'compare' (duration: 289.737788ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:52.839592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.212547Z","time spent":"627.017722ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:52.839737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.364392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-09-18T02:21:52.839811Z","caller":"traceutil/trace.go:171","msg":"trace[244174418] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:345; }","duration":"561.530234ms","start":"2026-09-18T02:21:52.278261Z","end":"2026-09-18T02:21:52.839792Z","steps":["trace[244174418] 'agreement among raft nodes before linearized reading' (duration: 561.330101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:52.839866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.278238Z","time spent":"561.619666ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-09-18T02:21:52.839946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.780788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-18T02:21:52.839997Z","caller":"traceutil/trace.go:171","msg":"trace[517820899] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:345; }","duration":"361.85445ms","start":"2026-09-18T02:21:52.478129Z","end":"2026-09-18T02:21:52.839983Z","steps":["trace[517820899] 'agreement among raft nodes before linearized reading' (duration: 361.766988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:52.840027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.478119Z","time spent":"361.90067ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-09-18T02:21:52.840069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.623451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-18T02:21:52.840133Z","caller":"traceutil/trace.go:171","msg":"trace[1838026922] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:345; }","duration":"311.743313ms","start":"2026-09-18T02:21:52.528378Z","end":"2026-09-18T02:21:52.840122Z","steps":["trace[1838026922] 'agreement among raft nodes before linearized reading' (duration: 311.633541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:52.840136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.467949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-18T02:21:52.840164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.528353Z","time spent":"311.803963ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-09-18T02:21:52.840199Z","caller":"traceutil/trace.go:171","msg":"trace[1368058204] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"411.562961ms","start":"2026-09-18T02:21:52.428617Z","end":"2026-09-18T02:21:52.84018Z","steps":["trace[1368058204] 'agreement among raft nodes before linearized reading' (duration: 411.463969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:52.840196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.607795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-09-18T02:21:52.840278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.192649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-09-18T02:21:52.840249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.428577Z","time spent":"411.659531ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-09-18T02:21:52.840306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.522063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-09-18T02:21:52.840352Z","caller":"traceutil/trace.go:171","msg":"trace[764930567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:345; }","duration":"461.602176ms","start":"2026-09-18T02:21:52.378737Z","end":"2026-09-18T02:21:52.840339Z","steps":["trace[764930567] 'agreement among raft nodes before linearized reading' (duration: 461.534474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:52.840395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.378712Z","time spent":"461.673727ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-09-18T02:21:52.840399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.19925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-09-18T02:21:52.840389Z","caller":"traceutil/trace.go:171","msg":"trace[1342886479] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:345; }","duration":"612.261079ms","start":"2026-09-18T02:21:52.228045Z","end":"2026-09-18T02:21:52.840306Z","steps":["trace[1342886479] 'agreement among raft nodes before linearized reading' (duration: 612.199139ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:52.840461Z","caller":"traceutil/trace.go:171","msg":"trace[1122943138] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:345; }","duration":"512.286701ms","start":"2026-09-18T02:21:52.328146Z","end":"2026-09-18T02:21:52.840433Z","steps":["trace[1122943138] 'agreement among raft nodes before linearized reading' (duration: 512.20718ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:52.840341Z","caller":"traceutil/trace.go:171","msg":"trace[240172243] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:345; }","duration":"261.806299ms","start":"2026-09-18T02:21:52.578497Z","end":"2026-09-18T02:21:52.840304Z","steps":["trace[240172243] 'agreement among raft nodes before linearized reading' (duration: 261.499565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:52.840476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.228023Z","time spent":"612.435104ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-09-18T02:21:52.84051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.328111Z","time spent":"512.388382ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-09-18T02:21:52.968313Z","caller":"traceutil/trace.go:171","msg":"trace[390431912] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"114.838813ms","start":"2026-09-18T02:21:52.853448Z","end":"2026-09-18T02:21:52.968287Z","steps":["trace[390431912] 'process raft request' (duration: 101.044236ms)","trace[390431912] 'compare' (duration: 13.500433ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:52.968531Z","caller":"traceutil/trace.go:171","msg":"trace[555085981] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"113.540733ms","start":"2026-09-18T02:21:52.854981Z","end":"2026-09-18T02:21:52.968522Z","steps":["trace[555085981] 'process raft request' (duration: 113.228998ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:52.96891Z","caller":"traceutil/trace.go:171","msg":"trace[1492226018] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"113.750795ms","start":"2026-09-18T02:21:52.855062Z","end":"2026-09-18T02:21:52.968813Z","steps":["trace[1492226018] 'process raft request' (duration: 113.213327ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:52.969133Z","caller":"traceutil/trace.go:171","msg":"trace[1688657999] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"113.985679ms","start":"2026-09-18T02:21:52.855101Z","end":"2026-09-18T02:21:52.969087Z","steps":["trace[1688657999] 'process raft request' (duration: 113.562433ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:52.969336Z","caller":"traceutil/trace.go:171","msg":"trace[682419611] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"113.355469ms","start":"2026-09-18T02:21:52.855958Z","end":"2026-09-18T02:21:52.969314Z","steps":["trace[682419611] 'process raft request' (duration: 113.082904ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:52.969467Z","caller":"traceutil/trace.go:171","msg":"trace[1172194322] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"111.859477ms","start":"2026-09-18T02:21:52.857594Z","end":"2026-09-18T02:21:52.969454Z","steps":["trace[1172194322] 'process raft request' (duration: 111.49664ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:52.969635Z","caller":"traceutil/trace.go:171","msg":"trace[1600435873] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"113.9819ms","start":"2026-09-18T02:21:52.855625Z","end":"2026-09-18T02:21:52.969607Z","steps":["trace[1600435873] 'process raft request' (duration: 113.37058ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:52.969656Z","caller":"traceutil/trace.go:171","msg":"trace[1398809338] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"114.479847ms","start":"2026-09-18T02:21:52.855161Z","end":"2026-09-18T02:21:52.969641Z","steps":["trace[1398809338] 'process raft request' (duration: 113.796436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.368264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.995488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:53.368724Z","caller":"traceutil/trace.go:171","msg":"trace[916807179] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"388.293479ms","start":"2026-09-18T02:21:52.980398Z","end":"2026-09-18T02:21:53.368692Z","steps":["trace[916807179] 'process raft request' (duration: 388.158327ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:53.368724Z","caller":"traceutil/trace.go:171","msg":"trace[1211610496] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"388.397491ms","start":"2026-09-18T02:21:52.980279Z","end":"2026-09-18T02:21:53.368676Z","steps":["trace[1211610496] 'process raft request' (duration: 388.146487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.369014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.98024Z","time spent":"388.577994ms","remote":"127.0.0.1:43842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:53.369068Z","caller":"traceutil/trace.go:171","msg":"trace[1187098936] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"388.753067ms","start":"2026-09-18T02:21:52.980301Z","end":"2026-09-18T02:21:53.369054Z","steps":["trace[1187098936] 'process raft request' (duration: 388.211049ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:53.369064Z","caller":"traceutil/trace.go:171","msg":"trace[1911650512] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"385.616817ms","start":"2026-09-18T02:21:52.983432Z","end":"2026-09-18T02:21:53.369049Z","steps":["trace[1911650512] 'process raft request' (duration: 385.479155ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:53.36913Z","caller":"traceutil/trace.go:171","msg":"trace[1307123758] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"387.205322ms","start":"2026-09-18T02:21:52.981914Z","end":"2026-09-18T02:21:53.369119Z","steps":["trace[1307123758] 'process raft request' (duration: 386.779015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.369201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.981898Z","time spent":"387.265722ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:53.369121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.980287Z","time spent":"388.812618ms","remote":"127.0.0.1:43698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:53.369284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.983415Z","time spent":"385.700938ms","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:53.368745Z","caller":"traceutil/trace.go:171","msg":"trace[1819814250] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"387.075471ms","start":"2026-09-18T02:21:52.981653Z","end":"2026-09-18T02:21:53.368728Z","steps":["trace[1819814250] 'process raft request' (duration: 386.965419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.369571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.981631Z","time spent":"387.902753ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:53.369019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.980349Z","time spent":"388.437641ms","remote":"127.0.0.1:43698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:53.368567Z","caller":"traceutil/trace.go:171","msg":"trace[971528585] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"389.650462ms","start":"2026-09-18T02:21:52.978866Z","end":"2026-09-18T02:21:53.368517Z","steps":["trace[971528585] 'process raft request' (duration: 250.309408ms)","trace[971528585] 'compare' (duration: 138.828255ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:53.370189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:52.978811Z","time spent":"391.331977ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:21:53.408005Z","caller":"traceutil/trace.go:171","msg":"trace[50888488] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:369; }","duration":"397.409959ms","start":"2026-09-18T02:21:53.010568Z","end":"2026-09-18T02:21:53.407978Z","steps":["trace[50888488] 'read index received' (duration: 218.572689ms)","trace[50888488] 'applied index is now lower than readState.Index' (duration: 178.83594ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:53.408131Z","caller":"traceutil/trace.go:171","msg":"trace[1933307609] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"397.708354ms","start":"2026-09-18T02:21:53.01041Z","end":"2026-09-18T02:21:53.408118Z","steps":["trace[1933307609] 'process raft request' (duration: 397.244728ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:53.408232Z","caller":"traceutil/trace.go:171","msg":"trace[1268079493] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"396.696758ms","start":"2026-09-18T02:21:53.011516Z","end":"2026-09-18T02:21:53.408212Z","steps":["trace[1268079493] 'process raft request' (duration: 396.385023ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:53.408341Z","caller":"traceutil/trace.go:171","msg":"trace[343278090] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"397.43833ms","start":"2026-09-18T02:21:53.010894Z","end":"2026-09-18T02:21:53.408332Z","steps":["trace[343278090] 'process raft request' (duration: 396.923742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.408394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.010867Z","time spent":"397.501821ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:53.408278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.010382Z","time spent":"397.777965ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:53.408475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.01149Z","time spent":"396.87132ms","remote":"127.0.0.1:44050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:53.408564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.921727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-18T02:21:53.408677Z","caller":"traceutil/trace.go:171","msg":"trace[969824663] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:365; }","duration":"398.14414ms","start":"2026-09-18T02:21:53.01051Z","end":"2026-09-18T02:21:53.408654Z","steps":["trace[969824663] 'agreement among raft nodes before linearized reading' (duration: 397.799865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.408752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.010487Z","time spent":"398.252172ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-09-18T02:21:53.920959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.793268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:53.92113Z","caller":"traceutil/trace.go:171","msg":"trace[1777396131] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"513.033483ms","start":"2026-09-18T02:21:53.40808Z","end":"2026-09-18T02:21:53.921114Z","steps":["trace[1777396131] 'read index received' (duration: 195.023552ms)","trace[1777396131] 'applied index is now lower than readState.Index' (duration: 318.007211ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:53.921176Z","caller":"traceutil/trace.go:171","msg":"trace[94569111] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"540.489842ms","start":"2026-09-18T02:21:53.380669Z","end":"2026-09-18T02:21:53.921159Z","steps":["trace[94569111] 'process raft request' (duration: 540.38145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.921271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.956535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"warn","ts":"2026-09-18T02:21:53.921275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.380648Z","time spent":"540.569623ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:53.921333Z","caller":"traceutil/trace.go:171","msg":"trace[677501121] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:367; }","duration":"542.034807ms","start":"2026-09-18T02:21:53.379259Z","end":"2026-09-18T02:21:53.921294Z","steps":["trace[677501121] 'agreement among raft nodes before linearized reading' (duration: 541.923774ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:53.921335Z","caller":"traceutil/trace.go:171","msg":"trace[236881391] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"546.307543ms","start":"2026-09-18T02:21:53.375007Z","end":"2026-09-18T02:21:53.921314Z","steps":["trace[236881391] 'process raft request' (duration: 228.089888ms)","trace[236881391] 'compare' (duration: 317.588625ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:53.921443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.374981Z","time spent":"546.420735ms","remote":"127.0.0.1:43698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:53.921486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.379236Z","time spent":"542.124168ms","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3729,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-09-18T02:21:53.959312Z","caller":"traceutil/trace.go:171","msg":"trace[1419510706] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"539.337074ms","start":"2026-09-18T02:21:53.419951Z","end":"2026-09-18T02:21:53.959288Z","steps":["trace[1419510706] 'process raft request' (duration: 539.291454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.959412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.271142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-lv4m7\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-09-18T02:21:53.959473Z","caller":"traceutil/trace.go:171","msg":"trace[433738985] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"544.912801ms","start":"2026-09-18T02:21:53.414551Z","end":"2026-09-18T02:21:53.959464Z","steps":["trace[433738985] 'process raft request' (duration: 544.563206ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:53.959487Z","caller":"traceutil/trace.go:171","msg":"trace[309273055] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-lv4m7; range_end:; response_count:1; response_revision:369; }","duration":"546.406514ms","start":"2026-09-18T02:21:53.413068Z","end":"2026-09-18T02:21:53.959474Z","steps":["trace[309273055] 'agreement among raft nodes before linearized reading' (duration: 546.240112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.959512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.414534Z","time spent":"544.958871ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:53.959527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.413043Z","time spent":"546.476555ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3462,"request content":"key:\"/registry/pods/kube-system/kube-proxy-lv4m7\" "} {"level":"warn","ts":"2026-09-18T02:21:53.959514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.790483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-09-18T02:21:53.959584Z","caller":"traceutil/trace.go:171","msg":"trace[2034287874] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:369; }","duration":"359.913534ms","start":"2026-09-18T02:21:53.599654Z","end":"2026-09-18T02:21:53.959567Z","steps":["trace[2034287874] 'agreement among raft nodes before linearized reading' (duration: 359.759432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.959625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.599627Z","time spent":"359.989316ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-09-18T02:21:53.959668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.176412ms","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-18T02:21:53.959756Z","caller":"traceutil/trace.go:171","msg":"trace[1130702856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"473.303033ms","start":"2026-09-18T02:21:53.486439Z","end":"2026-09-18T02:21:53.959742Z","steps":["trace[1130702856] 'agreement among raft nodes before linearized reading' (duration: 473.193141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:53.95979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.486414Z","time spent":"473.368605ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T02:21:53.959439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.419936Z","time spent":"539.449586ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:54.462645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":821520669779414084,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T02:21:54.568779Z","caller":"traceutil/trace.go:171","msg":"trace[1292520641] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"638.64627ms","start":"2026-09-18T02:21:53.930105Z","end":"2026-09-18T02:21:54.568751Z","steps":["trace[1292520641] 'process raft request' (duration: 638.553749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:54.569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.930092Z","time spent":"638.808292ms","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:54.569336Z","caller":"traceutil/trace.go:171","msg":"trace[535068956] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"642.363848ms","start":"2026-09-18T02:21:53.926961Z","end":"2026-09-18T02:21:54.569324Z","steps":["trace[535068956] 'process raft request' (duration: 634.494326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:54.569402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.926937Z","time spent":"642.429748ms","remote":"127.0.0.1:43698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:21:54.78337Z","caller":"traceutil/trace.go:171","msg":"trace[1800952752] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"818.10713ms","start":"2026-09-18T02:21:53.965237Z","end":"2026-09-18T02:21:54.783344Z","steps":["trace[1800952752] 'process raft request' (duration: 818.045209ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:54.783365Z","caller":"traceutil/trace.go:171","msg":"trace[1210172793] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"818.461975ms","start":"2026-09-18T02:21:53.96485Z","end":"2026-09-18T02:21:54.783312Z","steps":["trace[1210172793] 'process raft request' (duration: 764.292446ms)","trace[1210172793] 'compare' (duration: 53.622831ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:54.783519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.965206Z","time spent":"818.239762ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:54.783743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.964802Z","time spent":"818.669019ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:21:54.783764Z","caller":"traceutil/trace.go:171","msg":"trace[130025448] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"817.800046ms","start":"2026-09-18T02:21:53.965934Z","end":"2026-09-18T02:21:54.783734Z","steps":["trace[130025448] 'process raft request' (duration: 817.383049ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:54.784224Z","caller":"traceutil/trace.go:171","msg":"trace[451483701] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"819.192756ms","start":"2026-09-18T02:21:53.965013Z","end":"2026-09-18T02:21:54.784206Z","steps":["trace[451483701] 'process raft request' (duration: 818.208141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:54.784318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.965002Z","time spent":"819.269488ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:54.784428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.965917Z","time spent":"818.233933ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:54.7847Z","caller":"traceutil/trace.go:171","msg":"trace[355461107] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"818.531646ms","start":"2026-09-18T02:21:53.966144Z","end":"2026-09-18T02:21:54.784675Z","steps":["trace[355461107] 'process raft request' (duration: 817.321497ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:54.784915Z","caller":"traceutil/trace.go:171","msg":"trace[2036652220] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"818.545286ms","start":"2026-09-18T02:21:53.966328Z","end":"2026-09-18T02:21:54.784873Z","steps":["trace[2036652220] 'process raft request' (duration: 818.003248ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:54.784978Z","caller":"traceutil/trace.go:171","msg":"trace[532879405] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"812.016166ms","start":"2026-09-18T02:21:53.972954Z","end":"2026-09-18T02:21:54.78497Z","steps":["trace[532879405] 'process raft request' (duration: 811.794592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:54.784929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.966131Z","time spent":"818.722449ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:54.785051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.966317Z","time spent":"818.670248ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:54.785121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.972937Z","time spent":"812.143377ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4447,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:54.785241Z","caller":"traceutil/trace.go:171","msg":"trace[1561081047] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:383; }","duration":"824.237035ms","start":"2026-09-18T02:21:53.960988Z","end":"2026-09-18T02:21:54.785225Z","steps":["trace[1561081047] 'read index received' (duration: 600.510466ms)","trace[1561081047] 'applied index is now lower than readState.Index' (duration: 223.724979ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:54.785426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.174228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T02:21:54.785555Z","caller":"traceutil/trace.go:171","msg":"trace[427761289] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"810.592483ms","start":"2026-09-18T02:21:53.974949Z","end":"2026-09-18T02:21:54.785542Z","steps":["trace[427761289] 'process raft request' (duration: 809.991463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:54.785384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.383158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-lv4m7\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-09-18T02:21:54.785613Z","caller":"traceutil/trace.go:171","msg":"trace[1633891901] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-lv4m7; range_end:; response_count:1; response_revision:379; }","duration":"824.645372ms","start":"2026-09-18T02:21:53.960952Z","end":"2026-09-18T02:21:54.785598Z","steps":["trace[1633891901] 'agreement among raft nodes before linearized reading' (duration: 824.380748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:54.785638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.974937Z","time spent":"810.653344ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:54.785648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:53.960935Z","time spent":"824.701903ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4485,"request content":"key:\"/registry/pods/kube-system/kube-proxy-lv4m7\" "} {"level":"info","ts":"2026-09-18T02:21:54.786179Z","caller":"traceutil/trace.go:171","msg":"trace[1285834182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:379; }","duration":"720.264799ms","start":"2026-09-18T02:21:54.065203Z","end":"2026-09-18T02:21:54.785468Z","steps":["trace[1285834182] 'agreement among raft nodes before linearized reading' (duration: 720.138837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:54.786246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:54.065167Z","time spent":"721.061881ms","remote":"127.0.0.1:43848","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-18T02:21:55.012762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.617105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:55.012898Z","caller":"traceutil/trace.go:171","msg":"trace[135279045] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"159.955809ms","start":"2026-09-18T02:21:54.85292Z","end":"2026-09-18T02:21:55.012876Z","steps":["trace[135279045] 'process raft request' (duration: 38.135561ms)","trace[135279045] 'compare' (duration: 121.499153ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:56.032558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.097313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:56.032666Z","caller":"traceutil/trace.go:171","msg":"trace[542564240] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"430.067478ms","start":"2026-09-18T02:21:55.602576Z","end":"2026-09-18T02:21:56.032644Z","steps":["trace[542564240] 'process raft request' (duration: 118.816622ms)","trace[542564240] 'compare' (duration: 310.966131ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:56.032734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:55.602554Z","time spent":"430.15051ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:56.956884Z","caller":"traceutil/trace.go:171","msg":"trace[731458074] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"164.555623ms","start":"2026-09-18T02:21:56.792288Z","end":"2026-09-18T02:21:56.956844Z","steps":["trace[731458074] 'read index received' (duration: 164.107175ms)","trace[731458074] 'applied index is now lower than readState.Index' (duration: 446.538µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:56.956993Z","caller":"traceutil/trace.go:171","msg":"trace[1646071109] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"824.055082ms","start":"2026-09-18T02:21:56.13291Z","end":"2026-09-18T02:21:56.956965Z","steps":["trace[1646071109] 'process raft request' (duration: 823.586235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:56.95715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.833807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-18T02:21:56.957193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:56.132883Z","time spent":"824.168774ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:56.957255Z","caller":"traceutil/trace.go:171","msg":"trace[1915329530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:390; }","duration":"164.995049ms","start":"2026-09-18T02:21:56.792231Z","end":"2026-09-18T02:21:56.957226Z","steps":["trace[1915329530] 'agreement among raft nodes before linearized reading' (duration: 164.822857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:56.957284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.719425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-18T02:21:56.957285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.117583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T02:21:56.957421Z","caller":"traceutil/trace.go:171","msg":"trace[129416045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"109.286795ms","start":"2026-09-18T02:21:56.84812Z","end":"2026-09-18T02:21:56.957407Z","steps":["trace[129416045] 'agreement among raft nodes before linearized reading' (duration: 109.030431ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:56.957351Z","caller":"traceutil/trace.go:171","msg":"trace[325111044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"164.786516ms","start":"2026-09-18T02:21:56.792545Z","end":"2026-09-18T02:21:56.957332Z","steps":["trace[325111044] 'agreement among raft nodes before linearized reading' (duration: 164.667374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:56.957574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.02105ms","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-18T02:21:56.958087Z","caller":"traceutil/trace.go:171","msg":"trace[1440397154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"165.72396ms","start":"2026-09-18T02:21:56.792344Z","end":"2026-09-18T02:21:56.958068Z","steps":["trace[1440397154] 'agreement among raft nodes before linearized reading' (duration: 164.746935ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:57.191305Z","caller":"traceutil/trace.go:171","msg":"trace[1079471328] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"228.078081ms","start":"2026-09-18T02:21:56.963202Z","end":"2026-09-18T02:21:57.19128Z","steps":["trace[1079471328] 'process raft request' (duration: 176.738978ms)","trace[1079471328] 'compare' (duration: 51.200321ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:57.240765Z","caller":"traceutil/trace.go:171","msg":"trace[401985384] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:404; }","duration":"198.474824ms","start":"2026-09-18T02:21:57.042258Z","end":"2026-09-18T02:21:57.240733Z","steps":["trace[401985384] 'read index received' (duration: 97.705288ms)","trace[401985384] 'applied index is now lower than readState.Index' (duration: 100.768886ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:57.240891Z","caller":"traceutil/trace.go:171","msg":"trace[265190964] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"276.02754ms","start":"2026-09-18T02:21:56.964849Z","end":"2026-09-18T02:21:57.240876Z","steps":["trace[265190964] 'process raft request' (duration: 275.823697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:57.241006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.73131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.59\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-18T02:21:57.241052Z","caller":"traceutil/trace.go:171","msg":"trace[789488060] range","detail":"{range_begin:/registry/masterleases/162.253.55.59; range_end:; response_count:1; response_revision:394; }","duration":"198.842501ms","start":"2026-09-18T02:21:57.0422Z","end":"2026-09-18T02:21:57.241043Z","steps":["trace[789488060] 'agreement among raft nodes before linearized reading' (duration: 198.704488ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:57.241113Z","caller":"traceutil/trace.go:171","msg":"trace[124360235] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"275.194078ms","start":"2026-09-18T02:21:56.965898Z","end":"2026-09-18T02:21:57.241093Z","steps":["trace[124360235] 'process raft request' (duration: 274.809982ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:57.241504Z","caller":"traceutil/trace.go:171","msg":"trace[440205532] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"276.658501ms","start":"2026-09-18T02:21:56.964701Z","end":"2026-09-18T02:21:57.241359Z","steps":["trace[440205532] 'process raft request' (duration: 275.837318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:58.093209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.766159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:58.093418Z","caller":"traceutil/trace.go:171","msg":"trace[167005969] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:409; }","duration":"321.066237ms","start":"2026-09-18T02:21:57.77233Z","end":"2026-09-18T02:21:58.093397Z","steps":["trace[167005969] 'read index received' (duration: 287.314105ms)","trace[167005969] 'applied index is now lower than readState.Index' (duration: 33.750762ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:58.093499Z","caller":"traceutil/trace.go:171","msg":"trace[2084320309] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"806.125721ms","start":"2026-09-18T02:21:57.287322Z","end":"2026-09-18T02:21:58.093448Z","steps":["trace[2084320309] 'process raft request' (duration: 114.011527ms)","trace[2084320309] 'compare' (duration: 691.633207ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:58.093547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.209549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-09-18T02:21:58.093598Z","caller":"traceutil/trace.go:171","msg":"trace[1760903160] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:396; }","duration":"321.30036ms","start":"2026-09-18T02:21:57.772285Z","end":"2026-09-18T02:21:58.093586Z","steps":["trace[1760903160] 'agreement among raft nodes before linearized reading' (duration: 321.177888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:58.093657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:57.772264Z","time spent":"321.382323ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-18T02:21:58.093578Z","caller":"traceutil/trace.go:171","msg":"trace[1544735406] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"620.34904ms","start":"2026-09-18T02:21:57.473173Z","end":"2026-09-18T02:21:58.093522Z","steps":["trace[1544735406] 'process raft request' (duration: 620.156487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:58.09374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:57.287283Z","time spent":"806.317523ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:58.09389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:57.473135Z","time spent":"620.625194ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:21:58.852263Z","caller":"traceutil/trace.go:171","msg":"trace[986197594] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"148.015824ms","start":"2026-09-18T02:21:58.704207Z","end":"2026-09-18T02:21:58.852223Z","steps":["trace[986197594] 'process raft request' (duration: 147.74106ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:59.312808Z","caller":"traceutil/trace.go:171","msg":"trace[1316341373] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"117.603914ms","start":"2026-09-18T02:21:59.195161Z","end":"2026-09-18T02:21:59.312765Z","steps":["trace[1316341373] 'read index received' (duration: 117.37003ms)","trace[1316341373] 'applied index is now lower than readState.Index' (duration: 232.823µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:59.312847Z","caller":"traceutil/trace.go:171","msg":"trace[413407224] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"163.958202ms","start":"2026-09-18T02:21:59.148866Z","end":"2026-09-18T02:21:59.312824Z","steps":["trace[413407224] 'process raft request' (duration: 163.733989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:59.312966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.783226ms","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-18T02:21:59.31301Z","caller":"traceutil/trace.go:171","msg":"trace[1029088189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:400; }","duration":"117.876808ms","start":"2026-09-18T02:21:59.19512Z","end":"2026-09-18T02:21:59.312996Z","steps":["trace[1029088189] 'agreement among raft nodes before linearized reading' (duration: 117.787327ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:59.534704Z","caller":"traceutil/trace.go:171","msg":"trace[131763100] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"221.799506ms","start":"2026-09-18T02:21:59.31288Z","end":"2026-09-18T02:21:59.53468Z","steps":["trace[131763100] 'read index received' (duration: 136.993136ms)","trace[131763100] 'applied index is now lower than readState.Index' (duration: 84.8054ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:59.534719Z","caller":"traceutil/trace.go:171","msg":"trace[363829832] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"223.732424ms","start":"2026-09-18T02:21:59.310963Z","end":"2026-09-18T02:21:59.534696Z","steps":["trace[363829832] 'process raft request' (duration: 138.896744ms)","trace[363829832] 'compare' (duration: 84.677158ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:59.534866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.624258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-18T02:21:59.535109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.040874ms","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-18T02:21:59.53491Z","caller":"traceutil/trace.go:171","msg":"trace[825727000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"289.675629ms","start":"2026-09-18T02:21:59.245218Z","end":"2026-09-18T02:21:59.534893Z","steps":["trace[825727000] 'agreement among raft nodes before linearized reading' (duration: 289.595348ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:59.535154Z","caller":"traceutil/trace.go:171","msg":"trace[324547187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"290.113705ms","start":"2026-09-18T02:21:59.24503Z","end":"2026-09-18T02:21:59.535143Z","steps":["trace[324547187] 'agreement among raft nodes before linearized reading' (duration: 290.037844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:59.535085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.965523ms","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-18T02:21:59.535326Z","caller":"traceutil/trace.go:171","msg":"trace[526631538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"290.174456ms","start":"2026-09-18T02:21:59.245079Z","end":"2026-09-18T02:21:59.535253Z","steps":["trace[526631538] 'agreement among raft nodes before linearized reading' (duration: 289.934813ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:59.541974Z","caller":"traceutil/trace.go:171","msg":"trace[1677016971] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"219.455689ms","start":"2026-09-18T02:21:59.322499Z","end":"2026-09-18T02:21:59.541954Z","steps":["trace[1677016971] 'process raft request' (duration: 219.254656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:59.541995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.43419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-lv4m7\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-09-18T02:21:59.542046Z","caller":"traceutil/trace.go:171","msg":"trace[1594722780] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-lv4m7; range_end:; response_count:1; response_revision:402; }","duration":"219.52646ms","start":"2026-09-18T02:21:59.322503Z","end":"2026-09-18T02:21:59.54203Z","steps":["trace[1594722780] 'agreement among raft nodes before linearized reading' (duration: 219.372568ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:59.836928Z","caller":"traceutil/trace.go:171","msg":"trace[814974468] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"199.56895ms","start":"2026-09-18T02:21:59.637327Z","end":"2026-09-18T02:21:59.836896Z","steps":["trace[814974468] 'process raft request' (duration: 151.574465ms)","trace[814974468] 'compare' (duration: 47.804041ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:00.082856Z","caller":"traceutil/trace.go:171","msg":"trace[1813069861] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"264.542373ms","start":"2026-09-18T02:21:59.818293Z","end":"2026-09-18T02:22:00.082836Z","steps":["trace[1813069861] 'read index received' (duration: 230.414722ms)","trace[1813069861] 'applied index is now lower than readState.Index' (duration: 34.127111ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:00.082934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.625254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:22:00.082952Z","caller":"traceutil/trace.go:171","msg":"trace[1473125367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"264.680366ms","start":"2026-09-18T02:21:59.818264Z","end":"2026-09-18T02:22:00.082945Z","steps":["trace[1473125367] 'agreement among raft nodes before linearized reading' (duration: 264.634785ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:00.083067Z","caller":"traceutil/trace.go:171","msg":"trace[716642269] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"439.190446ms","start":"2026-09-18T02:21:59.643869Z","end":"2026-09-18T02:22:00.083059Z","steps":["trace[716642269] 'process raft request' (duration: 404.999624ms)","trace[716642269] 'compare' (duration: 33.871368ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:00.083123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:59.643841Z","time spent":"439.243428ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:00.368961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.023187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:22:00.369047Z","caller":"traceutil/trace.go:171","msg":"trace[2111136225] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"270.278275ms","start":"2026-09-18T02:22:00.098755Z","end":"2026-09-18T02:22:00.369033Z","steps":["trace[2111136225] 'process raft request' (duration: 103.122956ms)","trace[2111136225] 'compare' (duration: 166.929115ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:00.793459Z","caller":"traceutil/trace.go:171","msg":"trace[980482702] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"322.578191ms","start":"2026-09-18T02:22:00.470859Z","end":"2026-09-18T02:22:00.793437Z","steps":["trace[980482702] 'process raft request' (duration: 322.428829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:00.7936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:00.470834Z","time spent":"322.695642ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:22:01.708425Z","caller":"traceutil/trace.go:171","msg":"trace[788104031] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"116.361267ms","start":"2026-09-18T02:22:01.592041Z","end":"2026-09-18T02:22:01.708402Z","steps":["trace[788104031] 'process raft request' (duration: 116.298297ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:01.708479Z","caller":"traceutil/trace.go:171","msg":"trace[239006582] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"117.389222ms","start":"2026-09-18T02:22:01.591063Z","end":"2026-09-18T02:22:01.708452Z","steps":["trace[239006582] 'process raft request' (duration: 95.589367ms)","trace[239006582] 'compare' (duration: 21.517201ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:09.995528Z","caller":"traceutil/trace.go:171","msg":"trace[1964143818] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"393.431149ms","start":"2026-09-18T02:22:09.602056Z","end":"2026-09-18T02:22:09.995487Z","steps":["trace[1964143818] 'process raft request' (duration: 393.109154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:09.995687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:09.602032Z","time spent":"393.575292ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:09.995765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.450087ms","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-18T02:22:09.995867Z","caller":"traceutil/trace.go:171","msg":"trace[24854336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"220.650859ms","start":"2026-09-18T02:22:09.775195Z","end":"2026-09-18T02:22:09.995845Z","steps":["trace[24854336] 'agreement among raft nodes before linearized reading' (duration: 220.409505ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:09.996016Z","caller":"traceutil/trace.go:171","msg":"trace[867968037] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"220.140982ms","start":"2026-09-18T02:22:09.77525Z","end":"2026-09-18T02:22:09.995391Z","steps":["trace[867968037] 'read index received' (duration: 219.776227ms)","trace[867968037] 'applied index is now lower than readState.Index' (duration: 363.475µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:09.996184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.105588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:22:09.996218Z","caller":"traceutil/trace.go:171","msg":"trace[437960941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"176.175229ms","start":"2026-09-18T02:22:09.820034Z","end":"2026-09-18T02:22:09.996209Z","steps":["trace[437960941] 'agreement among raft nodes before linearized reading' (duration: 176.098718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:09.996229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.694043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-18T02:22:09.996233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.592911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T02:22:09.99626Z","caller":"traceutil/trace.go:171","msg":"trace[464481068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"191.746273ms","start":"2026-09-18T02:22:09.804504Z","end":"2026-09-18T02:22:09.99625Z","steps":["trace[464481068] 'agreement among raft nodes before linearized reading' (duration: 191.692263ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:09.996285Z","caller":"traceutil/trace.go:171","msg":"trace[583810883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:430; }","duration":"191.690053ms","start":"2026-09-18T02:22:09.804578Z","end":"2026-09-18T02:22:09.996268Z","steps":["trace[583810883] 'agreement among raft nodes before linearized reading' (duration: 191.602031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:09.996434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.093995ms","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-18T02:22:09.996535Z","caller":"traceutil/trace.go:171","msg":"trace[859979943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"198.245757ms","start":"2026-09-18T02:22:09.798272Z","end":"2026-09-18T02:22:09.996518Z","steps":["trace[859979943] 'agreement among raft nodes before linearized reading' (duration: 197.855432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:10.189865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.171381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:22:10.190005Z","caller":"traceutil/trace.go:171","msg":"trace[1503776909] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"187.009295ms","start":"2026-09-18T02:22:10.00297Z","end":"2026-09-18T02:22:10.18998Z","steps":["trace[1503776909] 'process raft request' (duration: 67.586011ms)","trace[1503776909] 'compare' (duration: 118.998318ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:10.216521Z","caller":"traceutil/trace.go:171","msg":"trace[1652433009] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"207.714662ms","start":"2026-09-18T02:22:10.00877Z","end":"2026-09-18T02:22:10.216485Z","steps":["trace[1652433009] 'process raft request' (duration: 207.680361ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:10.216576Z","caller":"traceutil/trace.go:171","msg":"trace[1015816940] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"208.062557ms","start":"2026-09-18T02:22:10.008462Z","end":"2026-09-18T02:22:10.216524Z","steps":["trace[1015816940] 'process raft request' (duration: 207.782943ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:10.216718Z","caller":"traceutil/trace.go:171","msg":"trace[646350340] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"208.097807ms","start":"2026-09-18T02:22:10.008589Z","end":"2026-09-18T02:22:10.216687Z","steps":["trace[646350340] 'process raft request' (duration: 207.819364ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:18.539607Z","caller":"traceutil/trace.go:171","msg":"trace[567355280] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"109.004659ms","start":"2026-09-18T02:22:18.430572Z","end":"2026-09-18T02:22:18.539576Z","steps":["trace[567355280] 'process raft request' (duration: 103.102515ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:18.550341Z","caller":"traceutil/trace.go:171","msg":"trace[2098486971] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"119.203223ms","start":"2026-09-18T02:22:18.431117Z","end":"2026-09-18T02:22:18.55032Z","steps":["trace[2098486971] 'process raft request' (duration: 119.158751ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:18.550663Z","caller":"traceutil/trace.go:171","msg":"trace[1132110313] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"119.969788ms","start":"2026-09-18T02:22:18.430686Z","end":"2026-09-18T02:22:18.550655Z","steps":["trace[1132110313] 'process raft request' (duration: 119.485622ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:23.357745Z","caller":"traceutil/trace.go:171","msg":"trace[57729946] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"164.025726ms","start":"2026-09-18T02:22:23.193698Z","end":"2026-09-18T02:22:23.357723Z","steps":["trace[57729946] 'process raft request' (duration: 163.85124ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:23.358017Z","caller":"traceutil/trace.go:171","msg":"trace[833673701] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"163.914082ms","start":"2026-09-18T02:22:23.194085Z","end":"2026-09-18T02:22:23.357999Z","steps":["trace[833673701] 'process raft request' (duration: 163.58409ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:23.358155Z","caller":"traceutil/trace.go:171","msg":"trace[1827236468] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"164.082117ms","start":"2026-09-18T02:22:23.193974Z","end":"2026-09-18T02:22:23.358056Z","steps":["trace[1827236468] 'process raft request' (duration: 163.628762ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:23.357796Z","caller":"traceutil/trace.go:171","msg":"trace[1584222718] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"170.083167ms","start":"2026-09-18T02:22:23.187655Z","end":"2026-09-18T02:22:23.357738Z","steps":["trace[1584222718] 'process raft request' (duration: 105.211053ms)","trace[1584222718] 'compare' (duration: 64.533802ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:23.358594Z","caller":"traceutil/trace.go:171","msg":"trace[523285371] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"164.483361ms","start":"2026-09-18T02:22:23.194101Z","end":"2026-09-18T02:22:23.358584Z","steps":["trace[523285371] 'process raft request' (duration: 163.533339ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:26.313542Z","caller":"traceutil/trace.go:171","msg":"trace[1715271016] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"161.581952ms","start":"2026-09-18T02:22:26.151931Z","end":"2026-09-18T02:22:26.313513Z","steps":["trace[1715271016] 'process raft request' (duration: 161.374064ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:38.706604Z","caller":"traceutil/trace.go:171","msg":"trace[1747323269] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"571.239591ms","start":"2026-09-18T02:22:38.135305Z","end":"2026-09-18T02:22:38.706545Z","steps":["trace[1747323269] 'process raft request' (duration: 547.671358ms)","trace[1747323269] 'compare' (duration: 23.223143ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:38.706823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:38.135289Z","time spent":"571.441206ms","remote":"127.0.0.1:43632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:22:38.732638Z","caller":"traceutil/trace.go:171","msg":"trace[699984219] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"578.195926ms","start":"2026-09-18T02:22:38.154366Z","end":"2026-09-18T02:22:38.732562Z","steps":["trace[699984219] 'process raft request' (duration: 577.741813ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:38.732733Z","caller":"traceutil/trace.go:171","msg":"trace[373343467] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"556.351212ms","start":"2026-09-18T02:22:38.176348Z","end":"2026-09-18T02:22:38.7327Z","steps":["trace[373343467] 'process raft request' (duration: 556.084064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:38.732897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:38.176327Z","time spent":"556.474977ms","remote":"127.0.0.1:43632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:22:38.732993Z","caller":"traceutil/trace.go:171","msg":"trace[273619414] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"131.780559ms","start":"2026-09-18T02:22:38.601149Z","end":"2026-09-18T02:22:38.732929Z","steps":["trace[273619414] 'read index received' (duration: 81.88731ms)","trace[273619414] 'applied index is now lower than readState.Index' (duration: 49.892049ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:38.733018Z","caller":"traceutil/trace.go:171","msg":"trace[49789459] transaction","detail":"{read_only:false; number_of_response:1; response_revision:608; }","duration":"551.622593ms","start":"2026-09-18T02:22:38.18136Z","end":"2026-09-18T02:22:38.732983Z","steps":["trace[49789459] 'process raft request' (duration: 551.235491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:38.732898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:38.154319Z","time spent":"578.433483ms","remote":"127.0.0.1:43632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:38.733214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:38.181338Z","time spent":"551.762448ms","remote":"127.0.0.1:43632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97,"response count":0,"response size":150531,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:38.733346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.201981ms","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-18T02:22:38.733401Z","caller":"traceutil/trace.go:171","msg":"trace[1266112129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"132.287384ms","start":"2026-09-18T02:22:38.601099Z","end":"2026-09-18T02:22:38.733386Z","steps":["trace[1266112129] 'agreement among raft nodes before linearized reading' (duration: 132.042817ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:39.20058Z","caller":"traceutil/trace.go:171","msg":"trace[1545469963] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"155.225674ms","start":"2026-09-18T02:22:39.045321Z","end":"2026-09-18T02:22:39.200546Z","steps":["trace[1545469963] 'process raft request' (duration: 88.378561ms)","trace[1545469963] 'compare' (duration: 66.548103ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:39.248307Z","caller":"traceutil/trace.go:171","msg":"trace[390598288] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"160.290583ms","start":"2026-09-18T02:22:39.087998Z","end":"2026-09-18T02:22:39.248289Z","steps":["trace[390598288] 'process raft request' (duration: 160.18897ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:39.248363Z","caller":"traceutil/trace.go:171","msg":"trace[1967152299] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"195.867681ms","start":"2026-09-18T02:22:39.052472Z","end":"2026-09-18T02:22:39.24834Z","steps":["trace[1967152299] 'process raft request' (duration: 195.543201ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:44.482619Z","caller":"traceutil/trace.go:171","msg":"trace[1169680881] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"113.208767ms","start":"2026-09-18T02:22:44.36938Z","end":"2026-09-18T02:22:44.482588Z","steps":["trace[1169680881] 'process raft request' (duration: 112.625081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:45.974687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330551153s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:22:45.974831Z","caller":"traceutil/trace.go:171","msg":"trace[1558945346] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"1.160619199s","start":"2026-09-18T02:22:44.814193Z","end":"2026-09-18T02:22:45.974812Z","steps":["trace[1558945346] 'read index received' (duration: 37.251µs)","trace[1558945346] 'applied index is now lower than readState.Index' (duration: 1.160580618s)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:45.974898Z","caller":"traceutil/trace.go:171","msg":"trace[1488081954] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.403527358s","start":"2026-09-18T02:22:44.571306Z","end":"2026-09-18T02:22:45.974833Z","steps":["trace[1488081954] 'process raft request' (duration: 72.727538ms)","trace[1488081954] 'compare' (duration: 1.330291955s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:45.975052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160863326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-18T02:22:45.975098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:44.57129Z","time spent":"1.403726104s","remote":"127.0.0.1:43942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:22:45.97511Z","caller":"traceutil/trace.go:171","msg":"trace[692103981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"1.160968978s","start":"2026-09-18T02:22:44.814129Z","end":"2026-09-18T02:22:45.975098Z","steps":["trace[692103981] 'agreement among raft nodes before linearized reading' (duration: 1.160793434s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:45.97511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154672552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T02:22:45.975147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:44.814111Z","time spent":"1.16102761s","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-18T02:22:45.975189Z","caller":"traceutil/trace.go:171","msg":"trace[1700600218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"1.154765664s","start":"2026-09-18T02:22:44.820403Z","end":"2026-09-18T02:22:45.975168Z","steps":["trace[1700600218] 'agreement among raft nodes before linearized reading' (duration: 1.154626691s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:45.975238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:44.820363Z","time spent":"1.154865447s","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T02:22:45.97528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.129121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-18T02:22:45.975417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.152925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:22:45.975409Z","caller":"traceutil/trace.go:171","msg":"trace[863358686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"978.290565ms","start":"2026-09-18T02:22:44.997084Z","end":"2026-09-18T02:22:45.975374Z","steps":["trace[863358686] 'agreement among raft nodes before linearized reading' (duration: 977.943495ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:45.97546Z","caller":"traceutil/trace.go:171","msg":"trace[1346459200] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:661; }","duration":"404.242718ms","start":"2026-09-18T02:22:45.571206Z","end":"2026-09-18T02:22:45.975448Z","steps":["trace[1346459200] 'agreement among raft nodes before linearized reading' (duration: 404.149585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:45.975493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:45.571186Z","time spent":"404.29904ms","remote":"127.0.0.1:43766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-09-18T02:22:45.975485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:44.997073Z","time spent":"978.395328ms","remote":"127.0.0.1:43848","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-18T02:22:45.975686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131939081s","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-18T02:22:45.975742Z","caller":"traceutil/trace.go:171","msg":"trace[388345388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"1.132050435s","start":"2026-09-18T02:22:44.843681Z","end":"2026-09-18T02:22:45.975732Z","steps":["trace[388345388] 'agreement among raft nodes before linearized reading' (duration: 1.131940922s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:45.975768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:44.843669Z","time spent":"1.132092226s","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T02:22:45.976292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051190689s","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-18T02:22:45.976335Z","caller":"traceutil/trace.go:171","msg":"trace[331057361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"1.05125824s","start":"2026-09-18T02:22:44.925065Z","end":"2026-09-18T02:22:45.976323Z","steps":["trace[331057361] 'agreement among raft nodes before linearized reading' (duration: 1.050419186s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:45.976366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:44.925053Z","time spent":"1.051305212s","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-18T02:22:46.666541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.640048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:22:46.666651Z","caller":"traceutil/trace.go:171","msg":"trace[533903428] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"684.318506ms","start":"2026-09-18T02:22:45.982314Z","end":"2026-09-18T02:22:46.666633Z","steps":["trace[533903428] 'process raft request' (duration: 113.472683ms)","trace[533903428] 'compare' (duration: 570.507374ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:46.666716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:45.982283Z","time spent":"684.398549ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:47.098093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.80364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:22:47.098571Z","caller":"traceutil/trace.go:171","msg":"trace[2074116859] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.115141435s","start":"2026-09-18T02:22:45.983394Z","end":"2026-09-18T02:22:47.098535Z","steps":["trace[2074116859] 'process raft request' (duration: 893.700027ms)","trace[2074116859] 'compare' (duration: 220.467751ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:47.098703Z","caller":"traceutil/trace.go:171","msg":"trace[604951526] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.115020862s","start":"2026-09-18T02:22:45.983649Z","end":"2026-09-18T02:22:47.09867Z","steps":["trace[604951526] 'process raft request' (duration: 1.114789575s)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:47.098729Z","caller":"traceutil/trace.go:171","msg":"trace[1471892577] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.114457385s","start":"2026-09-18T02:22:45.984253Z","end":"2026-09-18T02:22:47.09871Z","steps":["trace[1471892577] 'process raft request' (duration: 1.11428495s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:47.09874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:45.983363Z","time spent":"1.115283578s","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:47.09882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:45.984127Z","time spent":"1.114649002s","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:22:47.098803Z","caller":"traceutil/trace.go:171","msg":"trace[1577416782] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:695; }","duration":"1.112363278s","start":"2026-09-18T02:22:45.986408Z","end":"2026-09-18T02:22:47.098771Z","steps":["trace[1577416782] 'read index received' (duration: 109.516033ms)","trace[1577416782] 'applied index is now lower than readState.Index' (duration: 1.002841105s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:47.098854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:45.983637Z","time spent":"1.115136835s","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:47.099011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111321968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T02:22:47.099012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112554333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:22:47.099044Z","caller":"traceutil/trace.go:171","msg":"trace[347364082] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:666; }","duration":"1.111404211s","start":"2026-09-18T02:22:45.987627Z","end":"2026-09-18T02:22:47.099031Z","steps":["trace[347364082] 'agreement among raft nodes before linearized reading' (duration: 1.111322109s)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:47.099052Z","caller":"traceutil/trace.go:171","msg":"trace[1740144894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.112677376s","start":"2026-09-18T02:22:45.986365Z","end":"2026-09-18T02:22:47.099042Z","steps":["trace[1740144894] 'agreement among raft nodes before linearized reading' (duration: 1.112554033s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:47.099072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:45.987617Z","time spent":"1.111448252s","remote":"127.0.0.1:44098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-09-18T02:22:47.0991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:45.986349Z","time spent":"1.112743107s","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T02:22:47.098711Z","caller":"traceutil/trace.go:171","msg":"trace[188307513] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"382.181609ms","start":"2026-09-18T02:22:46.71651Z","end":"2026-09-18T02:22:47.098691Z","steps":["trace[188307513] 'process raft request' (duration: 382.107826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:47.100082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:46.716482Z","time spent":"383.450953ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:22:48.771679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":821520669779414677,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T02:22:49.093531Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.006652672s","expected-duration":"1s"} {"level":"info","ts":"2026-09-18T02:22:49.100233Z","caller":"traceutil/trace.go:171","msg":"trace[1607084422] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.016094949s","start":"2026-09-18T02:22:48.084097Z","end":"2026-09-18T02:22:49.100192Z","steps":["trace[1607084422] 'process raft request' (duration: 1.010271071s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:49.100409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:48.084067Z","time spent":"1.016245893s","remote":"127.0.0.1:43682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:22:49.100776Z","caller":"traceutil/trace.go:171","msg":"trace[263451202] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"829.933893ms","start":"2026-09-18T02:22:48.270799Z","end":"2026-09-18T02:22:49.100733Z","steps":["trace[263451202] 'read index received' (duration: 822.887861ms)","trace[263451202] 'applied index is now lower than readState.Index' (duration: 7.044742ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:49.101094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.173917ms","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-18T02:22:49.101182Z","caller":"traceutil/trace.go:171","msg":"trace[312673275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:673; }","duration":"429.381973ms","start":"2026-09-18T02:22:48.671775Z","end":"2026-09-18T02:22:49.101157Z","steps":["trace[312673275] 'agreement among raft nodes before linearized reading' (duration: 429.135576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:49.101191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.008956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-18T02:22:49.101231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:48.671751Z","time spent":"429.467795ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T02:22:49.101235Z","caller":"traceutil/trace.go:171","msg":"trace[459021235] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:673; }","duration":"128.091938ms","start":"2026-09-18T02:22:48.973128Z","end":"2026-09-18T02:22:49.101219Z","steps":["trace[459021235] 'agreement among raft nodes before linearized reading' (duration: 128.014226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:49.101237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.469138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:22:49.101302Z","caller":"traceutil/trace.go:171","msg":"trace[701810190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"280.55769ms","start":"2026-09-18T02:22:48.820722Z","end":"2026-09-18T02:22:49.101279Z","steps":["trace[701810190] 'agreement among raft nodes before linearized reading' (duration: 280.463128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:49.101094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.229232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:22:49.101439Z","caller":"traceutil/trace.go:171","msg":"trace[1345648848] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:673; }","duration":"143.654723ms","start":"2026-09-18T02:22:48.957765Z","end":"2026-09-18T02:22:49.101419Z","steps":["trace[1345648848] 'agreement among raft nodes before linearized reading' (duration: 143.15767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:49.102531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.730433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wb4jh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-18T02:22:49.102598Z","caller":"traceutil/trace.go:171","msg":"trace[250542156] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wb4jh; range_end:; response_count:1; response_revision:673; }","duration":"831.838486ms","start":"2026-09-18T02:22:48.270741Z","end":"2026-09-18T02:22:49.10258Z","steps":["trace[250542156] 'agreement among raft nodes before linearized reading' (duration: 830.109868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:49.102647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:48.270724Z","time spent":"831.912087ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-wb4jh\" "} {"level":"info","ts":"2026-09-18T02:22:49.216369Z","caller":"traceutil/trace.go:171","msg":"trace[998410971] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"107.760798ms","start":"2026-09-18T02:22:49.108584Z","end":"2026-09-18T02:22:49.216345Z","steps":["trace[998410971] 'process raft request' (duration: 107.303156ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:49.216491Z","caller":"traceutil/trace.go:171","msg":"trace[1599512481] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"105.843067ms","start":"2026-09-18T02:22:49.110643Z","end":"2026-09-18T02:22:49.216486Z","steps":["trace[1599512481] 'process raft request' (duration: 105.688552ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:49.216563Z","caller":"traceutil/trace.go:171","msg":"trace[1768019538] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"107.881781ms","start":"2026-09-18T02:22:49.108677Z","end":"2026-09-18T02:22:49.216559Z","steps":["trace[1768019538] 'process raft request' (duration: 107.292956ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:50.791798Z","caller":"traceutil/trace.go:171","msg":"trace[303806293] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"327.695085ms","start":"2026-09-18T02:22:50.464083Z","end":"2026-09-18T02:22:50.791779Z","steps":["trace[303806293] 'read index received' (duration: 317.370567ms)","trace[303806293] 'applied index is now lower than readState.Index' (duration: 10.323368ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:50.791821Z","caller":"traceutil/trace.go:171","msg":"trace[1710730012] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"390.614473ms","start":"2026-09-18T02:22:50.401189Z","end":"2026-09-18T02:22:50.791803Z","steps":["trace[1710730012] 'process raft request' (duration: 380.671805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:50.791915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:50.401172Z","time spent":"390.688875ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:22:50.79192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.825698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:22:50.791946Z","caller":"traceutil/trace.go:171","msg":"trace[795732485] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:695; }","duration":"327.88611ms","start":"2026-09-18T02:22:50.464053Z","end":"2026-09-18T02:22:50.791939Z","steps":["trace[795732485] 'agreement among raft nodes before linearized reading' (duration: 327.818268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:50.791968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:50.464039Z","time spent":"327.923051ms","remote":"127.0.0.1:43966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-18T02:22:50.796815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.441759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:22:50.79687Z","caller":"traceutil/trace.go:171","msg":"trace[779181354] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:697; }","duration":"320.503941ms","start":"2026-09-18T02:22:50.476352Z","end":"2026-09-18T02:22:50.796856Z","steps":["trace[779181354] 'agreement among raft nodes before linearized reading' (duration: 320.406449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:50.796901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:50.476318Z","time spent":"320.574012ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-09-18T02:22:50.796952Z","caller":"traceutil/trace.go:171","msg":"trace[263259829] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"165.904779ms","start":"2026-09-18T02:22:50.631035Z","end":"2026-09-18T02:22:50.79694Z","steps":["trace[263259829] 'process raft request' (duration: 165.614881ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:50.797074Z","caller":"traceutil/trace.go:171","msg":"trace[2117320346] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"136.864585ms","start":"2026-09-18T02:22:50.660199Z","end":"2026-09-18T02:22:50.797063Z","steps":["trace[2117320346] 'process raft request' (duration: 136.528236ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:00.30702Z","caller":"traceutil/trace.go:171","msg":"trace[718810611] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"106.762629ms","start":"2026-09-18T02:23:00.200239Z","end":"2026-09-18T02:23:00.307001Z","steps":["trace[718810611] 'process raft request' (duration: 106.658286ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:08.721235Z","caller":"traceutil/trace.go:171","msg":"trace[1368735434] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"318.799843ms","start":"2026-09-18T02:23:08.402394Z","end":"2026-09-18T02:23:08.721194Z","steps":["trace[1368735434] 'process raft request' (duration: 318.519546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:08.72181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:08.402363Z","time spent":"319.007238ms","remote":"127.0.0.1:43828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:20.488656Z","caller":"traceutil/trace.go:171","msg":"trace[389294144] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"105.82318ms","start":"2026-09-18T02:23:20.382792Z","end":"2026-09-18T02:23:20.488615Z","steps":["trace[389294144] 'process raft request' (duration: 89.056332ms)","trace[389294144] 'compare' (duration: 16.324018ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:20.94651Z","caller":"traceutil/trace.go:171","msg":"trace[1504852607] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"125.572456ms","start":"2026-09-18T02:23:20.820911Z","end":"2026-09-18T02:23:20.946483Z","steps":["trace[1504852607] 'read index received' (duration: 62.365509ms)","trace[1504852607] 'applied index is now lower than readState.Index' (duration: 63.205577ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:20.946647Z","caller":"traceutil/trace.go:171","msg":"trace[2012723385] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"244.405068ms","start":"2026-09-18T02:23:20.702172Z","end":"2026-09-18T02:23:20.946577Z","steps":["trace[2012723385] 'process raft request' (duration: 181.16328ms)","trace[2012723385] 'compare' (duration: 62.982032ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:20.946846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.907483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:23:20.947156Z","caller":"traceutil/trace.go:171","msg":"trace[1422853034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"126.272862ms","start":"2026-09-18T02:23:20.820864Z","end":"2026-09-18T02:23:20.947137Z","steps":["trace[1422853034] 'agreement among raft nodes before linearized reading' (duration: 125.856552ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:21.198751Z","caller":"traceutil/trace.go:171","msg":"trace[541474306] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"152.981476ms","start":"2026-09-18T02:23:21.045746Z","end":"2026-09-18T02:23:21.198727Z","steps":["trace[541474306] 'process raft request' (duration: 152.865604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:21.198931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.30344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:23:21.199029Z","caller":"traceutil/trace.go:171","msg":"trace[1627662572] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:986; }","duration":"111.457433ms","start":"2026-09-18T02:23:21.087546Z","end":"2026-09-18T02:23:21.199003Z","steps":["trace[1627662572] 'agreement among raft nodes before linearized reading' (duration: 111.257439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:21.256266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.330404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"info","ts":"2026-09-18T02:23:21.256333Z","caller":"traceutil/trace.go:171","msg":"trace[1880197359] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"119.145077ms","start":"2026-09-18T02:23:21.137161Z","end":"2026-09-18T02:23:21.256306Z","steps":["trace[1880197359] 'process raft request' (duration: 118.83334ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:21.256385Z","caller":"traceutil/trace.go:171","msg":"trace[2113483233] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"145.674863ms","start":"2026-09-18T02:23:21.110687Z","end":"2026-09-18T02:23:21.256362Z","steps":["trace[2113483233] 'process raft request' (duration: 124.386495ms)","trace[2113483233] 'compare' (duration: 20.746076ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:21.256609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.944817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-09-18T02:23:21.256691Z","caller":"traceutil/trace.go:171","msg":"trace[897581698] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:988; }","duration":"141.077379ms","start":"2026-09-18T02:23:21.11559Z","end":"2026-09-18T02:23:21.256667Z","steps":["trace[897581698] 'agreement among raft nodes before linearized reading' (duration: 140.791932ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:21.256356Z","caller":"traceutil/trace.go:171","msg":"trace[919141173] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:988; }","duration":"145.460828ms","start":"2026-09-18T02:23:21.110871Z","end":"2026-09-18T02:23:21.256332Z","steps":["trace[919141173] 'agreement among raft nodes before linearized reading' (duration: 145.218223ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:22.865595Z","caller":"traceutil/trace.go:171","msg":"trace[536755087] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"128.173186ms","start":"2026-09-18T02:23:22.737378Z","end":"2026-09-18T02:23:22.865551Z","steps":["trace[536755087] 'process raft request' (duration: 127.91301ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:41.673847Z","caller":"traceutil/trace.go:171","msg":"trace[393986988] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"119.203187ms","start":"2026-09-18T02:23:41.554619Z","end":"2026-09-18T02:23:41.673822Z","steps":["trace[393986988] 'read index received' (duration: 119.052634ms)","trace[393986988] 'applied index is now lower than readState.Index' (duration: 149.363µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:41.67397Z","caller":"traceutil/trace.go:171","msg":"trace[846028923] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"303.232504ms","start":"2026-09-18T02:23:41.370727Z","end":"2026-09-18T02:23:41.673959Z","steps":["trace[846028923] 'process raft request' (duration: 302.944528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:41.674112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:41.370687Z","time spent":"303.308785ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:23:41.674186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.559954ms","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-18T02:23:41.674361Z","caller":"traceutil/trace.go:171","msg":"trace[31646789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"119.778888ms","start":"2026-09-18T02:23:41.554562Z","end":"2026-09-18T02:23:41.674341Z","steps":["trace[31646789] 'agreement among raft nodes before linearized reading' (duration: 119.510623ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:00.542766Z","caller":"traceutil/trace.go:171","msg":"trace[672561256] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1463; }","duration":"157.569639ms","start":"2026-09-18T02:24:00.385175Z","end":"2026-09-18T02:24:00.542744Z","steps":["trace[672561256] 'read index received' (duration: 157.339155ms)","trace[672561256] 'applied index is now lower than readState.Index' (duration: 229.264µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:00.54285Z","caller":"traceutil/trace.go:171","msg":"trace[1683499313] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"175.452953ms","start":"2026-09-18T02:24:00.36735Z","end":"2026-09-18T02:24:00.542803Z","steps":["trace[1683499313] 'process raft request' (duration: 175.244559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:00.542903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.707532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:24:00.542969Z","caller":"traceutil/trace.go:171","msg":"trace[1111743655] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1407; }","duration":"157.804805ms","start":"2026-09-18T02:24:00.385121Z","end":"2026-09-18T02:24:00.542926Z","steps":["trace[1111743655] 'agreement among raft nodes before linearized reading' (duration: 157.719922ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:06.671029Z","caller":"traceutil/trace.go:171","msg":"trace[1704848056] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"110.642612ms","start":"2026-09-18T02:24:06.56036Z","end":"2026-09-18T02:24:06.671002Z","steps":["trace[1704848056] 'process raft request' (duration: 110.476609ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:08.206819Z","caller":"traceutil/trace.go:171","msg":"trace[1547054004] linearizableReadLoop","detail":"{readStateIndex:1588; appliedIndex:1587; }","duration":"301.935692ms","start":"2026-09-18T02:24:07.904858Z","end":"2026-09-18T02:24:08.206793Z","steps":["trace[1547054004] 'read index received' (duration: 301.759649ms)","trace[1547054004] 'applied index is now lower than readState.Index' (duration: 175.183µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:08.206943Z","caller":"traceutil/trace.go:171","msg":"trace[1943420029] transaction","detail":"{read_only:false; response_revision:1528; number_of_response:1; }","duration":"304.878818ms","start":"2026-09-18T02:24:07.902052Z","end":"2026-09-18T02:24:08.206931Z","steps":["trace[1943420029] 'process raft request' (duration: 304.592663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:08.207047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:07.902042Z","time spent":"304.924928ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:24:08.207613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.38178ms","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-18T02:24:08.207674Z","caller":"traceutil/trace.go:171","msg":"trace[925577032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1528; }","duration":"302.884152ms","start":"2026-09-18T02:24:07.904774Z","end":"2026-09-18T02:24:08.207658Z","steps":["trace[925577032] 'agreement among raft nodes before linearized reading' (duration: 302.356061ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:08.207941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:07.904752Z","time spent":"303.131606ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-18T02:24:08.727427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.117372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:24:08.727634Z","caller":"traceutil/trace.go:171","msg":"trace[543757404] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"513.7803ms","start":"2026-09-18T02:24:08.213835Z","end":"2026-09-18T02:24:08.727615Z","steps":["trace[543757404] 'process raft request' (duration: 513.667908ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:08.727627Z","caller":"traceutil/trace.go:171","msg":"trace[1735605494] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"822.163424ms","start":"2026-09-18T02:24:07.905421Z","end":"2026-09-18T02:24:08.727584Z","steps":["trace[1735605494] 'process raft request' (duration: 365.833848ms)","trace[1735605494] 'compare' (duration: 456.038141ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:08.727832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.81492ms","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-18T02:24:08.727851Z","caller":"traceutil/trace.go:171","msg":"trace[332182848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1530; }","duration":"794.949813ms","start":"2026-09-18T02:24:07.932896Z","end":"2026-09-18T02:24:08.727846Z","steps":["trace[332182848] 'agreement among raft nodes before linearized reading' (duration: 794.861201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:08.727717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:08.213804Z","time spent":"513.864052ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:24:08.727746Z","caller":"traceutil/trace.go:171","msg":"trace[1737921121] linearizableReadLoop","detail":"{readStateIndex:1589; appliedIndex:1588; }","duration":"520.848323ms","start":"2026-09-18T02:24:08.206891Z","end":"2026-09-18T02:24:08.727739Z","steps":["trace[1737921121] 'read index received' (duration: 64.379744ms)","trace[1737921121] 'applied index is now lower than readState.Index' (duration: 456.467639ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:08.727869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:07.932878Z","time spent":"794.986963ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-18T02:24:08.727885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:07.905398Z","time spent":"822.378697ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:24:08.728309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.68165ms","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-18T02:24:08.728393Z","caller":"traceutil/trace.go:171","msg":"trace[1338473890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1530; }","duration":"777.811482ms","start":"2026-09-18T02:24:07.950558Z","end":"2026-09-18T02:24:08.72837Z","steps":["trace[1338473890] 'agreement among raft nodes before linearized reading' (duration: 777.255451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:08.728443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:07.950542Z","time spent":"777.893384ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T02:24:08.950463Z","caller":"traceutil/trace.go:171","msg":"trace[391363885] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"674.542537ms","start":"2026-09-18T02:24:08.275886Z","end":"2026-09-18T02:24:08.950429Z","steps":["trace[391363885] 'process raft request' (duration: 626.322146ms)","trace[391363885] 'compare' (duration: 47.826554ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:08.950625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:08.275847Z","time spent":"674.68766ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:24:08.950879Z","caller":"traceutil/trace.go:171","msg":"trace[31994043] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"274.317561ms","start":"2026-09-18T02:24:08.676515Z","end":"2026-09-18T02:24:08.950833Z","steps":["trace[31994043] 'process raft request' (duration: 273.848722ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:08.950958Z","caller":"traceutil/trace.go:171","msg":"trace[1261188466] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"217.465058ms","start":"2026-09-18T02:24:08.733465Z","end":"2026-09-18T02:24:08.95093Z","steps":["trace[1261188466] 'process raft request' (duration: 217.190232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:08.95104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.033684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:24:08.950881Z","caller":"traceutil/trace.go:171","msg":"trace[140489171] linearizableReadLoop","detail":"{readStateIndex:1594; appliedIndex:1590; }","duration":"128.862891ms","start":"2026-09-18T02:24:08.821979Z","end":"2026-09-18T02:24:08.950842Z","steps":["trace[140489171] 'read index received' (duration: 80.245361ms)","trace[140489171] 'applied index is now lower than readState.Index' (duration: 48.6161ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:08.951084Z","caller":"traceutil/trace.go:171","msg":"trace[1792438939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1534; }","duration":"129.170117ms","start":"2026-09-18T02:24:08.821899Z","end":"2026-09-18T02:24:08.951069Z","steps":["trace[1792438939] 'agreement among raft nodes before linearized reading' (duration: 129.080665ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:08.951107Z","caller":"traceutil/trace.go:171","msg":"trace[638638060] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"216.312657ms","start":"2026-09-18T02:24:08.734731Z","end":"2026-09-18T02:24:08.951044Z","steps":["trace[638638060] 'process raft request' (duration: 216.054933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:19.239728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.890248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:24:19.23988Z","caller":"traceutil/trace.go:171","msg":"trace[1227156612] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"207.266264ms","start":"2026-09-18T02:24:19.032558Z","end":"2026-09-18T02:24:19.239824Z","steps":["trace[1227156612] 'process raft request' (duration: 60.159582ms)","trace[1227156612] 'compare' (duration: 146.762726ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:19.255146Z","caller":"traceutil/trace.go:171","msg":"trace[854841228] linearizableReadLoop","detail":"{readStateIndex:1793; appliedIndex:1790; }","duration":"188.416231ms","start":"2026-09-18T02:24:19.066715Z","end":"2026-09-18T02:24:19.255131Z","steps":["trace[854841228] 'read index received' (duration: 26.021942ms)","trace[854841228] 'applied index is now lower than readState.Index' (duration: 162.393579ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:19.25522Z","caller":"traceutil/trace.go:171","msg":"trace[10591533] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"218.264124ms","start":"2026-09-18T02:24:19.036948Z","end":"2026-09-18T02:24:19.255212Z","steps":["trace[10591533] 'process raft request' (duration: 217.998069ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:19.25531Z","caller":"traceutil/trace.go:171","msg":"trace[1479396786] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"218.278134ms","start":"2026-09-18T02:24:19.037026Z","end":"2026-09-18T02:24:19.255304Z","steps":["trace[1479396786] 'process raft request' (duration: 218.021199ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:19.255369Z","caller":"traceutil/trace.go:171","msg":"trace[1099870104] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"185.628951ms","start":"2026-09-18T02:24:19.069735Z","end":"2026-09-18T02:24:19.255364Z","steps":["trace[1099870104] 'process raft request' (duration: 185.353956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:19.255575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.179071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1080"} {"level":"warn","ts":"2026-09-18T02:24:19.255629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.911859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:1 size:130551"} {"level":"info","ts":"2026-09-18T02:24:19.255648Z","caller":"traceutil/trace.go:171","msg":"trace[38230270] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:1; response_revision:1716; }","duration":"188.972562ms","start":"2026-09-18T02:24:19.06667Z","end":"2026-09-18T02:24:19.255643Z","steps":["trace[38230270] 'agreement among raft nodes before linearized reading' (duration: 188.853429ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:19.255646Z","caller":"traceutil/trace.go:171","msg":"trace[936424717] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:1716; }","duration":"186.291993ms","start":"2026-09-18T02:24:19.069336Z","end":"2026-09-18T02:24:19.255628Z","steps":["trace[936424717] 'agreement among raft nodes before linearized reading' (duration: 186.09953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:19.255661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.964776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1113"} {"level":"info","ts":"2026-09-18T02:24:19.255742Z","caller":"traceutil/trace.go:171","msg":"trace[1754319985] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:1716; }","duration":"186.072889ms","start":"2026-09-18T02:24:19.069654Z","end":"2026-09-18T02:24:19.255727Z","steps":["trace[1754319985] 'agreement among raft nodes before linearized reading' (duration: 185.896765ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:19.542702Z","caller":"traceutil/trace.go:171","msg":"trace[2137852212] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"107.534742ms","start":"2026-09-18T02:24:19.435143Z","end":"2026-09-18T02:24:19.542678Z","steps":["trace[2137852212] 'process raft request' (duration: 107.501522ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:19.542764Z","caller":"traceutil/trace.go:171","msg":"trace[875068231] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"108.716474ms","start":"2026-09-18T02:24:19.434001Z","end":"2026-09-18T02:24:19.542717Z","steps":["trace[875068231] 'process raft request' (duration: 108.581681ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:19.54273Z","caller":"traceutil/trace.go:171","msg":"trace[1887375439] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"108.759784ms","start":"2026-09-18T02:24:19.433896Z","end":"2026-09-18T02:24:19.542655Z","steps":["trace[1887375439] 'process raft request' (duration: 67.894932ms)","trace[1887375439] 'compare' (duration: 40.632798ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:19.543005Z","caller":"traceutil/trace.go:171","msg":"trace[1736489353] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"108.683964ms","start":"2026-09-18T02:24:19.434309Z","end":"2026-09-18T02:24:19.542993Z","steps":["trace[1736489353] 'process raft request' (duration: 108.310996ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:21.566307Z","caller":"traceutil/trace.go:171","msg":"trace[1049966411] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"127.698868ms","start":"2026-09-18T02:24:21.438575Z","end":"2026-09-18T02:24:21.566273Z","steps":["trace[1049966411] 'process raft request' (duration: 123.139676ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:27.514788Z","caller":"traceutil/trace.go:171","msg":"trace[1010709969] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"112.137238ms","start":"2026-09-18T02:24:27.402624Z","end":"2026-09-18T02:24:27.514762Z","steps":["trace[1010709969] 'process raft request' (duration: 111.928285ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:27.633038Z","caller":"traceutil/trace.go:171","msg":"trace[1638632531] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"114.182945ms","start":"2026-09-18T02:24:27.518831Z","end":"2026-09-18T02:24:27.633014Z","steps":["trace[1638632531] 'process raft request' (duration: 114.042172ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:35.660488Z","caller":"traceutil/trace.go:171","msg":"trace[1545914511] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2026; }","duration":"377.040924ms","start":"2026-09-18T02:24:35.283427Z","end":"2026-09-18T02:24:35.660468Z","steps":["trace[1545914511] 'read index received' (duration: 376.873611ms)","trace[1545914511] 'applied index is now lower than readState.Index' (duration: 165.763µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:35.66059Z","caller":"traceutil/trace.go:171","msg":"trace[775114908] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"561.726089ms","start":"2026-09-18T02:24:35.098816Z","end":"2026-09-18T02:24:35.660542Z","steps":["trace[775114908] 'process raft request' (duration: 561.497926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:35.660695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.264778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-09-18T02:24:35.660721Z","caller":"traceutil/trace.go:171","msg":"trace[1779254026] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1940; }","duration":"377.330689ms","start":"2026-09-18T02:24:35.283381Z","end":"2026-09-18T02:24:35.660712Z","steps":["trace[1779254026] 'agreement among raft nodes before linearized reading' (duration: 377.214387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:35.660742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:35.283362Z","time spent":"377.37356ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T02:24:35.660739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:35.098798Z","time spent":"561.848241ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:24:35.660956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.806461ms","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-18T02:24:35.660984Z","caller":"traceutil/trace.go:171","msg":"trace[1192707699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1940; }","duration":"114.858652ms","start":"2026-09-18T02:24:35.546115Z","end":"2026-09-18T02:24:35.660974Z","steps":["trace[1192707699] 'agreement among raft nodes before linearized reading' (duration: 114.73674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:35.66099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.706364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07f0bab4-1bca-43aa-ba7e-60db320d4491\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-18T02:24:35.661176Z","caller":"traceutil/trace.go:171","msg":"trace[1419074272] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07f0bab4-1bca-43aa-ba7e-60db320d4491; range_end:; response_count:1; response_revision:1940; }","duration":"124.844048ms","start":"2026-09-18T02:24:35.536216Z","end":"2026-09-18T02:24:35.66106Z","steps":["trace[1419074272] 'agreement among raft nodes before linearized reading' (duration: 124.619473ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:35.888097Z","caller":"traceutil/trace.go:171","msg":"trace[2016909840] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"221.970219ms","start":"2026-09-18T02:24:35.666081Z","end":"2026-09-18T02:24:35.888051Z","steps":["trace[2016909840] 'process raft request' (duration: 153.385148ms)","trace[2016909840] 'compare' (duration: 68.391858ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:35.922468Z","caller":"traceutil/trace.go:171","msg":"trace[723477409] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2027; }","duration":"113.780743ms","start":"2026-09-18T02:24:35.808658Z","end":"2026-09-18T02:24:35.922438Z","steps":["trace[723477409] 'read index received' (duration: 10.82111ms)","trace[723477409] 'applied index is now lower than readState.Index' (duration: 102.958203ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:35.922489Z","caller":"traceutil/trace.go:171","msg":"trace[1091015460] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"255.948975ms","start":"2026-09-18T02:24:35.666509Z","end":"2026-09-18T02:24:35.922457Z","steps":["trace[1091015460] 'process raft request' (duration: 255.626909ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:35.922548Z","caller":"traceutil/trace.go:171","msg":"trace[1654322244] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"255.933874ms","start":"2026-09-18T02:24:35.66659Z","end":"2026-09-18T02:24:35.922524Z","steps":["trace[1654322244] 'process raft request' (duration: 255.72198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:35.922746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.046938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07f0bab4-1bca-43aa-ba7e-60db320d4491\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-09-18T02:24:35.922807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.302234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:24:35.922841Z","caller":"traceutil/trace.go:171","msg":"trace[301267566] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07f0bab4-1bca-43aa-ba7e-60db320d4491; range_end:; response_count:1; response_revision:1943; }","duration":"114.214291ms","start":"2026-09-18T02:24:35.808609Z","end":"2026-09-18T02:24:35.922823Z","steps":["trace[301267566] 'agreement among raft nodes before linearized reading' (duration: 113.964936ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:35.922907Z","caller":"traceutil/trace.go:171","msg":"trace[1305023587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1943; }","duration":"101.474358ms","start":"2026-09-18T02:24:35.82141Z","end":"2026-09-18T02:24:35.922884Z","steps":["trace[1305023587] 'agreement among raft nodes before linearized reading' (duration: 101.281483ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:36.271574Z","caller":"traceutil/trace.go:171","msg":"trace[1298527527] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2030; }","duration":"284.76141ms","start":"2026-09-18T02:24:35.986793Z","end":"2026-09-18T02:24:36.271554Z","steps":["trace[1298527527] 'read index received' (duration: 269.785788ms)","trace[1298527527] 'applied index is now lower than readState.Index' (duration: 14.974812ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:36.271669Z","caller":"traceutil/trace.go:171","msg":"trace[729725620] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"340.137619ms","start":"2026-09-18T02:24:35.931508Z","end":"2026-09-18T02:24:36.271646Z","steps":["trace[729725620] 'process raft request' (duration: 325.065995ms)","trace[729725620] 'compare' (duration: 14.687236ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:36.271798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.876701ms","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-18T02:24:36.27183Z","caller":"traceutil/trace.go:171","msg":"trace[343020496] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1944; }","duration":"240.942213ms","start":"2026-09-18T02:24:36.030878Z","end":"2026-09-18T02:24:36.27182Z","steps":["trace[343020496] 'agreement among raft nodes before linearized reading' (duration: 240.78088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:36.271815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.962173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-18T02:24:36.271849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:24:35.931472Z","time spent":"340.274061ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:24:36.271871Z","caller":"traceutil/trace.go:171","msg":"trace[959564106] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1944; }","duration":"285.121866ms","start":"2026-09-18T02:24:35.986732Z","end":"2026-09-18T02:24:36.271854Z","steps":["trace[959564106] 'agreement among raft nodes before linearized reading' (duration: 284.906443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:36.59995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.343123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T02:24:36.600059Z","caller":"traceutil/trace.go:171","msg":"trace[1366502960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1944; }","duration":"204.505235ms","start":"2026-09-18T02:24:36.395528Z","end":"2026-09-18T02:24:36.600033Z","steps":["trace[1366502960] 'range keys from in-memory index tree' (duration: 204.087658ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:36.843601Z","caller":"traceutil/trace.go:171","msg":"trace[1575362763] linearizableReadLoop","detail":"{readStateIndex:2032; appliedIndex:2031; }","duration":"176.510236ms","start":"2026-09-18T02:24:36.667071Z","end":"2026-09-18T02:24:36.843581Z","steps":["trace[1575362763] 'read index received' (duration: 176.262402ms)","trace[1575362763] 'applied index is now lower than readState.Index' (duration: 246.884µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:36.843853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.76818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07f0bab4-1bca-43aa-ba7e-60db320d4491\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-18T02:24:36.843888Z","caller":"traceutil/trace.go:171","msg":"trace[1786875583] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07f0bab4-1bca-43aa-ba7e-60db320d4491; range_end:; response_count:1; response_revision:1945; }","duration":"176.873522ms","start":"2026-09-18T02:24:36.667004Z","end":"2026-09-18T02:24:36.843878Z","steps":["trace[1786875583] 'agreement among raft nodes before linearized reading' (duration: 176.657168ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:36.844116Z","caller":"traceutil/trace.go:171","msg":"trace[1316439412] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"239.606918ms","start":"2026-09-18T02:24:36.604471Z","end":"2026-09-18T02:24:36.844078Z","steps":["trace[1316439412] 'process raft request' (duration: 238.969417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:37.051886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.710766ms","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-18T02:24:37.05218Z","caller":"traceutil/trace.go:171","msg":"trace[165015372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1945; }","duration":"145.97863ms","start":"2026-09-18T02:24:36.906082Z","end":"2026-09-18T02:24:37.05206Z","steps":["trace[165015372] 'range keys from in-memory index tree' (duration: 145.409901ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:37.052296Z","caller":"traceutil/trace.go:171","msg":"trace[206373750] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"145.780918ms","start":"2026-09-18T02:24:36.906299Z","end":"2026-09-18T02:24:37.05208Z","steps":["trace[206373750] 'process raft request' (duration: 94.93288ms)","trace[206373750] 'compare' (duration: 50.616704ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:37.053901Z","caller":"traceutil/trace.go:171","msg":"trace[1531270205] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"115.174302ms","start":"2026-09-18T02:24:36.938702Z","end":"2026-09-18T02:24:37.053876Z","steps":["trace[1531270205] 'process raft request' (duration: 115.005299ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:49.774249Z","caller":"traceutil/trace.go:171","msg":"trace[854905369] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"298.302293ms","start":"2026-09-18T02:24:49.475931Z","end":"2026-09-18T02:24:49.774234Z","steps":["trace[854905369] 'process raft request' (duration: 298.187151ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:09.969547Z","caller":"traceutil/trace.go:171","msg":"trace[1136842276] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"101.393141ms","start":"2026-09-18T02:25:09.868123Z","end":"2026-09-18T02:25:09.969517Z","steps":["trace[1136842276] 'process raft request' (duration: 26.677322ms)","trace[1136842276] 'compare' (duration: 74.563297ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:25:10.303063Z","caller":"traceutil/trace.go:171","msg":"trace[233481432] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"160.109453ms","start":"2026-09-18T02:25:10.142917Z","end":"2026-09-18T02:25:10.303027Z","steps":["trace[233481432] 'process raft request' (duration: 159.825988ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:11.254981Z","caller":"traceutil/trace.go:171","msg":"trace[1404803244] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"176.322948ms","start":"2026-09-18T02:25:11.078635Z","end":"2026-09-18T02:25:11.254958Z","steps":["trace[1404803244] 'process raft request' (duration: 176.125934ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:11.4962Z","caller":"traceutil/trace.go:171","msg":"trace[1599210312] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2285; }","duration":"232.150562ms","start":"2026-09-18T02:25:11.264024Z","end":"2026-09-18T02:25:11.496175Z","steps":["trace[1599210312] 'read index received' (duration: 231.50271ms)","trace[1599210312] 'applied index is now lower than readState.Index' (duration: 646.652µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:25:11.496265Z","caller":"traceutil/trace.go:171","msg":"trace[467454339] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"291.153468ms","start":"2026-09-18T02:25:11.205083Z","end":"2026-09-18T02:25:11.496237Z","steps":["trace[467454339] 'process raft request' (duration: 291.024236ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:11.496406Z","caller":"traceutil/trace.go:171","msg":"trace[1485282460] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"299.263042ms","start":"2026-09-18T02:25:11.197021Z","end":"2026-09-18T02:25:11.496284Z","steps":["trace[1485282460] 'process raft request' (duration: 298.855795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:25:11.496477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.428426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T02:25:11.496566Z","caller":"traceutil/trace.go:171","msg":"trace[288183130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2191; }","duration":"232.563148ms","start":"2026-09-18T02:25:11.263969Z","end":"2026-09-18T02:25:11.496532Z","steps":["trace[288183130] 'agreement among raft nodes before linearized reading' (duration: 232.378435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:25:11.496631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.48947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-18T02:25:11.496907Z","caller":"traceutil/trace.go:171","msg":"trace[1519743640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2191; }","duration":"231.627562ms","start":"2026-09-18T02:25:11.265067Z","end":"2026-09-18T02:25:11.496694Z","steps":["trace[1519743640] 'agreement among raft nodes before linearized reading' (duration: 231.340667ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:11.73851Z","caller":"traceutil/trace.go:171","msg":"trace[680465811] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"235.358944ms","start":"2026-09-18T02:25:11.503126Z","end":"2026-09-18T02:25:11.738485Z","steps":["trace[680465811] 'process raft request' (duration: 192.036937ms)","trace[680465811] 'compare' (duration: 43.085283ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:25:11.738597Z","caller":"traceutil/trace.go:171","msg":"trace[695329908] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"234.711404ms","start":"2026-09-18T02:25:11.503827Z","end":"2026-09-18T02:25:11.738538Z","steps":["trace[695329908] 'process raft request' (duration: 234.604882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:25:11.738739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.644299ms","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-18T02:25:11.738795Z","caller":"traceutil/trace.go:171","msg":"trace[231339951] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2193; }","duration":"189.787171ms","start":"2026-09-18T02:25:11.548996Z","end":"2026-09-18T02:25:11.738783Z","steps":["trace[231339951] 'agreement among raft nodes before linearized reading' (duration: 189.587818ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:11.738509Z","caller":"traceutil/trace.go:171","msg":"trace[764511417] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2287; }","duration":"189.403185ms","start":"2026-09-18T02:25:11.549074Z","end":"2026-09-18T02:25:11.738477Z","steps":["trace[764511417] 'read index received' (duration: 146.079648ms)","trace[764511417] 'applied index is now lower than readState.Index' (duration: 43.322367ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:25:12.117002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.877088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:25:12.117133Z","caller":"traceutil/trace.go:171","msg":"trace[377600547] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"141.035732ms","start":"2026-09-18T02:25:11.976072Z","end":"2026-09-18T02:25:12.117108Z","steps":["trace[377600547] 'process raft request' (duration: 39.954441ms)","trace[377600547] 'compare' (duration: 100.732985ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:26:05.951268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.685694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:26:05.951403Z","caller":"traceutil/trace.go:171","msg":"trace[166963156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2533; }","duration":"131.876167ms","start":"2026-09-18T02:26:05.819509Z","end":"2026-09-18T02:26:05.951385Z","steps":["trace[166963156] 'range keys from in-memory index tree' (duration: 131.602122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:26:05.951246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.23002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:26:05.951636Z","caller":"traceutil/trace.go:171","msg":"trace[1152163623] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2533; }","duration":"130.646998ms","start":"2026-09-18T02:26:05.820959Z","end":"2026-09-18T02:26:05.951606Z","steps":["trace[1152163623] 'range keys from in-memory index tree' (duration: 130.152698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:26:16.678038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.976526ms","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-18T02:26:16.678122Z","caller":"traceutil/trace.go:171","msg":"trace[426201775] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2644; }","duration":"193.106788ms","start":"2026-09-18T02:26:16.484997Z","end":"2026-09-18T02:26:16.678103Z","steps":["trace[426201775] 'count revisions from in-memory index tree' (duration: 192.895085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:26:17.218687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.354238ms","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-18T02:26:17.21875Z","caller":"traceutil/trace.go:171","msg":"trace[2133024993] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2647; }","duration":"250.470389ms","start":"2026-09-18T02:26:16.968264Z","end":"2026-09-18T02:26:17.218735Z","steps":["trace[2133024993] 'range keys from in-memory index tree' (duration: 250.213445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:26:17.218813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.798201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-18T02:26:17.218893Z","caller":"traceutil/trace.go:171","msg":"trace[506029812] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2647; }","duration":"436.911882ms","start":"2026-09-18T02:26:16.781959Z","end":"2026-09-18T02:26:17.218871Z","steps":["trace[506029812] 'range keys from in-memory index tree' (duration: 436.592717ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:26:17.218939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:26:16.781945Z","time spent":"436.983243ms","remote":"127.0.0.1:43766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-09-18T02:26:17.218958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.303437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.59\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-18T02:26:17.21901Z","caller":"traceutil/trace.go:171","msg":"trace[1937090774] range","detail":"{range_begin:/registry/masterleases/162.253.55.59; range_end:; response_count:1; response_revision:2647; }","duration":"163.38054ms","start":"2026-09-18T02:26:17.055621Z","end":"2026-09-18T02:26:17.219002Z","steps":["trace[1937090774] 'range keys from in-memory index tree' (duration: 162.582986ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:26:42.012873Z","caller":"traceutil/trace.go:171","msg":"trace[1021048001] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"147.77183ms","start":"2026-09-18T02:26:41.865079Z","end":"2026-09-18T02:26:42.012851Z","steps":["trace[1021048001] 'process raft request' (duration: 65.732467ms)","trace[1021048001] 'compare' (duration: 81.91203ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:26:42.033839Z","caller":"traceutil/trace.go:171","msg":"trace[1984251360] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"145.488768ms","start":"2026-09-18T02:26:41.888313Z","end":"2026-09-18T02:26:42.033802Z","steps":["trace[1984251360] 'process raft request' (duration: 145.22396ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:26:47.504082Z","caller":"traceutil/trace.go:171","msg":"trace[2141494629] linearizableReadLoop","detail":"{readStateIndex:2935; appliedIndex:2934; }","duration":"228.088436ms","start":"2026-09-18T02:26:47.27597Z","end":"2026-09-18T02:26:47.504058Z","steps":["trace[2141494629] 'read index received' (duration: 227.89382ms)","trace[2141494629] 'applied index is now lower than readState.Index' (duration: 193.536µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:26:47.50432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.319843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:26:47.504397Z","caller":"traceutil/trace.go:171","msg":"trace[950045454] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2817; }","duration":"228.442937ms","start":"2026-09-18T02:26:47.275942Z","end":"2026-09-18T02:26:47.504385Z","steps":["trace[950045454] 'agreement among raft nodes before linearized reading' (duration: 228.312793ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:26:47.505241Z","caller":"traceutil/trace.go:171","msg":"trace[825824310] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"244.841506ms","start":"2026-09-18T02:26:47.260265Z","end":"2026-09-18T02:26:47.505106Z","steps":["trace[825824310] 'process raft request' (duration: 243.65092ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:26:53.201941Z","caller":"traceutil/trace.go:171","msg":"trace[1235341696] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"284.748889ms","start":"2026-09-18T02:26:52.917155Z","end":"2026-09-18T02:26:53.201904Z","steps":["trace[1235341696] 'process raft request' (duration: 217.808188ms)","trace[1235341696] 'compare' (duration: 66.753564ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:26:53.209975Z","caller":"traceutil/trace.go:171","msg":"trace[1192217859] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"292.624015ms","start":"2026-09-18T02:26:52.917244Z","end":"2026-09-18T02:26:53.209868Z","steps":["trace[1192217859] 'process raft request' (duration: 292.407528ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:02.298868Z","caller":"traceutil/trace.go:171","msg":"trace[949981479] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"209.338748ms","start":"2026-09-18T02:31:02.089492Z","end":"2026-09-18T02:31:02.298831Z","steps":["trace[949981479] 'process raft request' (duration: 180.639413ms)","trace[949981479] 'compare' (duration: 28.536602ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:02.318308Z","caller":"traceutil/trace.go:171","msg":"trace[1826434907] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4665; }","duration":"177.013323ms","start":"2026-09-18T02:31:02.141277Z","end":"2026-09-18T02:31:02.318291Z","steps":["trace[1826434907] 'process raft request' (duration: 176.912542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:09.905275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.818675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:31:09.905384Z","caller":"traceutil/trace.go:171","msg":"trace[996514212] linearizableReadLoop","detail":"{readStateIndex:5015; appliedIndex:5014; }","duration":"198.592025ms","start":"2026-09-18T02:31:09.706778Z","end":"2026-09-18T02:31:09.90537Z","steps":["trace[996514212] 'read index received' (duration: 32.588767ms)","trace[996514212] 'applied index is now lower than readState.Index' (duration: 166.002218ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:09.905583Z","caller":"traceutil/trace.go:171","msg":"trace[1711826349] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"263.266557ms","start":"2026-09-18T02:31:09.642304Z","end":"2026-09-18T02:31:09.90557Z","steps":["trace[1711826349] 'process raft request' (duration: 97.087916ms)","trace[1711826349] 'compare' (duration: 165.474769ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:09.905828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.048063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-18f74c23-e855-41e6-a3da-5399b5a52f1d\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-09-18T02:31:09.905865Z","caller":"traceutil/trace.go:171","msg":"trace[861892323] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-18f74c23-e855-41e6-a3da-5399b5a52f1d; range_end:; response_count:1; response_revision:4818; }","duration":"199.125835ms","start":"2026-09-18T02:31:09.706729Z","end":"2026-09-18T02:31:09.905855Z","steps":["trace[861892323] 'agreement among raft nodes before linearized reading' (duration: 199.008322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:09.905868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.3745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T02:31:09.905976Z","caller":"traceutil/trace.go:171","msg":"trace[1997841664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4818; }","duration":"189.529744ms","start":"2026-09-18T02:31:09.716419Z","end":"2026-09-18T02:31:09.905949Z","steps":["trace[1997841664] 'agreement among raft nodes before linearized reading' (duration: 189.270299ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:10.036673Z","caller":"traceutil/trace.go:171","msg":"trace[1260619236] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"124.33576ms","start":"2026-09-18T02:31:09.912296Z","end":"2026-09-18T02:31:10.036632Z","steps":["trace[1260619236] 'process raft request' (duration: 94.938735ms)","trace[1260619236] 'compare' (duration: 29.263183ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:18.480852Z","caller":"traceutil/trace.go:171","msg":"trace[576955824] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"479.277005ms","start":"2026-09-18T02:31:18.001549Z","end":"2026-09-18T02:31:18.480826Z","steps":["trace[576955824] 'process raft request' (duration: 479.080472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:18.481081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:18.001534Z","time spent":"479.425179ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:31:18.509657Z","caller":"traceutil/trace.go:171","msg":"trace[699808002] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"113.658635ms","start":"2026-09-18T02:31:18.395972Z","end":"2026-09-18T02:31:18.509631Z","steps":["trace[699808002] 'process raft request' (duration: 113.473132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:18.992028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.532934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:31:18.992143Z","caller":"traceutil/trace.go:171","msg":"trace[2029735165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4955; }","duration":"171.702287ms","start":"2026-09-18T02:31:18.820417Z","end":"2026-09-18T02:31:18.99212Z","steps":["trace[2029735165] 'range keys from in-memory index tree' (duration: 171.440832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:18.992188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.500977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-18T02:31:18.992341Z","caller":"traceutil/trace.go:171","msg":"trace[584532188] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4955; }","duration":"159.632509ms","start":"2026-09-18T02:31:18.832625Z","end":"2026-09-18T02:31:18.992258Z","steps":["trace[584532188] 'range keys from in-memory index tree' (duration: 159.318614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:18.992582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.139161ms","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-18T02:31:18.992656Z","caller":"traceutil/trace.go:171","msg":"trace[1305163174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4955; }","duration":"208.231272ms","start":"2026-09-18T02:31:18.784407Z","end":"2026-09-18T02:31:18.992638Z","steps":["trace[1305163174] 'range keys from in-memory index tree' (duration: 206.537611ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:18.992871Z","caller":"traceutil/trace.go:171","msg":"trace[1213597436] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"159.213182ms","start":"2026-09-18T02:31:18.833641Z","end":"2026-09-18T02:31:18.992855Z","steps":["trace[1213597436] 'process raft request' (duration: 139.271177ms)","trace[1213597436] 'compare' (duration: 19.046908ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:18.993713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.265595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-09-18T02:31:18.993919Z","caller":"traceutil/trace.go:171","msg":"trace[1776842604] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4955; }","duration":"165.497ms","start":"2026-09-18T02:31:18.828381Z","end":"2026-09-18T02:31:18.993878Z","steps":["trace[1776842604] 'range keys from in-memory index tree' (duration: 163.867369ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:19.164005Z","caller":"traceutil/trace.go:171","msg":"trace[163690339] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5160; }","duration":"117.448696ms","start":"2026-09-18T02:31:19.046546Z","end":"2026-09-18T02:31:19.163994Z","steps":["trace[163690339] 'read index received' (duration: 51.804643ms)","trace[163690339] 'applied index is now lower than readState.Index' (duration: 65.642883ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:19.164234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.968971ms","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-18T02:31:19.164254Z","caller":"traceutil/trace.go:171","msg":"trace[67273096] 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:4959; }","duration":"113.033023ms","start":"2026-09-18T02:31:19.051213Z","end":"2026-09-18T02:31:19.164246Z","steps":["trace[67273096] 'agreement among raft nodes before linearized reading' (duration: 112.908981ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:19.164547Z","caller":"traceutil/trace.go:171","msg":"trace[942851729] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"153.871519ms","start":"2026-09-18T02:31:19.010083Z","end":"2026-09-18T02:31:19.163954Z","steps":["trace[942851729] 'process raft request' (duration: 88.294197ms)","trace[942851729] 'compare' (duration: 65.390288ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:19.165137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.969543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-18T02:31:19.165227Z","caller":"traceutil/trace.go:171","msg":"trace[1454512574] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4959; }","duration":"153.070694ms","start":"2026-09-18T02:31:19.012134Z","end":"2026-09-18T02:31:19.165204Z","steps":["trace[1454512574] 'agreement among raft nodes before linearized reading' (duration: 151.967664ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:20.136799Z","caller":"traceutil/trace.go:171","msg":"trace[716967059] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"164.307824ms","start":"2026-09-18T02:31:19.972445Z","end":"2026-09-18T02:31:20.136753Z","steps":["trace[716967059] 'process raft request' (duration: 95.087045ms)","trace[716967059] 'compare' (duration: 68.574056ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:20.137808Z","caller":"traceutil/trace.go:171","msg":"trace[1712072362] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5179; }","duration":"139.343664ms","start":"2026-09-18T02:31:19.998443Z","end":"2026-09-18T02:31:20.137786Z","steps":["trace[1712072362] 'read index received' (duration: 69.006324ms)","trace[1712072362] 'applied index is now lower than readState.Index' (duration: 70.33663ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:20.137877Z","caller":"traceutil/trace.go:171","msg":"trace[1309269150] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"157.266431ms","start":"2026-09-18T02:31:19.980595Z","end":"2026-09-18T02:31:20.137862Z","steps":["trace[1309269150] 'process raft request' (duration: 157.107878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:20.138089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.61303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0deaa7f8-1060-4ea1-bd08-1edd1587798f\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-18T02:31:20.138116Z","caller":"traceutil/trace.go:171","msg":"trace[1824923575] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0deaa7f8-1060-4ea1-bd08-1edd1587798f; range_end:; response_count:1; response_revision:4979; }","duration":"139.696532ms","start":"2026-09-18T02:31:19.998411Z","end":"2026-09-18T02:31:20.138108Z","steps":["trace[1824923575] 'agreement among raft nodes before linearized reading' (duration: 139.509287ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:20.138114Z","caller":"traceutil/trace.go:171","msg":"trace[1348301174] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"165.127788ms","start":"2026-09-18T02:31:19.972954Z","end":"2026-09-18T02:31:20.138082Z","steps":["trace[1348301174] 'process raft request' (duration: 163.512728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:20.138513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.980518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1579"} {"level":"info","ts":"2026-09-18T02:31:20.138537Z","caller":"traceutil/trace.go:171","msg":"trace[93647339] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4979; }","duration":"131.037949ms","start":"2026-09-18T02:31:20.007494Z","end":"2026-09-18T02:31:20.138532Z","steps":["trace[93647339] 'agreement among raft nodes before linearized reading' (duration: 130.978728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:20.138692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.98571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1579"} {"level":"info","ts":"2026-09-18T02:31:20.138856Z","caller":"traceutil/trace.go:171","msg":"trace[1852883371] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4979; }","duration":"138.158643ms","start":"2026-09-18T02:31:20.000682Z","end":"2026-09-18T02:31:20.13884Z","steps":["trace[1852883371] 'agreement among raft nodes before linearized reading' (duration: 137.724715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:20.987775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.41184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T02:31:20.988014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.309757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-18T02:31:20.988127Z","caller":"traceutil/trace.go:171","msg":"trace[272566432] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5036; }","duration":"169.421029ms","start":"2026-09-18T02:31:20.818688Z","end":"2026-09-18T02:31:20.988109Z","steps":["trace[272566432] 'range keys from in-memory index tree' (duration: 169.180444ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:20.98817Z","caller":"traceutil/trace.go:171","msg":"trace[1537439694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5036; }","duration":"169.783557ms","start":"2026-09-18T02:31:20.818315Z","end":"2026-09-18T02:31:20.988098Z","steps":["trace[1537439694] 'range keys from in-memory index tree' (duration: 169.316567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:20.988205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.378497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7cf7d4cc5d-w9t8t.18d6497756ae262e\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-09-18T02:31:20.988295Z","caller":"traceutil/trace.go:171","msg":"trace[1186841439] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7cf7d4cc5d-w9t8t.18d6497756ae262e; range_end:; response_count:1; response_revision:5036; }","duration":"219.54287ms","start":"2026-09-18T02:31:20.768727Z","end":"2026-09-18T02:31:20.98827Z","steps":["trace[1186841439] 'range keys from in-memory index tree' (duration: 219.153563ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:23.241317Z","caller":"traceutil/trace.go:171","msg":"trace[687295388] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"177.701228ms","start":"2026-09-18T02:31:23.063589Z","end":"2026-09-18T02:31:23.241291Z","steps":["trace[687295388] 'process raft request' (duration: 177.560315ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:23.429813Z","caller":"traceutil/trace.go:171","msg":"trace[2042841988] linearizableReadLoop","detail":"{readStateIndex:5260; appliedIndex:5258; }","duration":"231.987624ms","start":"2026-09-18T02:31:23.197809Z","end":"2026-09-18T02:31:23.429797Z","steps":["trace[2042841988] 'read index received' (duration: 43.356342ms)","trace[2042841988] 'applied index is now lower than readState.Index' (duration: 188.630522ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:23.429887Z","caller":"traceutil/trace.go:171","msg":"trace[1798918901] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"257.153436ms","start":"2026-09-18T02:31:23.172725Z","end":"2026-09-18T02:31:23.429878Z","steps":["trace[1798918901] 'process raft request' (duration: 256.972732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:23.430053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.24625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-18T02:31:23.430164Z","caller":"traceutil/trace.go:171","msg":"trace[1279522962] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5056; }","duration":"232.442703ms","start":"2026-09-18T02:31:23.19771Z","end":"2026-09-18T02:31:23.430152Z","steps":["trace[1279522962] 'agreement among raft nodes before linearized reading' (duration: 232.25246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:23.430871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.021995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T02:31:23.430909Z","caller":"traceutil/trace.go:171","msg":"trace[454171058] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"128.644149ms","start":"2026-09-18T02:31:23.30222Z","end":"2026-09-18T02:31:23.430864Z","steps":["trace[454171058] 'process raft request' (duration: 128.387505ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:23.430947Z","caller":"traceutil/trace.go:171","msg":"trace[167140861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5057; }","duration":"114.135896ms","start":"2026-09-18T02:31:23.316795Z","end":"2026-09-18T02:31:23.430931Z","steps":["trace[167140861] 'agreement among raft nodes before linearized reading' (duration: 113.923643ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:28.098887Z","caller":"traceutil/trace.go:171","msg":"trace[1312557383] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5356; }","duration":"150.46962ms","start":"2026-09-18T02:31:27.948394Z","end":"2026-09-18T02:31:28.098864Z","steps":["trace[1312557383] 'read index received' (duration: 150.310367ms)","trace[1312557383] 'applied index is now lower than readState.Index' (duration: 158.333µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:28.099192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.768125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-18f74c23-e855-41e6-a3da-5399b5a52f1d\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-18T02:31:28.099255Z","caller":"traceutil/trace.go:171","msg":"trace[2029323151] range","detail":"{range_begin:/registry/persistentvolumes/pvc-18f74c23-e855-41e6-a3da-5399b5a52f1d; range_end:; response_count:1; response_revision:5149; }","duration":"150.915148ms","start":"2026-09-18T02:31:27.948322Z","end":"2026-09-18T02:31:28.099237Z","steps":["trace[2029323151] 'agreement among raft nodes before linearized reading' (duration: 150.626773ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:28.099137Z","caller":"traceutil/trace.go:171","msg":"trace[641482877] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"151.675823ms","start":"2026-09-18T02:31:27.947437Z","end":"2026-09-18T02:31:28.099113Z","steps":["trace[641482877] 'process raft request' (duration: 151.265035ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:32.092414Z","caller":"traceutil/trace.go:171","msg":"trace[522941022] linearizableReadLoop","detail":"{readStateIndex:5402; appliedIndex:5401; }","duration":"115.290207ms","start":"2026-09-18T02:31:31.977105Z","end":"2026-09-18T02:31:32.092396Z","steps":["trace[522941022] 'read index received' (duration: 115.083153ms)","trace[522941022] 'applied index is now lower than readState.Index' (duration: 206.494µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:32.092924Z","caller":"traceutil/trace.go:171","msg":"trace[1491987761] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"120.166259ms","start":"2026-09-18T02:31:31.972716Z","end":"2026-09-18T02:31:32.092883Z","steps":["trace[1491987761] 'process raft request' (duration: 119.538477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:32.09323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.045841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-18T02:31:32.093467Z","caller":"traceutil/trace.go:171","msg":"trace[672785906] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5194; }","duration":"116.290576ms","start":"2026-09-18T02:31:31.977059Z","end":"2026-09-18T02:31:32.093349Z","steps":["trace[672785906] 'agreement among raft nodes before linearized reading' (duration: 115.801577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:32.240636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.933316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-09-18T02:31:32.24071Z","caller":"traceutil/trace.go:171","msg":"trace[1029673565] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5195; }","duration":"111.061008ms","start":"2026-09-18T02:31:32.129632Z","end":"2026-09-18T02:31:32.240693Z","steps":["trace[1029673565] 'range keys from in-memory index tree' (duration: 110.772473ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:32.240711Z","caller":"traceutil/trace.go:171","msg":"trace[545062653] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"107.105334ms","start":"2026-09-18T02:31:32.133582Z","end":"2026-09-18T02:31:32.240688Z","steps":["trace[545062653] 'process raft request' (duration: 16.63924ms)","trace[545062653] 'compare' (duration: 90.24432ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:32.276922Z","caller":"traceutil/trace.go:171","msg":"trace[1158556903] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"128.321019ms","start":"2026-09-18T02:31:32.148576Z","end":"2026-09-18T02:31:32.276897Z","steps":["trace[1158556903] 'process raft request' (duration: 128.254968ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:32.277602Z","caller":"traceutil/trace.go:171","msg":"trace[1904682678] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"139.911707ms","start":"2026-09-18T02:31:32.137128Z","end":"2026-09-18T02:31:32.27704Z","steps":["trace[1904682678] 'process raft request' (duration: 139.56178ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:32.417636Z","caller":"traceutil/trace.go:171","msg":"trace[131837296] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"135.051336ms","start":"2026-09-18T02:31:32.282559Z","end":"2026-09-18T02:31:32.417611Z","steps":["trace[131837296] 'process raft request' (duration: 59.018569ms)","trace[131837296] 'compare' (duration: 75.833164ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:32.418083Z","caller":"traceutil/trace.go:171","msg":"trace[1447603245] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"103.944396ms","start":"2026-09-18T02:31:32.314072Z","end":"2026-09-18T02:31:32.418016Z","steps":["trace[1447603245] 'process raft request' (duration: 103.474047ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:32.418134Z","caller":"traceutil/trace.go:171","msg":"trace[2141179000] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"103.517568ms","start":"2026-09-18T02:31:32.314586Z","end":"2026-09-18T02:31:32.418103Z","steps":["trace[2141179000] 'process raft request' (duration: 103.361075ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:32.418209Z","caller":"traceutil/trace.go:171","msg":"trace[861777597] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"103.861135ms","start":"2026-09-18T02:31:32.314333Z","end":"2026-09-18T02:31:32.418194Z","steps":["trace[861777597] 'process raft request' (duration: 103.476268ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:32.418128Z","caller":"traceutil/trace.go:171","msg":"trace[1419889643] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"103.246023ms","start":"2026-09-18T02:31:32.314852Z","end":"2026-09-18T02:31:32.418098Z","steps":["trace[1419889643] 'process raft request' (duration: 103.182292ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:43.885119Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2796} {"level":"info","ts":"2026-09-18T02:31:43.963233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2796,"took":"76.726341ms","hash":1536839070,"current-db-size-bytes":51875840,"current-db-size":"52 MB","current-db-size-in-use-bytes":27619328,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T02:31:43.963281Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1536839070,"revision":2796,"compact-revision":-1} {"level":"warn","ts":"2026-09-18T02:32:11.84855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.426054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:32:11.848645Z","caller":"traceutil/trace.go:171","msg":"trace[615503663] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"198.126325ms","start":"2026-09-18T02:32:11.650498Z","end":"2026-09-18T02:32:11.848625Z","steps":["trace[615503663] 'process raft request' (duration: 12.551449ms)","trace[615503663] 'compare' (duration: 185.311122ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:11.907026Z","caller":"traceutil/trace.go:171","msg":"trace[649058059] linearizableReadLoop","detail":"{readStateIndex:5748; appliedIndex:5747; }","duration":"158.950651ms","start":"2026-09-18T02:32:11.748051Z","end":"2026-09-18T02:32:11.907002Z","steps":["trace[649058059] 'read index received' (duration: 158.811649ms)","trace[649058059] 'applied index is now lower than readState.Index' (duration: 138.202µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:11.907173Z","caller":"traceutil/trace.go:171","msg":"trace[1564293183] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"245.174834ms","start":"2026-09-18T02:32:11.661936Z","end":"2026-09-18T02:32:11.90711Z","steps":["trace[1564293183] 'process raft request' (duration: 244.94389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:11.907309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.240157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-18T02:32:11.907456Z","caller":"traceutil/trace.go:171","msg":"trace[333915665] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5530; }","duration":"159.44001ms","start":"2026-09-18T02:32:11.747994Z","end":"2026-09-18T02:32:11.907434Z","steps":["trace[333915665] 'agreement among raft nodes before linearized reading' (duration: 159.130724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:11.907491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.986331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T02:32:11.911051Z","caller":"traceutil/trace.go:171","msg":"trace[1376612685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5530; }","duration":"142.085103ms","start":"2026-09-18T02:32:11.765456Z","end":"2026-09-18T02:32:11.907541Z","steps":["trace[1376612685] 'agreement among raft nodes before linearized reading' (duration: 141.669745ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:12.031233Z","caller":"traceutil/trace.go:171","msg":"trace[390948586] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"119.4489ms","start":"2026-09-18T02:32:11.911756Z","end":"2026-09-18T02:32:12.031205Z","steps":["trace[390948586] 'process raft request' (duration: 119.096793ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:12.032077Z","caller":"traceutil/trace.go:171","msg":"trace[2876374] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"115.692012ms","start":"2026-09-18T02:32:11.916355Z","end":"2026-09-18T02:32:12.032047Z","steps":["trace[2876374] 'process raft request' (duration: 115.416587ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:13.156773Z","caller":"traceutil/trace.go:171","msg":"trace[838031562] linearizableReadLoop","detail":"{readStateIndex:5758; appliedIndex:5757; }","duration":"230.001544ms","start":"2026-09-18T02:32:12.926744Z","end":"2026-09-18T02:32:13.156746Z","steps":["trace[838031562] 'read index received' (duration: 209.565822ms)","trace[838031562] 'applied index is now lower than readState.Index' (duration: 20.434392ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:13.15725Z","caller":"traceutil/trace.go:171","msg":"trace[562323909] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"238.801705ms","start":"2026-09-18T02:32:12.918424Z","end":"2026-09-18T02:32:13.157226Z","steps":["trace[562323909] 'process raft request' (duration: 217.860703ms)","trace[562323909] 'compare' (duration: 20.343041ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:32:13.157669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.909811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-18T02:32:13.157831Z","caller":"traceutil/trace.go:171","msg":"trace[1786050914] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5539; }","duration":"231.112105ms","start":"2026-09-18T02:32:12.926708Z","end":"2026-09-18T02:32:13.15782Z","steps":["trace[1786050914] 'agreement among raft nodes before linearized reading' (duration: 230.24997ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:34:17.690834Z","caller":"traceutil/trace.go:171","msg":"trace[25266001] transaction","detail":"{read_only:false; response_revision:6316; number_of_response:1; }","duration":"115.3812ms","start":"2026-09-18T02:34:17.575413Z","end":"2026-09-18T02:34:17.690794Z","steps":["trace[25266001] 'process raft request' (duration: 93.741829ms)","trace[25266001] 'compare' (duration: 21.470658ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:34:24.64927Z","caller":"traceutil/trace.go:171","msg":"trace[18328439] linearizableReadLoop","detail":"{readStateIndex:6613; appliedIndex:6611; }","duration":"111.668243ms","start":"2026-09-18T02:34:24.537578Z","end":"2026-09-18T02:34:24.649247Z","steps":["trace[18328439] 'read index received' (duration: 3.232767ms)","trace[18328439] 'applied index is now lower than readState.Index' (duration: 108.434626ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:34:24.649568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.918507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-bootstrap-5lkbk\" ","response":"range_response_count:1 size:7177"} {"level":"info","ts":"2026-09-18T02:34:24.649634Z","caller":"traceutil/trace.go:171","msg":"trace[837247359] range","detail":"{range_begin:/registry/pods/openstack/keystone-bootstrap-5lkbk; range_end:; response_count:1; response_revision:6366; }","duration":"112.077429ms","start":"2026-09-18T02:34:24.53754Z","end":"2026-09-18T02:34:24.649617Z","steps":["trace[837247359] 'agreement among raft nodes before linearized reading' (duration: 111.793744ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:34:24.649715Z","caller":"traceutil/trace.go:171","msg":"trace[1964607286] transaction","detail":"{read_only:false; response_revision:6366; number_of_response:1; }","duration":"116.923084ms","start":"2026-09-18T02:34:24.532765Z","end":"2026-09-18T02:34:24.649689Z","steps":["trace[1964607286] 'process raft request' (duration: 101.817989ms)","trace[1964607286] 'compare' (duration: 14.549706ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:34:34.563265Z","caller":"traceutil/trace.go:171","msg":"trace[57169367] transaction","detail":"{read_only:false; response_revision:6431; number_of_response:1; }","duration":"107.75578ms","start":"2026-09-18T02:34:34.455483Z","end":"2026-09-18T02:34:34.563239Z","steps":["trace[57169367] 'process raft request' (duration: 44.169054ms)","trace[57169367] 'compare' (duration: 63.424803ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:34:34.563385Z","caller":"traceutil/trace.go:171","msg":"trace[228972123] transaction","detail":"{read_only:false; response_revision:6432; number_of_response:1; }","duration":"106.778674ms","start":"2026-09-18T02:34:34.456588Z","end":"2026-09-18T02:34:34.563366Z","steps":["trace[228972123] 'process raft request' (duration: 106.619991ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:34:58.764332Z","caller":"traceutil/trace.go:171","msg":"trace[1507474502] transaction","detail":"{read_only:false; response_revision:6702; number_of_response:1; }","duration":"109.628147ms","start":"2026-09-18T02:34:58.654679Z","end":"2026-09-18T02:34:58.764308Z","steps":["trace[1507474502] 'process raft request' (duration: 109.512835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:35:07.265311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.138241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-09-18T02:35:07.265564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.700189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:35:07.265561Z","caller":"traceutil/trace.go:171","msg":"trace[733245984] transaction","detail":"{read_only:false; response_revision:6755; number_of_response:1; }","duration":"139.082056ms","start":"2026-09-18T02:35:07.126407Z","end":"2026-09-18T02:35:07.265489Z","steps":["trace[733245984] 'process raft request' (duration: 19.688318ms)","trace[733245984] 'compare' (duration: 118.974155ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:35:07.265608Z","caller":"traceutil/trace.go:171","msg":"trace[747025576] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6755; }","duration":"123.772281ms","start":"2026-09-18T02:35:07.141823Z","end":"2026-09-18T02:35:07.265595Z","steps":["trace[747025576] 'agreement among raft nodes before linearized reading' (duration: 123.671048ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:35:07.26567Z","caller":"traceutil/trace.go:171","msg":"trace[2010962198] linearizableReadLoop","detail":"{readStateIndex:7014; appliedIndex:7013; }","duration":"123.632967ms","start":"2026-09-18T02:35:07.141857Z","end":"2026-09-18T02:35:07.26549Z","steps":["trace[2010962198] 'read index received' (duration: 4.166967ms)","trace[2010962198] 'applied index is now lower than readState.Index' (duration: 119.46444ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:20.111781Z","caller":"traceutil/trace.go:171","msg":"trace[730049433] transaction","detail":"{read_only:false; response_revision:7211; number_of_response:1; }","duration":"140.281998ms","start":"2026-09-18T02:36:19.971478Z","end":"2026-09-18T02:36:20.11176Z","steps":["trace[730049433] 'process raft request' (duration: 140.127102ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:23.575428Z","caller":"traceutil/trace.go:171","msg":"trace[1512981202] transaction","detail":"{read_only:false; response_revision:7227; number_of_response:1; }","duration":"299.381467ms","start":"2026-09-18T02:36:23.276023Z","end":"2026-09-18T02:36:23.575405Z","steps":["trace[1512981202] 'process raft request' (duration: 298.983017ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:23.575429Z","caller":"traceutil/trace.go:171","msg":"trace[1633073681] linearizableReadLoop","detail":"{readStateIndex:7506; appliedIndex:7505; }","duration":"108.363047ms","start":"2026-09-18T02:36:23.467036Z","end":"2026-09-18T02:36:23.575399Z","steps":["trace[1633073681] 'read index received' (duration: 107.887505ms)","trace[1633073681] 'applied index is now lower than readState.Index' (duration: 473.522µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:36:23.575982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.918343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T02:36:23.576039Z","caller":"traceutil/trace.go:171","msg":"trace[1500323641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7227; }","duration":"109.033016ms","start":"2026-09-18T02:36:23.466993Z","end":"2026-09-18T02:36:23.576026Z","steps":["trace[1500323641] 'agreement among raft nodes before linearized reading' (duration: 108.492321ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:23.709947Z","caller":"traceutil/trace.go:171","msg":"trace[1170331376] transaction","detail":"{read_only:false; response_revision:7229; number_of_response:1; }","duration":"128.475548ms","start":"2026-09-18T02:36:23.581374Z","end":"2026-09-18T02:36:23.709849Z","steps":["trace[1170331376] 'process raft request' (duration: 96.227075ms)","trace[1170331376] 'compare' (duration: 32.091829ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:24.663557Z","caller":"traceutil/trace.go:171","msg":"trace[974053536] linearizableReadLoop","detail":"{readStateIndex:7511; appliedIndex:7510; }","duration":"108.801576ms","start":"2026-09-18T02:36:24.554731Z","end":"2026-09-18T02:36:24.663532Z","steps":["trace[974053536] 'read index received' (duration: 33.127715ms)","trace[974053536] 'applied index is now lower than readState.Index' (duration: 75.672921ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:36:24.663781Z","caller":"traceutil/trace.go:171","msg":"trace[1318525644] transaction","detail":"{read_only:false; response_revision:7232; number_of_response:1; }","duration":"134.218101ms","start":"2026-09-18T02:36:24.529386Z","end":"2026-09-18T02:36:24.663605Z","steps":["trace[1318525644] 'process raft request' (duration: 58.522581ms)","trace[1318525644] 'compare' (duration: 75.514394ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:36:24.663798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.047952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T02:36:24.663912Z","caller":"traceutil/trace.go:171","msg":"trace[739995320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7232; }","duration":"109.207147ms","start":"2026-09-18T02:36:24.554692Z","end":"2026-09-18T02:36:24.663899Z","steps":["trace[739995320] 'agreement among raft nodes before linearized reading' (duration: 108.96567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:36:24.663957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.677442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com\" ","response":"range_response_count:1 size:156632"} {"level":"info","ts":"2026-09-18T02:36:24.664342Z","caller":"traceutil/trace.go:171","msg":"trace[875420333] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagerconfigs.monitoring.coreos.com; range_end:; response_count:1; response_revision:7232; }","duration":"108.901059ms","start":"2026-09-18T02:36:24.555221Z","end":"2026-09-18T02:36:24.664122Z","steps":["trace[875420333] 'agreement among raft nodes before linearized reading' (duration: 108.468067ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:36:43.899268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5274} {"level":"info","ts":"2026-09-18T02:36:43.980182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5274,"took":"78.56694ms","hash":303198595,"current-db-size-bytes":51875840,"current-db-size":"52 MB","current-db-size-in-use-bytes":18935808,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T02:36:43.98024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":303198595,"revision":5274,"compact-revision":2796}