{"level":"warn","ts":"2026-09-18T05:16:43.971086Z","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-18T05:16:43.971181Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.24: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.24:2380","--initial-cluster=instance=https://162.253.55.24:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.24:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.24: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-18T05:16:43.971249Z","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-18T05:16:43.971264Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.24:2380"]} {"level":"info","ts":"2026-09-18T05:16:43.971284Z","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-18T05:16:43.972292Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.24:2379"]} {"level":"info","ts":"2026-09-18T05:16:43.972401Z","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.24:2380"],"listen-peer-urls":["https://162.253.55.24:2380"],"advertise-client-urls":["https://162.253.55.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.24: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-18T05:16:43.976556Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.900709ms"} {"level":"info","ts":"2026-09-18T05:16:43.98258Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7a47c02f9bc0b904","cluster-id":"318a7363fda604da"} {"level":"info","ts":"2026-09-18T05:16:43.98265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:16:43.982669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became follower at term 0"} {"level":"info","ts":"2026-09-18T05:16:43.982682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7a47c02f9bc0b904 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:16:43.98269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became follower at term 1"} {"level":"info","ts":"2026-09-18T05:16:43.982735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 switched to configuration voters=(8811222506682759428)"} {"level":"warn","ts":"2026-09-18T05:16:43.994413Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:16:43.995727Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:16:43.997606Z","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-18T05:16:44.000206Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7a47c02f9bc0b904","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:16:44.000349Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7a47c02f9bc0b904","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:16:44.000639Z","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-18T05:16:44.000729Z","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-18T05:16:44.000743Z","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-18T05:16:44.001202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 switched to configuration voters=(8811222506682759428)"} {"level":"info","ts":"2026-09-18T05:16:44.001312Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"318a7363fda604da","local-member-id":"7a47c02f9bc0b904","added-peer-id":"7a47c02f9bc0b904","added-peer-peer-urls":["https://162.253.55.24:2380"]} {"level":"info","ts":"2026-09-18T05:16:44.004626Z","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-18T05:16:44.004808Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7a47c02f9bc0b904","initial-advertise-peer-urls":["https://162.253.55.24:2380"],"listen-peer-urls":["https://162.253.55.24:2380"],"advertise-client-urls":["https://162.253.55.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:16:44.004834Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:16:44.005042Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.24:2380"} {"level":"info","ts":"2026-09-18T05:16:44.005054Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.24:2380"} {"level":"info","ts":"2026-09-18T05:16:44.184082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:16:44.18425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:16:44.184368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 received MsgPreVoteResp from 7a47c02f9bc0b904 at term 1"} {"level":"info","ts":"2026-09-18T05:16:44.184407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:16:44.184482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 received MsgVoteResp from 7a47c02f9bc0b904 at term 2"} {"level":"info","ts":"2026-09-18T05:16:44.184507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became leader at term 2"} {"level":"info","ts":"2026-09-18T05:16:44.184578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7a47c02f9bc0b904 elected leader 7a47c02f9bc0b904 at term 2"} {"level":"info","ts":"2026-09-18T05:16:44.185806Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:16:44.186441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:16:44.186445Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7a47c02f9bc0b904","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.24:2379]}","request-path":"/0/members/7a47c02f9bc0b904/attributes","cluster-id":"318a7363fda604da","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:16:44.18657Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:16:44.186819Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:16:44.186932Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:16:44.187127Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"318a7363fda604da","local-member-id":"7a47c02f9bc0b904","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:16:44.187289Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:16:44.187339Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:16:44.190365Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:16:44.197544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.24:2379"} {"level":"info","ts":"2026-09-18T05:16:49.189265Z","caller":"traceutil/trace.go:171","msg":"trace[150612803] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"207.292515ms","start":"2026-09-18T05:16:48.981944Z","end":"2026-09-18T05:16:49.189236Z","steps":["trace[150612803] 'process raft request' (duration: 207.144112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:49.634495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.580081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:16:49.634757Z","caller":"traceutil/trace.go:171","msg":"trace[434042449] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"438.481245ms","start":"2026-09-18T05:16:49.196253Z","end":"2026-09-18T05:16:49.634734Z","steps":["trace[434042449] 'process raft request' (duration: 125.19499ms)","trace[434042449] 'compare' (duration: 312.462569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:16:49.634891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:16:49.196238Z","time spent":"438.615817ms","remote":"127.0.0.1:52560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:16:49.635114Z","caller":"traceutil/trace.go:171","msg":"trace[2006834191] linearizableReadLoop","detail":"{readStateIndex:221; appliedIndex:220; }","duration":"308.608943ms","start":"2026-09-18T05:16:49.326492Z","end":"2026-09-18T05:16:49.635101Z","steps":["trace[2006834191] 'read index received' (duration: 28.65µs)","trace[2006834191] 'applied index is now lower than readState.Index' (duration: 308.579103ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:16:49.635344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.840647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:49.635786Z","caller":"traceutil/trace.go:171","msg":"trace[343627033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:216; }","duration":"309.320777ms","start":"2026-09-18T05:16:49.326452Z","end":"2026-09-18T05:16:49.635772Z","steps":["trace[343627033] 'agreement among raft nodes before linearized reading' (duration: 308.839288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:49.635925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:16:49.326432Z","time spent":"309.47903ms","remote":"127.0.0.1:52756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-18T05:17:28.30018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.495233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T05:17:28.300273Z","caller":"traceutil/trace.go:171","msg":"trace[100096496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"185.616606ms","start":"2026-09-18T05:17:28.114622Z","end":"2026-09-18T05:17:28.300238Z","steps":["trace[100096496] 'range keys from in-memory index tree' (duration: 185.30163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:28.300332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.815253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-09-18T05:17:28.300523Z","caller":"traceutil/trace.go:171","msg":"trace[894578651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"185.067736ms","start":"2026-09-18T05:17:28.115406Z","end":"2026-09-18T05:17:28.300473Z","steps":["trace[894578651] 'range keys from in-memory index tree' (duration: 184.6321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:28.573911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.568663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:17:28.574073Z","caller":"traceutil/trace.go:171","msg":"trace[1774886151] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"244.529987ms","start":"2026-09-18T05:17:28.329503Z","end":"2026-09-18T05:17:28.574033Z","steps":["trace[1774886151] 'read index received' (duration: 59.130965ms)","trace[1774886151] 'applied index is now lower than readState.Index' (duration: 185.397712ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:28.574162Z","caller":"traceutil/trace.go:171","msg":"trace[1208849857] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"270.109435ms","start":"2026-09-18T05:17:28.30402Z","end":"2026-09-18T05:17:28.57413Z","steps":["trace[1208849857] 'process raft request' (duration: 168.034834ms)","trace[1208849857] 'compare' (duration: 101.39201ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:17:28.574734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.212197ms","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-18T05:17:28.574812Z","caller":"traceutil/trace.go:171","msg":"trace[1784985979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"245.321979ms","start":"2026-09-18T05:17:28.329468Z","end":"2026-09-18T05:17:28.57479Z","steps":["trace[1784985979] 'agreement among raft nodes before linearized reading' (duration: 244.76986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:28.659102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.347369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2tqdf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-18T05:17:28.659114Z","caller":"traceutil/trace.go:171","msg":"trace[108141826] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"250.438491ms","start":"2026-09-18T05:17:28.408638Z","end":"2026-09-18T05:17:28.659076Z","steps":["trace[108141826] 'process raft request' (duration: 243.790725ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:28.659192Z","caller":"traceutil/trace.go:171","msg":"trace[1884855970] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2tqdf; range_end:; response_count:1; response_revision:638; }","duration":"270.473351ms","start":"2026-09-18T05:17:28.388699Z","end":"2026-09-18T05:17:28.659172Z","steps":["trace[1884855970] 'agreement among raft nodes before linearized reading' (duration: 270.299668ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:29.309113Z","caller":"traceutil/trace.go:171","msg":"trace[214218922] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"102.580362ms","start":"2026-09-18T05:17:29.206508Z","end":"2026-09-18T05:17:29.309089Z","steps":["trace[214218922] 'read index received' (duration: 102.375048ms)","trace[214218922] 'applied index is now lower than readState.Index' (duration: 204.454µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:17:29.309258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.734185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:17:29.309289Z","caller":"traceutil/trace.go:171","msg":"trace[1323519406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"102.819846ms","start":"2026-09-18T05:17:29.206458Z","end":"2026-09-18T05:17:29.309278Z","steps":["trace[1323519406] 'agreement among raft nodes before linearized reading' (duration: 102.731884ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:29.309442Z","caller":"traceutil/trace.go:171","msg":"trace[1443969367] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"571.986867ms","start":"2026-09-18T05:17:28.737422Z","end":"2026-09-18T05:17:29.309409Z","steps":["trace[1443969367] 'process raft request' (duration: 571.53961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:29.309564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:28.737396Z","time spent":"572.104659ms","remote":"127.0.0.1:52976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:17:30.72402Z","caller":"traceutil/trace.go:171","msg":"trace[466769873] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"140.020358ms","start":"2026-09-18T05:17:30.583971Z","end":"2026-09-18T05:17:30.723992Z","steps":["trace[466769873] 'process raft request' (duration: 139.192855ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.731435Z","caller":"traceutil/trace.go:171","msg":"trace[562558378] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"146.166665ms","start":"2026-09-18T05:17:30.585186Z","end":"2026-09-18T05:17:30.731353Z","steps":["trace[562558378] 'process raft request' (duration: 145.85301ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:37.744534Z","caller":"traceutil/trace.go:171","msg":"trace[99959782] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"195.43091ms","start":"2026-09-18T05:17:37.549074Z","end":"2026-09-18T05:17:37.744505Z","steps":["trace[99959782] 'process raft request' (duration: 188.23771ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:45.127839Z","caller":"traceutil/trace.go:171","msg":"trace[1918984451] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"153.328965ms","start":"2026-09-18T05:17:44.974489Z","end":"2026-09-18T05:17:45.127818Z","steps":["trace[1918984451] 'process raft request' (duration: 153.272274ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:45.127922Z","caller":"traceutil/trace.go:171","msg":"trace[1149714433] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"153.401956ms","start":"2026-09-18T05:17:44.974478Z","end":"2026-09-18T05:17:45.12788Z","steps":["trace[1149714433] 'process raft request' (duration: 147.694191ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:31.516554Z","caller":"traceutil/trace.go:171","msg":"trace[2066420588] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"110.356183ms","start":"2026-09-18T05:18:31.406172Z","end":"2026-09-18T05:18:31.516528Z","steps":["trace[2066420588] 'process raft request' (duration: 110.177731ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:38.967503Z","caller":"traceutil/trace.go:171","msg":"trace[2137042682] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"165.184522ms","start":"2026-09-18T05:18:38.802303Z","end":"2026-09-18T05:18:38.967487Z","steps":["trace[2137042682] 'process raft request' (duration: 165.07389ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:02.807384Z","caller":"traceutil/trace.go:171","msg":"trace[136228393] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"174.316122ms","start":"2026-09-18T05:19:02.633029Z","end":"2026-09-18T05:19:02.807346Z","steps":["trace[136228393] 'process raft request' (duration: 174.053218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:03.038834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.628642ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-18T05:19:03.039401Z","caller":"traceutil/trace.go:171","msg":"trace[1109858307] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"213.690549ms","start":"2026-09-18T05:19:02.825685Z","end":"2026-09-18T05:19:03.039376Z","steps":["trace[1109858307] 'process raft request' (duration: 213.319264ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:03.040282Z","caller":"traceutil/trace.go:171","msg":"trace[1524096382] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1908; }","duration":"342.880161ms","start":"2026-09-18T05:19:02.697354Z","end":"2026-09-18T05:19:03.040234Z","steps":["trace[1524096382] 'read index received' (duration: 109.787217ms)","trace[1524096382] 'applied index is now lower than readState.Index' (duration: 233.088454ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:03.040737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.364937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-09-18T05:19:03.040811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.49552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9032"} {"level":"info","ts":"2026-09-18T05:19:03.040827Z","caller":"traceutil/trace.go:171","msg":"trace[1969317708] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"343.527579ms","start":"2026-09-18T05:19:02.697283Z","end":"2026-09-18T05:19:03.040811Z","steps":["trace[1969317708] 'agreement among raft nodes before linearized reading' (duration: 343.076294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:03.041103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:02.697254Z","time spent":"343.828523ms","remote":"127.0.0.1:50594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-18T05:19:03.040921Z","caller":"traceutil/trace.go:171","msg":"trace[141900192] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1858; }","duration":"173.715543ms","start":"2026-09-18T05:19:02.867186Z","end":"2026-09-18T05:19:03.040902Z","steps":["trace[141900192] 'agreement among raft nodes before linearized reading' (duration: 173.361839ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:03.269946Z","caller":"traceutil/trace.go:171","msg":"trace[1515298564] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"143.400109ms","start":"2026-09-18T05:19:03.126521Z","end":"2026-09-18T05:19:03.269921Z","steps":["trace[1515298564] 'process raft request' (duration: 143.120095ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:03.270111Z","caller":"traceutil/trace.go:171","msg":"trace[1401799558] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"142.712501ms","start":"2026-09-18T05:19:03.127355Z","end":"2026-09-18T05:19:03.270067Z","steps":["trace[1401799558] 'process raft request' (duration: 142.428097ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:03.270669Z","caller":"traceutil/trace.go:171","msg":"trace[284808131] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"141.652917ms","start":"2026-09-18T05:19:03.128993Z","end":"2026-09-18T05:19:03.270646Z","steps":["trace[284808131] 'process raft request' (duration: 140.852877ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:03.270701Z","caller":"traceutil/trace.go:171","msg":"trace[1583391929] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"141.583747ms","start":"2026-09-18T05:19:03.129103Z","end":"2026-09-18T05:19:03.270687Z","steps":["trace[1583391929] 'process raft request' (duration: 141.431235ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:06.260566Z","caller":"traceutil/trace.go:171","msg":"trace[2084333832] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"119.350299ms","start":"2026-09-18T05:19:06.141174Z","end":"2026-09-18T05:19:06.260525Z","steps":["trace[2084333832] 'process raft request' (duration: 79.486968ms)","trace[2084333832] 'compare' (duration: 39.458686ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:09.865522Z","caller":"traceutil/trace.go:171","msg":"trace[1059558147] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"111.84994ms","start":"2026-09-18T05:19:09.753641Z","end":"2026-09-18T05:19:09.865491Z","steps":["trace[1059558147] 'process raft request' (duration: 111.698809ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:20.594785Z","caller":"traceutil/trace.go:171","msg":"trace[2116677037] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"104.804498ms","start":"2026-09-18T05:19:20.489944Z","end":"2026-09-18T05:19:20.594748Z","steps":["trace[2116677037] 'process raft request' (duration: 104.445083ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:22.219964Z","caller":"traceutil/trace.go:171","msg":"trace[70708941] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"188.437151ms","start":"2026-09-18T05:19:22.031492Z","end":"2026-09-18T05:19:22.219929Z","steps":["trace[70708941] 'process raft request' (duration: 101.940791ms)","trace[70708941] 'compare' (duration: 86.341298ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:22.224979Z","caller":"traceutil/trace.go:171","msg":"trace[655223132] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2101; }","duration":"157.095632ms","start":"2026-09-18T05:19:22.067849Z","end":"2026-09-18T05:19:22.224944Z","steps":["trace[655223132] 'read index received' (duration: 65.628182ms)","trace[655223132] 'applied index is now lower than readState.Index' (duration: 91.46554ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:22.225244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.370346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-18T05:19:22.225326Z","caller":"traceutil/trace.go:171","msg":"trace[1636198210] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2043; }","duration":"157.488548ms","start":"2026-09-18T05:19:22.067818Z","end":"2026-09-18T05:19:22.225306Z","steps":["trace[1636198210] 'agreement among raft nodes before linearized reading' (duration: 157.271145ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:22.225051Z","caller":"traceutil/trace.go:171","msg":"trace[191247048] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"188.554881ms","start":"2026-09-18T05:19:22.036471Z","end":"2026-09-18T05:19:22.225026Z","steps":["trace[191247048] 'process raft request' (duration: 188.252678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:22.820337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.273449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-18T05:19:23.56847Z","caller":"traceutil/trace.go:171","msg":"trace[637179143] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"116.409229ms","start":"2026-09-18T05:19:23.452032Z","end":"2026-09-18T05:19:23.568441Z","steps":["trace[637179143] 'process raft request' (duration: 116.220756ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:23.616212Z","caller":"traceutil/trace.go:171","msg":"trace[1899012491] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"118.140179ms","start":"2026-09-18T05:19:23.49804Z","end":"2026-09-18T05:19:23.61618Z","steps":["trace[1899012491] 'process raft request' (duration: 117.945067ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:23.787302Z","caller":"traceutil/trace.go:171","msg":"trace[819503374] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"104.848375ms","start":"2026-09-18T05:19:23.682423Z","end":"2026-09-18T05:19:23.787271Z","steps":["trace[819503374] 'process raft request' (duration: 104.664423ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:23.834101Z","caller":"traceutil/trace.go:171","msg":"trace[725463990] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"149.188693ms","start":"2026-09-18T05:19:23.684886Z","end":"2026-09-18T05:19:23.834075Z","steps":["trace[725463990] 'process raft request' (duration: 149.021041ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:23.834184Z","caller":"traceutil/trace.go:171","msg":"trace[1401827873] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"148.584366ms","start":"2026-09-18T05:19:23.685546Z","end":"2026-09-18T05:19:23.834131Z","steps":["trace[1401827873] 'process raft request' (duration: 148.474005ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:23.968459Z","caller":"traceutil/trace.go:171","msg":"trace[1716449693] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"114.712938ms","start":"2026-09-18T05:19:23.85372Z","end":"2026-09-18T05:19:23.968433Z","steps":["trace[1716449693] 'process raft request' (duration: 114.560006ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:26.937548Z","caller":"traceutil/trace.go:171","msg":"trace[1939435269] linearizableReadLoop","detail":"{readStateIndex:2133; appliedIndex:2132; }","duration":"193.994071ms","start":"2026-09-18T05:19:26.743529Z","end":"2026-09-18T05:19:26.937523Z","steps":["trace[1939435269] 'read index received' (duration: 134.418607ms)","trace[1939435269] 'applied index is now lower than readState.Index' (duration: 59.573774ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:26.937772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.232614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-09-18T05:19:26.937829Z","caller":"traceutil/trace.go:171","msg":"trace[825041887] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2072; }","duration":"194.356205ms","start":"2026-09-18T05:19:26.743456Z","end":"2026-09-18T05:19:26.937812Z","steps":["trace[825041887] 'agreement among raft nodes before linearized reading' (duration: 194.208164ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:26.938002Z","caller":"traceutil/trace.go:171","msg":"trace[2097840840] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"200.694893ms","start":"2026-09-18T05:19:26.737294Z","end":"2026-09-18T05:19:26.937989Z","steps":["trace[2097840840] 'process raft request' (duration: 140.629122ms)","trace[2097840840] 'compare' (duration: 59.442983ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:27.603917Z","caller":"traceutil/trace.go:171","msg":"trace[1413742807] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"175.067856ms","start":"2026-09-18T05:19:27.428823Z","end":"2026-09-18T05:19:27.603891Z","steps":["trace[1413742807] 'process raft request' (duration: 174.885033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:27.895142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.295484ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-09-18T05:19:27.895593Z","caller":"traceutil/trace.go:171","msg":"trace[2101445932] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2078; }","duration":"284.612113ms","start":"2026-09-18T05:19:27.610966Z","end":"2026-09-18T05:19:27.895578Z","steps":["trace[2101445932] 'process raft request' (duration: 284.271168ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:27.895955Z","caller":"traceutil/trace.go:171","msg":"trace[1934972017] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"254.538433ms","start":"2026-09-18T05:19:27.641393Z","end":"2026-09-18T05:19:27.895932Z","steps":["trace[1934972017] 'process raft request' (duration: 253.899655ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:28.145629Z","caller":"traceutil/trace.go:171","msg":"trace[532785163] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"245.748634ms","start":"2026-09-18T05:19:27.89984Z","end":"2026-09-18T05:19:28.145588Z","steps":["trace[532785163] 'process raft request' (duration: 216.551295ms)","trace[532785163] 'compare' (duration: 28.893825ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:28.145849Z","caller":"traceutil/trace.go:171","msg":"trace[1245926650] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2143; }","duration":"233.483353ms","start":"2026-09-18T05:19:27.91234Z","end":"2026-09-18T05:19:28.145823Z","steps":["trace[1245926650] 'read index received' (duration: 203.94779ms)","trace[1245926650] 'applied index is now lower than readState.Index' (duration: 29.534433ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:28.145978Z","caller":"traceutil/trace.go:171","msg":"trace[2029623377] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"246.121169ms","start":"2026-09-18T05:19:27.899841Z","end":"2026-09-18T05:19:28.145962Z","steps":["trace[2029623377] 'process raft request' (duration: 245.670883ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:28.146313Z","caller":"traceutil/trace.go:171","msg":"trace[1754791769] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"238.732028ms","start":"2026-09-18T05:19:27.907566Z","end":"2026-09-18T05:19:28.146298Z","steps":["trace[1754791769] 'process raft request' (duration: 238.139021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:28.146632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.278733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-09-18T05:19:28.1467Z","caller":"traceutil/trace.go:171","msg":"trace[433879908] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2083; }","duration":"234.385615ms","start":"2026-09-18T05:19:27.912298Z","end":"2026-09-18T05:19:28.146683Z","steps":["trace[433879908] 'agreement among raft nodes before linearized reading' (duration: 234.267613ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:28.441712Z","caller":"traceutil/trace.go:171","msg":"trace[221430771] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"193.130406ms","start":"2026-09-18T05:19:28.248533Z","end":"2026-09-18T05:19:28.441663Z","steps":["trace[221430771] 'process raft request' (duration: 192.948764ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:28.441791Z","caller":"traceutil/trace.go:171","msg":"trace[570103862] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"195.256921ms","start":"2026-09-18T05:19:28.24651Z","end":"2026-09-18T05:19:28.441767Z","steps":["trace[570103862] 'process raft request' (duration: 133.599363ms)","trace[570103862] 'compare' (duration: 61.250563ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:35.552178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.360633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-18T05:19:35.552312Z","caller":"traceutil/trace.go:171","msg":"trace[546308666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2129; }","duration":"107.522715ms","start":"2026-09-18T05:19:35.44474Z","end":"2026-09-18T05:19:35.552262Z","steps":["trace[546308666] 'range keys from in-memory index tree' (duration: 107.174771ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:41.230398Z","caller":"traceutil/trace.go:171","msg":"trace[425290175] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"139.153455ms","start":"2026-09-18T05:19:41.091207Z","end":"2026-09-18T05:19:41.230361Z","steps":["trace[425290175] 'process raft request' (duration: 138.810851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:41.488127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.489406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:19:41.4883Z","caller":"traceutil/trace.go:171","msg":"trace[369843598] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"125.870913ms","start":"2026-09-18T05:19:41.362411Z","end":"2026-09-18T05:19:41.488282Z","steps":["trace[369843598] 'read index received' (duration: 41.351µs)","trace[369843598] 'applied index is now lower than readState.Index' (duration: 125.827222ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:41.488401Z","caller":"traceutil/trace.go:171","msg":"trace[315711478] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"240.57841ms","start":"2026-09-18T05:19:41.247807Z","end":"2026-09-18T05:19:41.488386Z","steps":["trace[315711478] 'process raft request' (duration: 92.71766ms)","trace[315711478] 'compare' (duration: 147.263063ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:41.488582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.178117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:19:41.488613Z","caller":"traceutil/trace.go:171","msg":"trace[204471974] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2168; }","duration":"126.210997ms","start":"2026-09-18T05:19:41.362389Z","end":"2026-09-18T05:19:41.4886Z","steps":["trace[204471974] 'agreement among raft nodes before linearized reading' (duration: 126.145746ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:41.850763Z","caller":"traceutil/trace.go:171","msg":"trace[623097243] linearizableReadLoop","detail":"{readStateIndex:2238; appliedIndex:2237; }","duration":"266.674738ms","start":"2026-09-18T05:19:41.58406Z","end":"2026-09-18T05:19:41.850735Z","steps":["trace[623097243] 'read index received' (duration: 262.209294ms)","trace[623097243] 'applied index is now lower than readState.Index' (duration: 4.464084ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:41.85091Z","caller":"traceutil/trace.go:171","msg":"trace[1193597052] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"274.400613ms","start":"2026-09-18T05:19:41.576474Z","end":"2026-09-18T05:19:41.850874Z","steps":["trace[1193597052] 'process raft request' (duration: 269.874238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:41.851036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.956611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-18T05:19:41.851037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.990551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-09-18T05:19:41.851085Z","caller":"traceutil/trace.go:171","msg":"trace[928431752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2171; }","duration":"267.011962ms","start":"2026-09-18T05:19:41.584057Z","end":"2026-09-18T05:19:41.851069Z","steps":["trace[928431752] 'agreement among raft nodes before linearized reading' (duration: 266.797589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:41.851035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.616639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-09-18T05:19:41.85112Z","caller":"traceutil/trace.go:171","msg":"trace[1743519811] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2171; }","duration":"258.731262ms","start":"2026-09-18T05:19:41.592381Z","end":"2026-09-18T05:19:41.851112Z","steps":["trace[1743519811] 'agreement among raft nodes before linearized reading' (duration: 258.521929ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:41.85109Z","caller":"traceutil/trace.go:171","msg":"trace[1837784824] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2171; }","duration":"184.091092ms","start":"2026-09-18T05:19:41.666984Z","end":"2026-09-18T05:19:41.851076Z","steps":["trace[1837784824] 'agreement among raft nodes before linearized reading' (duration: 183.95105ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:19.401949Z","caller":"traceutil/trace.go:171","msg":"trace[1014640484] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"126.951208ms","start":"2026-09-18T05:20:19.274736Z","end":"2026-09-18T05:20:19.401688Z","steps":["trace[1014640484] 'process raft request' (duration: 122.335635ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:19.406432Z","caller":"traceutil/trace.go:171","msg":"trace[1751017511] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"131.616923ms","start":"2026-09-18T05:20:19.27479Z","end":"2026-09-18T05:20:19.406407Z","steps":["trace[1751017511] 'process raft request' (duration: 131.435255ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:19.406825Z","caller":"traceutil/trace.go:171","msg":"trace[430579453] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"131.904255ms","start":"2026-09-18T05:20:19.274887Z","end":"2026-09-18T05:20:19.406792Z","steps":["trace[430579453] 'process raft request' (duration: 131.481136ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:19.40702Z","caller":"traceutil/trace.go:171","msg":"trace[954177559] transaction","detail":"{read_only:false; response_revision:2487; number_of_response:1; }","duration":"129.424476ms","start":"2026-09-18T05:20:19.277577Z","end":"2026-09-18T05:20:19.407002Z","steps":["trace[954177559] 'process raft request' (duration: 129.142594ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:19.407017Z","caller":"traceutil/trace.go:171","msg":"trace[1584715334] linearizableReadLoop","detail":"{readStateIndex:2574; appliedIndex:2570; }","duration":"123.960255ms","start":"2026-09-18T05:20:19.282973Z","end":"2026-09-18T05:20:19.406933Z","steps":["trace[1584715334] 'read index received' (duration: 114.169795ms)","trace[1584715334] 'applied index is now lower than readState.Index' (duration: 9.78941ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:20:19.407381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.385544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-09-18T05:20:19.407546Z","caller":"traceutil/trace.go:171","msg":"trace[502957155] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2487; }","duration":"124.52002ms","start":"2026-09-18T05:20:19.282946Z","end":"2026-09-18T05:20:19.407466Z","steps":["trace[502957155] 'agreement among raft nodes before linearized reading' (duration: 124.110122ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:36.772876Z","caller":"traceutil/trace.go:171","msg":"trace[461432361] linearizableReadLoop","detail":"{readStateIndex:2740; appliedIndex:2739; }","duration":"260.146056ms","start":"2026-09-18T05:20:36.512688Z","end":"2026-09-18T05:20:36.772834Z","steps":["trace[461432361] 'read index received' (duration: 259.97543ms)","trace[461432361] 'applied index is now lower than readState.Index' (duration: 169.756µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:20:36.773087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.368846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T05:20:36.773121Z","caller":"traceutil/trace.go:171","msg":"trace[54129467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2647; }","duration":"260.493671ms","start":"2026-09-18T05:20:36.512618Z","end":"2026-09-18T05:20:36.773111Z","steps":["trace[54129467] 'agreement among raft nodes before linearized reading' (duration: 260.317124ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:36.77342Z","caller":"traceutil/trace.go:171","msg":"trace[1230549839] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"265.65226ms","start":"2026-09-18T05:20:36.507292Z","end":"2026-09-18T05:20:36.772945Z","steps":["trace[1230549839] 'process raft request' (duration: 265.419041ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:55.478174Z","caller":"traceutil/trace.go:171","msg":"trace[1186148738] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"144.569895ms","start":"2026-09-18T05:20:55.333583Z","end":"2026-09-18T05:20:55.478153Z","steps":["trace[1186148738] 'process raft request' (duration: 143.980393ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:11.910152Z","caller":"traceutil/trace.go:171","msg":"trace[1236013248] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"203.298695ms","start":"2026-09-18T05:21:11.706827Z","end":"2026-09-18T05:21:11.910126Z","steps":["trace[1236013248] 'process raft request' (duration: 203.131749ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:11.910291Z","caller":"traceutil/trace.go:171","msg":"trace[841645613] linearizableReadLoop","detail":"{readStateIndex:2996; appliedIndex:2996; }","duration":"186.670928ms","start":"2026-09-18T05:21:11.723585Z","end":"2026-09-18T05:21:11.910256Z","steps":["trace[841645613] 'read index received' (duration: 186.659997ms)","trace[841645613] 'applied index is now lower than readState.Index' (duration: 8.451µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:11.910627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.026361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-09-18T05:21:11.910686Z","caller":"traceutil/trace.go:171","msg":"trace[2063769142] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2888; }","duration":"187.129364ms","start":"2026-09-18T05:21:11.723541Z","end":"2026-09-18T05:21:11.910671Z","steps":["trace[2063769142] 'agreement among raft nodes before linearized reading' (duration: 186.924807ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:30.139728Z","caller":"traceutil/trace.go:171","msg":"trace[917585528] transaction","detail":"{read_only:false; response_revision:4375; number_of_response:1; }","duration":"101.086048ms","start":"2026-09-18T05:23:30.038587Z","end":"2026-09-18T05:23:30.139673Z","steps":["trace[917585528] 'process raft request' (duration: 100.470564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:30.140374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.639848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-18T05:23:30.140425Z","caller":"traceutil/trace.go:171","msg":"trace[1807423879] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4375; }","duration":"100.71621ms","start":"2026-09-18T05:23:30.039695Z","end":"2026-09-18T05:23:30.140411Z","steps":["trace[1807423879] 'agreement among raft nodes before linearized reading' (duration: 100.478574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:43.253208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.190194ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-18T05:23:43.253447Z","caller":"traceutil/trace.go:171","msg":"trace[1294582825] linearizableReadLoop","detail":"{readStateIndex:4828; appliedIndex:4826; }","duration":"275.054536ms","start":"2026-09-18T05:23:42.978371Z","end":"2026-09-18T05:23:43.253425Z","steps":["trace[1294582825] 'read index received' (duration: 18.934454ms)","trace[1294582825] 'applied index is now lower than readState.Index' (duration: 256.119002ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:43.253741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.354992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-18T05:23:43.253777Z","caller":"traceutil/trace.go:171","msg":"trace[74113167] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4631; }","duration":"275.425594ms","start":"2026-09-18T05:23:42.978341Z","end":"2026-09-18T05:23:43.253767Z","steps":["trace[74113167] 'agreement among raft nodes before linearized reading' (duration: 275.193709ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:43.254114Z","caller":"traceutil/trace.go:171","msg":"trace[490776403] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"231.610616ms","start":"2026-09-18T05:23:43.022406Z","end":"2026-09-18T05:23:43.254017Z","steps":["trace[490776403] 'process raft request' (duration: 230.986672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:43.255475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.443476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:72 size:59901"} {"level":"warn","ts":"2026-09-18T05:23:43.255524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.900567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-18T05:23:43.255664Z","caller":"traceutil/trace.go:171","msg":"trace[2075318275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4632; }","duration":"147.04213ms","start":"2026-09-18T05:23:43.108607Z","end":"2026-09-18T05:23:43.255649Z","steps":["trace[2075318275] 'agreement among raft nodes before linearized reading' (duration: 146.391446ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:43.255822Z","caller":"traceutil/trace.go:171","msg":"trace[1692933570] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:72; response_revision:4632; }","duration":"233.599339ms","start":"2026-09-18T05:23:43.022003Z","end":"2026-09-18T05:23:43.255602Z","steps":["trace[1692933570] 'agreement among raft nodes before linearized reading' (duration: 232.742061ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:43.405899Z","caller":"traceutil/trace.go:171","msg":"trace[1046869202] linearizableReadLoop","detail":"{readStateIndex:4833; appliedIndex:4831; }","duration":"128.837052ms","start":"2026-09-18T05:23:43.277035Z","end":"2026-09-18T05:23:43.405872Z","steps":["trace[1046869202] 'read index received' (duration: 128.605157ms)","trace[1046869202] 'applied index is now lower than readState.Index' (duration: 231.045µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:43.406319Z","caller":"traceutil/trace.go:171","msg":"trace[1561477834] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"144.973705ms","start":"2026-09-18T05:23:43.261332Z","end":"2026-09-18T05:23:43.406306Z","steps":["trace[1561477834] 'process raft request' (duration: 144.368272ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:43.406505Z","caller":"traceutil/trace.go:171","msg":"trace[381360753] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"132.142304ms","start":"2026-09-18T05:23:43.274353Z","end":"2026-09-18T05:23:43.406495Z","steps":["trace[381360753] 'process raft request' (duration: 131.472049ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:43.406748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.380551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-18T05:23:43.406791Z","caller":"traceutil/trace.go:171","msg":"trace[1413200973] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4635; }","duration":"144.458943ms","start":"2026-09-18T05:23:43.26232Z","end":"2026-09-18T05:23:43.406778Z","steps":["trace[1413200973] 'agreement among raft nodes before linearized reading' (duration: 144.31286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:43.407054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.702108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-09-18T05:23:43.407105Z","caller":"traceutil/trace.go:171","msg":"trace[900360764] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:4635; }","duration":"127.80016ms","start":"2026-09-18T05:23:43.279293Z","end":"2026-09-18T05:23:43.407093Z","steps":["trace[900360764] 'agreement among raft nodes before linearized reading' (duration: 127.667077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:43.40782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.217773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-18T05:23:43.407864Z","caller":"traceutil/trace.go:171","msg":"trace[1775638503] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4635; }","duration":"103.363527ms","start":"2026-09-18T05:23:43.304489Z","end":"2026-09-18T05:23:43.407853Z","steps":["trace[1775638503] 'agreement among raft nodes before linearized reading' (duration: 103.261714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:43.796324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.36527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:23:43.796941Z","caller":"traceutil/trace.go:171","msg":"trace[1391373076] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"262.298526ms","start":"2026-09-18T05:23:43.5346Z","end":"2026-09-18T05:23:43.796899Z","steps":["trace[1391373076] 'process raft request' (duration: 96.238001ms)","trace[1391373076] 'compare' (duration: 165.068063ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:43.797124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.275825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-09-18T05:23:43.797194Z","caller":"traceutil/trace.go:171","msg":"trace[2000524109] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:4644; }","duration":"262.374778ms","start":"2026-09-18T05:23:43.534802Z","end":"2026-09-18T05:23:43.797177Z","steps":["trace[2000524109] 'agreement among raft nodes before linearized reading' (duration: 262.209354ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:43.797672Z","caller":"traceutil/trace.go:171","msg":"trace[1973711425] linearizableReadLoop","detail":"{readStateIndex:4842; appliedIndex:4841; }","duration":"262.01069ms","start":"2026-09-18T05:23:43.534834Z","end":"2026-09-18T05:23:43.796844Z","steps":["trace[1973711425] 'read index received' (duration: 95.937364ms)","trace[1973711425] 'applied index is now lower than readState.Index' (duration: 166.071356ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:43.798093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.080962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-18T05:23:43.799272Z","caller":"traceutil/trace.go:171","msg":"trace[1495211140] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4644; }","duration":"263.313498ms","start":"2026-09-18T05:23:43.53594Z","end":"2026-09-18T05:23:43.799253Z","steps":["trace[1495211140] 'agreement among raft nodes before linearized reading' (duration: 261.969019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:43.799076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.113696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-18T05:23:43.799681Z","caller":"traceutil/trace.go:171","msg":"trace[657110466] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4644; }","duration":"237.76517ms","start":"2026-09-18T05:23:43.561898Z","end":"2026-09-18T05:23:43.799663Z","steps":["trace[657110466] 'agreement among raft nodes before linearized reading' (duration: 237.039724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:43.799191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.600313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-18T05:23:43.80014Z","caller":"traceutil/trace.go:171","msg":"trace[1750915423] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4644; }","duration":"263.507013ms","start":"2026-09-18T05:23:43.536556Z","end":"2026-09-18T05:23:43.800063Z","steps":["trace[1750915423] 'agreement among raft nodes before linearized reading' (duration: 262.561562ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:44.020696Z","caller":"traceutil/trace.go:171","msg":"trace[360212600] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4849; }","duration":"182.572855ms","start":"2026-09-18T05:23:43.838088Z","end":"2026-09-18T05:23:44.02066Z","steps":["trace[360212600] 'read index received' (duration: 83.705597ms)","trace[360212600] 'applied index is now lower than readState.Index' (duration: 98.865678ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:44.020775Z","caller":"traceutil/trace.go:171","msg":"trace[1811438631] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"205.125027ms","start":"2026-09-18T05:23:43.815609Z","end":"2026-09-18T05:23:44.020734Z","steps":["trace[1811438631] 'process raft request' (duration: 106.152238ms)","trace[1811438631] 'compare' (duration: 98.698223ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:44.0212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.076135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-09-18T05:23:44.021267Z","caller":"traceutil/trace.go:171","msg":"trace[445020852] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4652; }","duration":"183.206248ms","start":"2026-09-18T05:23:43.838043Z","end":"2026-09-18T05:23:44.021249Z","steps":["trace[445020852] 'agreement among raft nodes before linearized reading' (duration: 182.875741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:44.021304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.129107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-09-18T05:23:44.021354Z","caller":"traceutil/trace.go:171","msg":"trace[1614440714] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4652; }","duration":"183.220648ms","start":"2026-09-18T05:23:43.838122Z","end":"2026-09-18T05:23:44.021342Z","steps":["trace[1614440714] 'agreement among raft nodes before linearized reading' (duration: 183.015614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:44.021715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.798648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-18T05:23:44.021773Z","caller":"traceutil/trace.go:171","msg":"trace[859906690] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4652; }","duration":"181.876419ms","start":"2026-09-18T05:23:43.839882Z","end":"2026-09-18T05:23:44.021759Z","steps":["trace[859906690] 'agreement among raft nodes before linearized reading' (duration: 181.729396ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:46.62828Z","caller":"traceutil/trace.go:171","msg":"trace[1592615011] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"165.951991ms","start":"2026-09-18T05:23:46.462304Z","end":"2026-09-18T05:23:46.628256Z","steps":["trace[1592615011] 'process raft request' (duration: 165.813418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:55.68915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.492003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-18T05:23:55.68925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.348587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-18T05:23:55.68931Z","caller":"traceutil/trace.go:171","msg":"trace[1523265271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4852; }","duration":"183.703868ms","start":"2026-09-18T05:23:55.505582Z","end":"2026-09-18T05:23:55.689286Z","steps":["trace[1523265271] 'range keys from in-memory index tree' (duration: 183.301859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:55.68949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.171825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"info","ts":"2026-09-18T05:23:55.689526Z","caller":"traceutil/trace.go:171","msg":"trace[1155312417] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4852; }","duration":"181.516441ms","start":"2026-09-18T05:23:55.507817Z","end":"2026-09-18T05:23:55.689333Z","steps":["trace[1155312417] 'range keys from in-memory index tree' (duration: 181.129222ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:55.689595Z","caller":"traceutil/trace.go:171","msg":"trace[446880190] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4852; }","duration":"250.325579ms","start":"2026-09-18T05:23:55.439246Z","end":"2026-09-18T05:23:55.689572Z","steps":["trace[446880190] 'range keys from in-memory index tree' (duration: 249.358628ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:23:56.022489Z","caller":"traceutil/trace.go:171","msg":"trace[1664312772] linearizableReadLoop","detail":"{readStateIndex:5066; appliedIndex:5064; }","duration":"137.365029ms","start":"2026-09-18T05:23:55.88509Z","end":"2026-09-18T05:23:56.022455Z","steps":["trace[1664312772] 'read index received' (duration: 15.45572ms)","trace[1664312772] 'applied index is now lower than readState.Index' (duration: 121.908039ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:23:56.022639Z","caller":"traceutil/trace.go:171","msg":"trace[418884654] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"146.490444ms","start":"2026-09-18T05:23:55.876132Z","end":"2026-09-18T05:23:56.022622Z","steps":["trace[418884654] 'process raft request' (duration: 72.052587ms)","trace[418884654] 'compare' (duration: 74.081929ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:56.023088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.341245ms","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-18T05:23:56.02317Z","caller":"traceutil/trace.go:171","msg":"trace[1927201964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4860; }","duration":"135.451508ms","start":"2026-09-18T05:23:55.887699Z","end":"2026-09-18T05:23:56.02315Z","steps":["trace[1927201964] 'agreement among raft nodes before linearized reading' (duration: 135.191093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:56.023186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.084814ms","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-18T05:23:56.023227Z","caller":"traceutil/trace.go:171","msg":"trace[654142103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4860; }","duration":"138.388031ms","start":"2026-09-18T05:23:55.884828Z","end":"2026-09-18T05:23:56.023216Z","steps":["trace[654142103] 'agreement among raft nodes before linearized reading' (duration: 138.221247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:56.023374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.084812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-09-18T05:23:56.023434Z","caller":"traceutil/trace.go:171","msg":"trace[94940414] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:4860; }","duration":"121.150873ms","start":"2026-09-18T05:23:55.902255Z","end":"2026-09-18T05:23:56.023406Z","steps":["trace[94940414] 'agreement among raft nodes before linearized reading' (duration: 121.04845ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:26:44.441512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3185} {"level":"info","ts":"2026-09-18T05:26:44.54452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3185,"took":"101.485634ms","hash":4147192441,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-18T05:26:44.544597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4147192441,"revision":3185,"compact-revision":-1} {"level":"info","ts":"2026-09-18T05:27:55.676219Z","caller":"traceutil/trace.go:171","msg":"trace[1187575827] linearizableReadLoop","detail":"{readStateIndex:7245; appliedIndex:7244; }","duration":"124.152371ms","start":"2026-09-18T05:27:55.552008Z","end":"2026-09-18T05:27:55.676161Z","steps":["trace[1187575827] 'read index received' (duration: 123.765924ms)","trace[1187575827] 'applied index is now lower than readState.Index' (duration: 385.487µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:27:55.676462Z","caller":"traceutil/trace.go:171","msg":"trace[1496711462] transaction","detail":"{read_only:false; response_revision:6934; number_of_response:1; }","duration":"127.256193ms","start":"2026-09-18T05:27:55.549169Z","end":"2026-09-18T05:27:55.676425Z","steps":["trace[1496711462] 'process raft request' (duration: 126.745254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:55.676931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.888783ms","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-18T05:27:55.676996Z","caller":"traceutil/trace.go:171","msg":"trace[663709083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6934; }","duration":"125.020785ms","start":"2026-09-18T05:27:55.551964Z","end":"2026-09-18T05:27:55.676985Z","steps":["trace[663709083] 'agreement among raft nodes before linearized reading' (duration: 124.76069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:27:55.677113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.292977ms","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-18T05:27:55.677167Z","caller":"traceutil/trace.go:171","msg":"trace[1476358823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6934; }","duration":"116.392378ms","start":"2026-09-18T05:27:55.560761Z","end":"2026-09-18T05:27:55.677153Z","steps":["trace[1476358823] 'agreement among raft nodes before linearized reading' (duration: 116.249386ms)"],"step_count":1}