{"level":"warn","ts":"2026-10-14T15:39:46.1108Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T15:39:46.110889Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.193: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.193:2380","--initial-cluster=instance=https://162.253.55.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.193:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-14T15:39:46.110953Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T15:39:46.110965Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-10-14T15:39:46.110984Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-14T15:39:46.11164Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"]} {"level":"info","ts":"2026-10-14T15:39:46.111723Z","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.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.193:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-14T15:39:46.134018Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"22.089835ms"} {"level":"info","ts":"2026-10-14T15:39:46.151013Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6814e3e3d6b4d713","cluster-id":"e5e99c9118b1492a"} {"level":"info","ts":"2026-10-14T15:39:46.151085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T15:39:46.151108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 0"} {"level":"info","ts":"2026-10-14T15:39:46.151123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6814e3e3d6b4d713 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T15:39:46.151179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 1"} {"level":"info","ts":"2026-10-14T15:39:46.151324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"warn","ts":"2026-10-14T15:39:46.157759Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T15:39:46.158951Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T15:39:46.160004Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-14T15:39:46.16179Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6814e3e3d6b4d713","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T15:39:46.161925Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6814e3e3d6b4d713","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T15:39:46.162159Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T15:39:46.162266Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T15:39:46.162285Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-14T15:39:46.164091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"info","ts":"2026-10-14T15:39:46.164821Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","added-peer-id":"6814e3e3d6b4d713","added-peer-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-10-14T15:39:46.166295Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-14T15:39:46.166395Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-10-14T15:39:46.166495Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-10-14T15:39:46.166736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6814e3e3d6b4d713","initial-advertise-peer-urls":["https://162.253.55.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T15:39:46.166795Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T15:39:46.352477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T15:39:46.352549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T15:39:46.352584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgPreVoteResp from 6814e3e3d6b4d713 at term 1"} {"level":"info","ts":"2026-10-14T15:39:46.352602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became candidate at term 2"} {"level":"info","ts":"2026-10-14T15:39:46.352613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgVoteResp from 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-10-14T15:39:46.352631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became leader at term 2"} {"level":"info","ts":"2026-10-14T15:39:46.352644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6814e3e3d6b4d713 elected leader 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-10-14T15:39:46.358324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6814e3e3d6b4d713","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.193:2379]}","request-path":"/0/members/6814e3e3d6b4d713/attributes","cluster-id":"e5e99c9118b1492a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T15:39:46.358627Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:39:46.359463Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:39:46.360016Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:39:46.360186Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:39:46.360218Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:39:46.360902Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:39:46.363888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.193:2379"} {"level":"info","ts":"2026-10-14T15:39:46.366387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T15:39:46.368298Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T15:39:46.368327Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T15:39:49.84224Z","caller":"traceutil/trace.go:171","msg":"trace[664661381] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"139.533563ms","start":"2026-10-14T15:39:49.702678Z","end":"2026-10-14T15:39:49.842211Z","steps":["trace[664661381] 'process raft request' (duration: 95.25421ms)","trace[664661381] 'compare' (duration: 44.149339ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:39:55.309403Z","caller":"traceutil/trace.go:171","msg":"trace[663994540] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"110.564491ms","start":"2026-10-14T15:39:55.198816Z","end":"2026-10-14T15:39:55.309381Z","steps":["trace[663994540] 'process raft request' (duration: 110.402526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:39:55.602657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.69544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:39:55.60279Z","caller":"traceutil/trace.go:171","msg":"trace[1395158143] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"253.912656ms","start":"2026-10-14T15:39:55.348859Z","end":"2026-10-14T15:39:55.602772Z","steps":["trace[1395158143] 'process raft request' (duration: 43.359501ms)","trace[1395158143] 'compare' (duration: 209.513035ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:39:55.797923Z","caller":"traceutil/trace.go:171","msg":"trace[1188178148] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"148.928901ms","start":"2026-10-14T15:39:55.648968Z","end":"2026-10-14T15:39:55.797897Z","steps":["trace[1188178148] 'process raft request' (duration: 148.659444ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:39:55.97075Z","caller":"traceutil/trace.go:171","msg":"trace[1357529309] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"121.480134ms","start":"2026-10-14T15:39:55.849243Z","end":"2026-10-14T15:39:55.970723Z","steps":["trace[1357529309] 'process raft request' (duration: 121.221887ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:39:57.08367Z","caller":"traceutil/trace.go:171","msg":"trace[2133327536] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"130.018504ms","start":"2026-10-14T15:39:56.953623Z","end":"2026-10-14T15:39:57.083641Z","steps":["trace[2133327536] 'process raft request' (duration: 58.434827ms)","trace[2133327536] 'compare' (duration: 71.399872ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:39:57.106469Z","caller":"traceutil/trace.go:171","msg":"trace[2102552199] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"152.389502ms","start":"2026-10-14T15:39:56.954051Z","end":"2026-10-14T15:39:57.106441Z","steps":["trace[2102552199] 'process raft request' (duration: 152.180916ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:39:57.106647Z","caller":"traceutil/trace.go:171","msg":"trace[501750181] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:318; }","duration":"107.921427ms","start":"2026-10-14T15:39:56.998692Z","end":"2026-10-14T15:39:57.106613Z","steps":["trace[501750181] 'read index received' (duration: 13.301113ms)","trace[501750181] 'applied index is now lower than readState.Index' (duration: 94.618894ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:39:57.106729Z","caller":"traceutil/trace.go:171","msg":"trace[408910777] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"151.146358ms","start":"2026-10-14T15:39:56.955572Z","end":"2026-10-14T15:39:57.106718Z","steps":["trace[408910777] 'process raft request' (duration: 150.784567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:39:57.106949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.196264ms","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-10-14T15:39:57.10702Z","caller":"traceutil/trace.go:171","msg":"trace[1711081416] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:311; }","duration":"108.338168ms","start":"2026-10-14T15:39:56.998664Z","end":"2026-10-14T15:39:57.107002Z","steps":["trace[1711081416] 'agreement among raft nodes before linearized reading' (duration: 108.181944ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:39:57.244197Z","caller":"traceutil/trace.go:171","msg":"trace[1238821603] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"123.392451ms","start":"2026-10-14T15:39:57.120782Z","end":"2026-10-14T15:39:57.244174Z","steps":["trace[1238821603] 'process raft request' (duration: 85.576611ms)","trace[1238821603] 'compare' (duration: 37.581484ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:39:57.244245Z","caller":"traceutil/trace.go:171","msg":"trace[699217505] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"123.34456ms","start":"2026-10-14T15:39:57.120888Z","end":"2026-10-14T15:39:57.244233Z","steps":["trace[699217505] 'process raft request' (duration: 123.169105ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:39:57.2444Z","caller":"traceutil/trace.go:171","msg":"trace[1237405297] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"123.414833ms","start":"2026-10-14T15:39:57.120976Z","end":"2026-10-14T15:39:57.24439Z","steps":["trace[1237405297] 'process raft request' (duration: 123.130144ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:39:57.256461Z","caller":"traceutil/trace.go:171","msg":"trace[1059410528] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"100.128989ms","start":"2026-10-14T15:39:57.156273Z","end":"2026-10-14T15:39:57.256402Z","steps":["trace[1059410528] 'process raft request' (duration: 100.059337ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:39:57.256577Z","caller":"traceutil/trace.go:171","msg":"trace[52112228] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"103.301889ms","start":"2026-10-14T15:39:57.153229Z","end":"2026-10-14T15:39:57.256531Z","steps":["trace[52112228] 'process raft request' (duration: 103.018901ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.966209Z","caller":"traceutil/trace.go:171","msg":"trace[1118532880] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"141.746934ms","start":"2026-10-14T15:40:30.824438Z","end":"2026-10-14T15:40:30.966185Z","steps":["trace[1118532880] 'process raft request' (duration: 140.939591ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.979362Z","caller":"traceutil/trace.go:171","msg":"trace[286646526] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"146.774472ms","start":"2026-10-14T15:40:30.83256Z","end":"2026-10-14T15:40:30.979334Z","steps":["trace[286646526] 'process raft request' (duration: 146.563956ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.979424Z","caller":"traceutil/trace.go:171","msg":"trace[1991012840] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"146.352338ms","start":"2026-10-14T15:40:30.833031Z","end":"2026-10-14T15:40:30.979384Z","steps":["trace[1991012840] 'process raft request' (duration: 146.221594ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.979634Z","caller":"traceutil/trace.go:171","msg":"trace[113719805] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"138.769906ms","start":"2026-10-14T15:40:30.840852Z","end":"2026-10-14T15:40:30.979622Z","steps":["trace[113719805] 'process raft request' (duration: 138.439587ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.97974Z","caller":"traceutil/trace.go:171","msg":"trace[1973364287] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"137.627362ms","start":"2026-10-14T15:40:30.842097Z","end":"2026-10-14T15:40:30.979724Z","steps":["trace[1973364287] 'process raft request' (duration: 137.420416ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.979698Z","caller":"traceutil/trace.go:171","msg":"trace[1853446185] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"130.821223ms","start":"2026-10-14T15:40:30.848861Z","end":"2026-10-14T15:40:30.979682Z","steps":["trace[1853446185] 'process raft request' (duration: 130.752011ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.979954Z","caller":"traceutil/trace.go:171","msg":"trace[480892584] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"127.181886ms","start":"2026-10-14T15:40:30.852765Z","end":"2026-10-14T15:40:30.979947Z","steps":["trace[480892584] 'process raft request' (duration: 126.931398ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.980074Z","caller":"traceutil/trace.go:171","msg":"trace[1185303737] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"119.362216ms","start":"2026-10-14T15:40:30.860704Z","end":"2026-10-14T15:40:30.980067Z","steps":["trace[1185303737] 'process raft request' (duration: 119.15579ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.980131Z","caller":"traceutil/trace.go:171","msg":"trace[888913114] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"106.134588ms","start":"2026-10-14T15:40:30.87399Z","end":"2026-10-14T15:40:30.980125Z","steps":["trace[888913114] 'process raft request' (duration: 105.919211ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.980187Z","caller":"traceutil/trace.go:171","msg":"trace[1115171135] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"127.466043ms","start":"2026-10-14T15:40:30.852714Z","end":"2026-10-14T15:40:30.98018Z","steps":["trace[1115171135] 'process raft request' (duration: 126.959189ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.980076Z","caller":"traceutil/trace.go:171","msg":"trace[1603056788] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"123.442166ms","start":"2026-10-14T15:40:30.856623Z","end":"2026-10-14T15:40:30.980066Z","steps":["trace[1603056788] 'process raft request' (duration: 123.177528ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.980284Z","caller":"traceutil/trace.go:171","msg":"trace[1148162516] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"127.403522ms","start":"2026-10-14T15:40:30.852856Z","end":"2026-10-14T15:40:30.980259Z","steps":["trace[1148162516] 'process raft request' (duration: 126.875606ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:30.980584Z","caller":"traceutil/trace.go:171","msg":"trace[176124465] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"130.866295ms","start":"2026-10-14T15:40:30.849709Z","end":"2026-10-14T15:40:30.980575Z","steps":["trace[176124465] 'process raft request' (duration: 129.937328ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:32.227843Z","caller":"traceutil/trace.go:171","msg":"trace[1701530390] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"382.686838ms","start":"2026-10-14T15:40:31.845129Z","end":"2026-10-14T15:40:32.227815Z","steps":["trace[1701530390] 'process raft request' (duration: 382.493262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:32.227983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:40:31.845101Z","time spent":"382.816161ms","remote":"127.0.0.1:51966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T15:40:32.251893Z","caller":"traceutil/trace.go:171","msg":"trace[1282069206] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"296.6677ms","start":"2026-10-14T15:40:31.955193Z","end":"2026-10-14T15:40:32.251861Z","steps":["trace[1282069206] 'read index received' (duration: 273.341744ms)","trace[1282069206] 'applied index is now lower than readState.Index' (duration: 23.325026ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:40:32.252008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.792704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:40:32.25206Z","caller":"traceutil/trace.go:171","msg":"trace[185893694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"296.878546ms","start":"2026-10-14T15:40:31.955168Z","end":"2026-10-14T15:40:32.252047Z","steps":["trace[185893694] 'agreement among raft nodes before linearized reading' (duration: 296.793053ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:32.252277Z","caller":"traceutil/trace.go:171","msg":"trace[726274636] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"310.845006ms","start":"2026-10-14T15:40:31.941421Z","end":"2026-10-14T15:40:32.252266Z","steps":["trace[726274636] 'process raft request' (duration: 309.410774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:32.252593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:40:31.941381Z","time spent":"310.926579ms","remote":"127.0.0.1:51966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:40:37.088335Z","caller":"traceutil/trace.go:171","msg":"trace[1473783960] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"221.086345ms","start":"2026-10-14T15:40:36.867225Z","end":"2026-10-14T15:40:37.088311Z","steps":["trace[1473783960] 'process raft request' (duration: 220.92155ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:37.089074Z","caller":"traceutil/trace.go:171","msg":"trace[1915988655] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:631; }","duration":"131.145718ms","start":"2026-10-14T15:40:36.957916Z","end":"2026-10-14T15:40:37.089062Z","steps":["trace[1915988655] 'read index received' (duration: 131.140988ms)","trace[1915988655] 'applied index is now lower than readState.Index' (duration: 3.73µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:40:37.089209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.281512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:40:37.089235Z","caller":"traceutil/trace.go:171","msg":"trace[1691901684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"131.357485ms","start":"2026-10-14T15:40:36.95787Z","end":"2026-10-14T15:40:37.089227Z","steps":["trace[1691901684] 'agreement among raft nodes before linearized reading' (duration: 131.244391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:38.14685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15497908017786153998,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T15:40:38.449049Z","caller":"traceutil/trace.go:171","msg":"trace[135253693] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"803.206947ms","start":"2026-10-14T15:40:37.645818Z","end":"2026-10-14T15:40:38.449025Z","steps":["trace[135253693] 'read index received' (duration: 802.346071ms)","trace[135253693] 'applied index is now lower than readState.Index' (duration: 859.986µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:40:38.449171Z","caller":"traceutil/trace.go:171","msg":"trace[488110305] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"953.12041ms","start":"2026-10-14T15:40:37.496026Z","end":"2026-10-14T15:40:38.449146Z","steps":["trace[488110305] 'process raft request' (duration: 952.329407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:38.44927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.432503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6khp6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T15:40:38.449311Z","caller":"traceutil/trace.go:171","msg":"trace[502390580] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6khp6; range_end:; response_count:1; response_revision:610; }","duration":"803.524117ms","start":"2026-10-14T15:40:37.645776Z","end":"2026-10-14T15:40:38.4493Z","steps":["trace[502390580] 'agreement among raft nodes before linearized reading' (duration: 803.378402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:38.449339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:40:37.645759Z","time spent":"803.573148ms","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-6khp6\" "} {"level":"warn","ts":"2026-10-14T15:40:38.449333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:40:37.495998Z","time spent":"953.248634ms","remote":"127.0.0.1:51990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:40:38.449365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.431085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:40:38.449454Z","caller":"traceutil/trace.go:171","msg":"trace[543142195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"491.570459ms","start":"2026-10-14T15:40:37.957858Z","end":"2026-10-14T15:40:38.449429Z","steps":["trace[543142195] 'agreement among raft nodes before linearized reading' (duration: 491.390274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:38.449515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:40:37.957839Z","time spent":"491.666983ms","remote":"127.0.0.1:51936","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-10-14T15:40:38.449584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.844913ms","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-10-14T15:40:38.449615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.43698ms","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-10-14T15:40:38.44969Z","caller":"traceutil/trace.go:171","msg":"trace[1877171898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"131.985907ms","start":"2026-10-14T15:40:38.317673Z","end":"2026-10-14T15:40:38.449659Z","steps":["trace[1877171898] 'agreement among raft nodes before linearized reading' (duration: 131.653607ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:38.449695Z","caller":"traceutil/trace.go:171","msg":"trace[1465237932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"132.544164ms","start":"2026-10-14T15:40:38.317129Z","end":"2026-10-14T15:40:38.449673Z","steps":["trace[1465237932] 'agreement among raft nodes before linearized reading' (duration: 132.256875ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:40:38.6178Z","caller":"traceutil/trace.go:171","msg":"trace[526493597] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"162.689226ms","start":"2026-10-14T15:40:38.455018Z","end":"2026-10-14T15:40:38.617708Z","steps":["trace[526493597] 'process raft request' (duration: 145.376084ms)","trace[526493597] 'compare' (duration: 17.102006ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:40:38.618343Z","caller":"traceutil/trace.go:171","msg":"trace[441628529] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:632; }","duration":"129.258148ms","start":"2026-10-14T15:40:38.489063Z","end":"2026-10-14T15:40:38.618321Z","steps":["trace[441628529] 'read index received' (duration: 6.587186ms)","trace[441628529] 'applied index is now lower than readState.Index' (duration: 122.670102ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:40:38.618553Z","caller":"traceutil/trace.go:171","msg":"trace[1682168854] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"137.4972ms","start":"2026-10-14T15:40:38.481029Z","end":"2026-10-14T15:40:38.618526Z","steps":["trace[1682168854] 'process raft request' (duration: 137.186691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:38.618667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.591037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-14T15:40:38.618698Z","caller":"traceutil/trace.go:171","msg":"trace[590017045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"129.679389ms","start":"2026-10-14T15:40:38.489009Z","end":"2026-10-14T15:40:38.618689Z","steps":["trace[590017045] 'agreement among raft nodes before linearized reading' (duration: 129.564526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:38.621347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.817023ms","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-10-14T15:40:38.621398Z","caller":"traceutil/trace.go:171","msg":"trace[1786059613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"127.931117ms","start":"2026-10-14T15:40:38.493454Z","end":"2026-10-14T15:40:38.621385Z","steps":["trace[1786059613] 'agreement among raft nodes before linearized reading' (duration: 127.355389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:40:53.980877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.281729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m275w\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-14T15:40:53.98099Z","caller":"traceutil/trace.go:171","msg":"trace[1074318421] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m275w; range_end:; response_count:1; response_revision:772; }","duration":"236.440064ms","start":"2026-10-14T15:40:53.744526Z","end":"2026-10-14T15:40:53.980966Z","steps":["trace[1074318421] 'range keys from in-memory index tree' (duration: 236.058572ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:47.252429Z","caller":"traceutil/trace.go:171","msg":"trace[573539545] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"180.758066ms","start":"2026-10-14T15:41:47.071621Z","end":"2026-10-14T15:41:47.252379Z","steps":["trace[573539545] 'read index received' (duration: 5.053935ms)","trace[573539545] 'applied index is now lower than readState.Index' (duration: 175.702871ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:41:47.252572Z","caller":"traceutil/trace.go:171","msg":"trace[1159277718] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"195.221471ms","start":"2026-10-14T15:41:47.05732Z","end":"2026-10-14T15:41:47.252541Z","steps":["trace[1159277718] 'process raft request' (duration: 154.571261ms)","trace[1159277718] 'compare' (duration: 40.32237ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:41:47.252739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.321673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-14T15:41:47.252775Z","caller":"traceutil/trace.go:171","msg":"trace[1121002497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1439; }","duration":"180.400595ms","start":"2026-10-14T15:41:47.072363Z","end":"2026-10-14T15:41:47.252764Z","steps":["trace[1121002497] 'agreement among raft nodes before linearized reading' (duration: 180.21463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:47.252768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.109426ms","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-10-14T15:41:47.252824Z","caller":"traceutil/trace.go:171","msg":"trace[1953805946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1439; }","duration":"181.22276ms","start":"2026-10-14T15:41:47.071588Z","end":"2026-10-14T15:41:47.25281Z","steps":["trace[1953805946] 'agreement among raft nodes before linearized reading' (duration: 180.952072ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:59.037312Z","caller":"traceutil/trace.go:171","msg":"trace[64435759] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"119.429831ms","start":"2026-10-14T15:41:58.917857Z","end":"2026-10-14T15:41:59.037287Z","steps":["trace[64435759] 'process raft request' (duration: 109.620459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:59.29774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.693794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-14T15:41:59.297777Z","caller":"traceutil/trace.go:171","msg":"trace[166573815] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"143.137353ms","start":"2026-10-14T15:41:59.1546Z","end":"2026-10-14T15:41:59.297737Z","steps":["trace[166573815] 'process raft request' (duration: 74.9239ms)","trace[166573815] 'compare' (duration: 67.890723ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:41:59.29781Z","caller":"traceutil/trace.go:171","msg":"trace[2113412585] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"141.988928ms","start":"2026-10-14T15:41:59.155799Z","end":"2026-10-14T15:41:59.297788Z","steps":["trace[2113412585] 'process raft request' (duration: 141.859074ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:59.297854Z","caller":"traceutil/trace.go:171","msg":"trace[1746182123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1663; }","duration":"159.89836ms","start":"2026-10-14T15:41:59.137934Z","end":"2026-10-14T15:41:59.297833Z","steps":["trace[1746182123] 'range keys from in-memory index tree' (duration: 159.432656ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:41:59.490084Z","caller":"traceutil/trace.go:171","msg":"trace[811285202] linearizableReadLoop","detail":"{readStateIndex:1730; appliedIndex:1728; }","duration":"109.515596ms","start":"2026-10-14T15:41:59.380542Z","end":"2026-10-14T15:41:59.490058Z","steps":["trace[811285202] 'read index received' (duration: 79.767298ms)","trace[811285202] 'applied index is now lower than readState.Index' (duration: 29.747028ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:41:59.490438Z","caller":"traceutil/trace.go:171","msg":"trace[1465887511] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"192.30561ms","start":"2026-10-14T15:41:59.298112Z","end":"2026-10-14T15:41:59.490417Z","steps":["trace[1465887511] 'process raft request' (duration: 162.217752ms)","trace[1465887511] 'compare' (duration: 29.459049ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:41:59.490456Z","caller":"traceutil/trace.go:171","msg":"trace[1208380166] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"188.578004ms","start":"2026-10-14T15:41:59.301863Z","end":"2026-10-14T15:41:59.490441Z","steps":["trace[1208380166] 'process raft request' (duration: 188.093479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:59.490869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.308011ms","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-10-14T15:41:59.491088Z","caller":"traceutil/trace.go:171","msg":"trace[391647060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1668; }","duration":"110.569499ms","start":"2026-10-14T15:41:59.380501Z","end":"2026-10-14T15:41:59.491071Z","steps":["trace[391647060] 'agreement among raft nodes before linearized reading' (duration: 109.754604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:41:59.491022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.022992ms","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-10-14T15:41:59.491321Z","caller":"traceutil/trace.go:171","msg":"trace[371054641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1668; }","duration":"110.333161ms","start":"2026-10-14T15:41:59.380976Z","end":"2026-10-14T15:41:59.491309Z","steps":["trace[371054641] 'agreement among raft nodes before linearized reading' (duration: 109.417274ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:13.187083Z","caller":"traceutil/trace.go:171","msg":"trace[1696492735] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"261.734794ms","start":"2026-10-14T15:42:12.925323Z","end":"2026-10-14T15:42:13.187058Z","steps":["trace[1696492735] 'process raft request' (duration: 261.581529ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:13.220331Z","caller":"traceutil/trace.go:171","msg":"trace[2014056001] linearizableReadLoop","detail":"{readStateIndex:1915; appliedIndex:1913; }","duration":"173.458774ms","start":"2026-10-14T15:42:13.046844Z","end":"2026-10-14T15:42:13.220303Z","steps":["trace[2014056001] 'read index received' (duration: 140.116343ms)","trace[2014056001] 'applied index is now lower than readState.Index' (duration: 33.341541ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:13.220476Z","caller":"traceutil/trace.go:171","msg":"trace[606545700] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"244.48183ms","start":"2026-10-14T15:42:12.975984Z","end":"2026-10-14T15:42:13.220466Z","steps":["trace[606545700] 'process raft request' (duration: 244.191141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:13.220691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.849277ms","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-10-14T15:42:13.220763Z","caller":"traceutil/trace.go:171","msg":"trace[1724687270] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1846; }","duration":"173.95451ms","start":"2026-10-14T15:42:13.046795Z","end":"2026-10-14T15:42:13.22075Z","steps":["trace[1724687270] 'agreement among raft nodes before linearized reading' (duration: 173.799375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:13.221151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.24748ms","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-10-14T15:42:13.221244Z","caller":"traceutil/trace.go:171","msg":"trace[1887274510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1846; }","duration":"111.375604ms","start":"2026-10-14T15:42:13.109845Z","end":"2026-10-14T15:42:13.221221Z","steps":["trace[1887274510] 'agreement among raft nodes before linearized reading' (duration: 110.898469ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:13.353825Z","caller":"traceutil/trace.go:171","msg":"trace[1855385546] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"128.930188ms","start":"2026-10-14T15:42:13.224867Z","end":"2026-10-14T15:42:13.353797Z","steps":["trace[1855385546] 'process raft request' (duration: 71.690737ms)","trace[1855385546] 'compare' (duration: 57.107797ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:13.436547Z","caller":"traceutil/trace.go:171","msg":"trace[383063064] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1915; }","duration":"186.693853ms","start":"2026-10-14T15:42:13.249827Z","end":"2026-10-14T15:42:13.436521Z","steps":["trace[383063064] 'read index received' (duration: 46.742405ms)","trace[383063064] 'applied index is now lower than readState.Index' (duration: 139.950148ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:13.436561Z","caller":"traceutil/trace.go:171","msg":"trace[1573705843] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"210.597063ms","start":"2026-10-14T15:42:13.22594Z","end":"2026-10-14T15:42:13.436537Z","steps":["trace[1573705843] 'process raft request' (duration: 210.359776ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:13.436624Z","caller":"traceutil/trace.go:171","msg":"trace[63871248] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"207.25357ms","start":"2026-10-14T15:42:13.229335Z","end":"2026-10-14T15:42:13.436589Z","steps":["trace[63871248] 'process raft request' (duration: 207.109215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:13.436769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.892629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:42:13.436831Z","caller":"traceutil/trace.go:171","msg":"trace[851604511] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1849; }","duration":"187.035133ms","start":"2026-10-14T15:42:13.24978Z","end":"2026-10-14T15:42:13.436815Z","steps":["trace[851604511] 'agreement among raft nodes before linearized reading' (duration: 186.850728ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:13.773785Z","caller":"traceutil/trace.go:171","msg":"trace[1772776828] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"170.42167ms","start":"2026-10-14T15:42:13.603339Z","end":"2026-10-14T15:42:13.77376Z","steps":["trace[1772776828] 'process raft request' (duration: 92.731447ms)","trace[1772776828] 'compare' (duration: 77.316012ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:13.773898Z","caller":"traceutil/trace.go:171","msg":"trace[1937566940] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1919; }","duration":"163.28637ms","start":"2026-10-14T15:42:13.610585Z","end":"2026-10-14T15:42:13.773871Z","steps":["trace[1937566940] 'read index received' (duration: 85.476333ms)","trace[1937566940] 'applied index is now lower than readState.Index' (duration: 77.808937ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:13.774638Z","caller":"traceutil/trace.go:171","msg":"trace[1629316836] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"168.421558ms","start":"2026-10-14T15:42:13.605564Z","end":"2026-10-14T15:42:13.773986Z","steps":["trace[1629316836] 'process raft request' (duration: 168.074318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:13.774524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.898908ms","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-10-14T15:42:13.775334Z","caller":"traceutil/trace.go:171","msg":"trace[533601254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"164.751865ms","start":"2026-10-14T15:42:13.610553Z","end":"2026-10-14T15:42:13.775305Z","steps":["trace[533601254] 'agreement among raft nodes before linearized reading' (duration: 163.538708ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:27.392725Z","caller":"traceutil/trace.go:171","msg":"trace[1123633394] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2037; }","duration":"139.206205ms","start":"2026-10-14T15:42:27.253495Z","end":"2026-10-14T15:42:27.392701Z","steps":["trace[1123633394] 'read index received' (duration: 106.449781ms)","trace[1123633394] 'applied index is now lower than readState.Index' (duration: 32.755304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:42:27.392931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.424792ms","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-10-14T15:42:27.392965Z","caller":"traceutil/trace.go:171","msg":"trace[1417639622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1965; }","duration":"139.502484ms","start":"2026-10-14T15:42:27.253453Z","end":"2026-10-14T15:42:27.392955Z","steps":["trace[1417639622] 'agreement among raft nodes before linearized reading' (duration: 139.35108ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:27.392959Z","caller":"traceutil/trace.go:171","msg":"trace[717568234] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"316.833083ms","start":"2026-10-14T15:42:27.076088Z","end":"2026-10-14T15:42:27.392921Z","steps":["trace[717568234] 'process raft request' (duration: 283.931744ms)","trace[717568234] 'compare' (duration: 32.355502ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:42:27.393121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:42:27.076065Z","time spent":"316.949696ms","remote":"127.0.0.1:52378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5641,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:42:47.655553Z","caller":"traceutil/trace.go:171","msg":"trace[542246112] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"191.438361ms","start":"2026-10-14T15:42:47.464085Z","end":"2026-10-14T15:42:47.655523Z","steps":["trace[542246112] 'process raft request' (duration: 191.185963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:47.994827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.370798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:42:47.995146Z","caller":"traceutil/trace.go:171","msg":"trace[16849531] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"334.448768ms","start":"2026-10-14T15:42:47.660676Z","end":"2026-10-14T15:42:47.995125Z","steps":["trace[16849531] 'process raft request' (duration: 334.257642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:47.995272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.776125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-10-14T15:42:47.995326Z","caller":"traceutil/trace.go:171","msg":"trace[50546434] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"307.237561ms","start":"2026-10-14T15:42:47.688077Z","end":"2026-10-14T15:42:47.995314Z","steps":["trace[50546434] 'process raft request' (duration: 306.978564ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:47.995352Z","caller":"traceutil/trace.go:171","msg":"trace[1110169667] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2093; }","duration":"101.899348ms","start":"2026-10-14T15:42:47.893439Z","end":"2026-10-14T15:42:47.995339Z","steps":["trace[1110169667] 'agreement among raft nodes before linearized reading' (duration: 101.746574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:47.99539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:42:47.688056Z","time spent":"307.303954ms","remote":"127.0.0.1:52140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:42:47.995276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:42:47.660661Z","time spent":"334.54118ms","remote":"127.0.0.1:52140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:42:47.995133Z","caller":"traceutil/trace.go:171","msg":"trace[1653319749] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"335.043116ms","start":"2026-10-14T15:42:47.660062Z","end":"2026-10-14T15:42:47.995105Z","steps":["trace[1653319749] 'process raft request' (duration: 161.324806ms)","trace[1653319749] 'compare' (duration: 173.283406ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:42:47.995573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:42:47.660038Z","time spent":"335.50481ms","remote":"127.0.0.1:52140","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-10-14T15:42:47.995155Z","caller":"traceutil/trace.go:171","msg":"trace[625201123] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2167; }","duration":"101.610999ms","start":"2026-10-14T15:42:47.893526Z","end":"2026-10-14T15:42:47.995137Z","steps":["trace[625201123] 'read index received' (duration: 46.851µs)","trace[625201123] 'applied index is now lower than readState.Index' (duration: 101.562428ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:48.184829Z","caller":"traceutil/trace.go:171","msg":"trace[1376868791] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2170; }","duration":"183.855446ms","start":"2026-10-14T15:42:48.000955Z","end":"2026-10-14T15:42:48.184811Z","steps":["trace[1376868791] 'read index received' (duration: 159.049104ms)","trace[1376868791] 'applied index is now lower than readState.Index' (duration: 24.805521ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:42:48.184932Z","caller":"traceutil/trace.go:171","msg":"trace[1344592756] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"184.031631ms","start":"2026-10-14T15:42:48.000868Z","end":"2026-10-14T15:42:48.184899Z","steps":["trace[1344592756] 'process raft request' (duration: 159.210049ms)","trace[1344592756] 'compare' (duration: 24.620556ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:42:48.185013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.038001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-10-14T15:42:48.185279Z","caller":"traceutil/trace.go:171","msg":"trace[1408120175] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2094; }","duration":"184.348351ms","start":"2026-10-14T15:42:48.000923Z","end":"2026-10-14T15:42:48.185271Z","steps":["trace[1408120175] 'agreement among raft nodes before linearized reading' (duration: 183.9996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:48.185182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.045505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-14T15:42:48.185422Z","caller":"traceutil/trace.go:171","msg":"trace[1799719958] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2094; }","duration":"132.355286ms","start":"2026-10-14T15:42:48.053057Z","end":"2026-10-14T15:42:48.185412Z","steps":["trace[1799719958] 'agreement among raft nodes before linearized reading' (duration: 131.994314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:42:48.185176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.810724ms","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-10-14T15:42:48.185211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.950358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25766"} {"level":"warn","ts":"2026-10-14T15:42:48.185236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.606272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-14T15:42:48.185572Z","caller":"traceutil/trace.go:171","msg":"trace[161687729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2094; }","duration":"184.162626ms","start":"2026-10-14T15:42:48.001328Z","end":"2026-10-14T15:42:48.185491Z","steps":["trace[161687729] 'agreement among raft nodes before linearized reading' (duration: 183.738062ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:48.185629Z","caller":"traceutil/trace.go:171","msg":"trace[1729119419] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2094; }","duration":"184.2928ms","start":"2026-10-14T15:42:48.001232Z","end":"2026-10-14T15:42:48.185525Z","steps":["trace[1729119419] 'agreement among raft nodes before linearized reading' (duration: 183.756763ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:42:48.18574Z","caller":"traceutil/trace.go:171","msg":"trace[976329849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2094; }","duration":"132.950504ms","start":"2026-10-14T15:42:48.052578Z","end":"2026-10-14T15:42:48.185529Z","steps":["trace[976329849] 'agreement among raft nodes before linearized reading' (duration: 132.522949ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:44:26.320084Z","caller":"traceutil/trace.go:171","msg":"trace[2037985067] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"162.105297ms","start":"2026-10-14T15:44:26.157955Z","end":"2026-10-14T15:44:26.32006Z","steps":["trace[2037985067] 'process raft request' (duration: 161.930083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:15.664707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.303313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:47:15.664853Z","caller":"traceutil/trace.go:171","msg":"trace[1147518749] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:3685; }","duration":"108.492229ms","start":"2026-10-14T15:47:15.556339Z","end":"2026-10-14T15:47:15.664831Z","steps":["trace[1147518749] 'count revisions from in-memory index tree' (duration: 108.19598ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.542939Z","caller":"traceutil/trace.go:171","msg":"trace[716789110] linearizableReadLoop","detail":"{readStateIndex:4911; appliedIndex:4910; }","duration":"147.381021ms","start":"2026-10-14T15:49:01.39553Z","end":"2026-10-14T15:49:01.542911Z","steps":["trace[716789110] 'read index received' (duration: 146.496534ms)","trace[716789110] 'applied index is now lower than readState.Index' (duration: 883.077µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:49:01.543166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.624608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-14T15:49:01.543215Z","caller":"traceutil/trace.go:171","msg":"trace[496393019] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4730; }","duration":"147.729201ms","start":"2026-10-14T15:49:01.39547Z","end":"2026-10-14T15:49:01.543199Z","steps":["trace[496393019] 'agreement among raft nodes before linearized reading' (duration: 147.541486ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.54329Z","caller":"traceutil/trace.go:171","msg":"trace[1317217488] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"325.979185ms","start":"2026-10-14T15:49:01.21718Z","end":"2026-10-14T15:49:01.543159Z","steps":["trace[1317217488] 'process raft request' (duration: 325.568752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:01.5436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:01.217158Z","time spent":"326.300815ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3387,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T15:49:01.609084Z","caller":"traceutil/trace.go:171","msg":"trace[1691375812] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"160.972662ms","start":"2026-10-14T15:49:01.448078Z","end":"2026-10-14T15:49:01.609051Z","steps":["trace[1691375812] 'process raft request' (duration: 160.817258ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.848278Z","caller":"traceutil/trace.go:171","msg":"trace[9520700] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"298.482771ms","start":"2026-10-14T15:49:01.549775Z","end":"2026-10-14T15:49:01.848258Z","steps":["trace[9520700] 'process raft request' (duration: 298.419169ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.848377Z","caller":"traceutil/trace.go:171","msg":"trace[1371542561] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"298.831032ms","start":"2026-10-14T15:49:01.549495Z","end":"2026-10-14T15:49:01.848326Z","steps":["trace[1371542561] 'process raft request' (duration: 298.646726ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.848775Z","caller":"traceutil/trace.go:171","msg":"trace[1097857125] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"297.024478ms","start":"2026-10-14T15:49:01.551724Z","end":"2026-10-14T15:49:01.848749Z","steps":["trace[1097857125] 'process raft request' (duration: 296.501063ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.848855Z","caller":"traceutil/trace.go:171","msg":"trace[442175329] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"300.278254ms","start":"2026-10-14T15:49:01.548562Z","end":"2026-10-14T15:49:01.84884Z","steps":["trace[442175329] 'process raft request' (duration: 299.434718ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.848976Z","caller":"traceutil/trace.go:171","msg":"trace[1245200001] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"296.913875ms","start":"2026-10-14T15:49:01.552046Z","end":"2026-10-14T15:49:01.84896Z","steps":["trace[1245200001] 'process raft request' (duration: 296.622066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:01.84898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:01.548522Z","time spent":"300.370867ms","remote":"127.0.0.1:52140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:49:01.849303Z","caller":"traceutil/trace.go:171","msg":"trace[1842370970] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"296.017538ms","start":"2026-10-14T15:49:01.553267Z","end":"2026-10-14T15:49:01.849284Z","steps":["trace[1842370970] 'process raft request' (duration: 295.639047ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.849498Z","caller":"traceutil/trace.go:171","msg":"trace[1103800718] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"296.239635ms","start":"2026-10-14T15:49:01.553243Z","end":"2026-10-14T15:49:01.849483Z","steps":["trace[1103800718] 'process raft request' (duration: 295.617147ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:01.849675Z","caller":"traceutil/trace.go:171","msg":"trace[1912104937] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"285.683382ms","start":"2026-10-14T15:49:01.56397Z","end":"2026-10-14T15:49:01.849653Z","steps":["trace[1912104937] 'process raft request' (duration: 284.991152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:02.018785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.058946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:49:02.019098Z","caller":"traceutil/trace.go:171","msg":"trace[1295368619] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"163.535488ms","start":"2026-10-14T15:49:01.85551Z","end":"2026-10-14T15:49:02.019046Z","steps":["trace[1295368619] 'process raft request' (duration: 28.06311ms)","trace[1295368619] 'compare' (duration: 134.18778ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:02.105919Z","caller":"traceutil/trace.go:171","msg":"trace[1323567692] linearizableReadLoop","detail":"{readStateIndex:4924; appliedIndex:4921; }","duration":"141.422294ms","start":"2026-10-14T15:49:01.964473Z","end":"2026-10-14T15:49:02.105895Z","steps":["trace[1323567692] 'read index received' (duration: 141.229418ms)","trace[1323567692] 'applied index is now lower than readState.Index' (duration: 191.576µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:02.105936Z","caller":"traceutil/trace.go:171","msg":"trace[468792890] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"247.362118ms","start":"2026-10-14T15:49:01.858541Z","end":"2026-10-14T15:49:02.105903Z","steps":["trace[468792890] 'process raft request' (duration: 247.258145ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:02.106111Z","caller":"traceutil/trace.go:171","msg":"trace[1152625842] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"235.902721ms","start":"2026-10-14T15:49:01.870167Z","end":"2026-10-14T15:49:02.106069Z","steps":["trace[1152625842] 'process raft request' (duration: 235.664613ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:02.106145Z","caller":"traceutil/trace.go:171","msg":"trace[69160580] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"249.662808ms","start":"2026-10-14T15:49:01.856401Z","end":"2026-10-14T15:49:02.106064Z","steps":["trace[69160580] 'process raft request' (duration: 249.220624ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:02.106251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.757405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:49:02.106341Z","caller":"traceutil/trace.go:171","msg":"trace[1357455185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4743; }","duration":"141.955851ms","start":"2026-10-14T15:49:01.964376Z","end":"2026-10-14T15:49:02.106332Z","steps":["trace[1357455185] 'agreement among raft nodes before linearized reading' (duration: 141.622211ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:02.145548Z","caller":"traceutil/trace.go:171","msg":"trace[549194957] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"179.698178ms","start":"2026-10-14T15:49:01.965814Z","end":"2026-10-14T15:49:02.145513Z","steps":["trace[549194957] 'process raft request' (duration: 156.099439ms)","trace[549194957] 'compare' (duration: 23.080013ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:49:02.146816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.722979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T15:49:02.146883Z","caller":"traceutil/trace.go:171","msg":"trace[396333141] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4745; }","duration":"151.825203ms","start":"2026-10-14T15:49:01.99504Z","end":"2026-10-14T15:49:02.146865Z","steps":["trace[396333141] 'agreement among raft nodes before linearized reading' (duration: 151.571835ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:06.374076Z","caller":"traceutil/trace.go:171","msg":"trace[1103470521] linearizableReadLoop","detail":"{readStateIndex:5040; appliedIndex:5039; }","duration":"112.112039ms","start":"2026-10-14T15:49:06.261943Z","end":"2026-10-14T15:49:06.374055Z","steps":["trace[1103470521] 'read index received' (duration: 68.954672ms)","trace[1103470521] 'applied index is now lower than readState.Index' (duration: 43.156217ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:06.374282Z","caller":"traceutil/trace.go:171","msg":"trace[1101763306] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"160.858703ms","start":"2026-10-14T15:49:06.213413Z","end":"2026-10-14T15:49:06.374271Z","steps":["trace[1101763306] 'process raft request' (duration: 117.54274ms)","trace[1101763306] 'compare' (duration: 42.944992ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:49:06.374484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.536832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T15:49:06.374511Z","caller":"traceutil/trace.go:171","msg":"trace[261607019] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4857; }","duration":"112.617934ms","start":"2026-10-14T15:49:06.261884Z","end":"2026-10-14T15:49:06.374502Z","steps":["trace[261607019] 'agreement among raft nodes before linearized reading' (duration: 112.517281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:06.374638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.952816ms","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-10-14T15:49:06.374714Z","caller":"traceutil/trace.go:171","msg":"trace[1754774350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4857; }","duration":"109.040168ms","start":"2026-10-14T15:49:06.265634Z","end":"2026-10-14T15:49:06.374675Z","steps":["trace[1754774350] 'agreement among raft nodes before linearized reading' (duration: 108.900944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:06.374681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.328079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-14T15:49:06.37479Z","caller":"traceutil/trace.go:171","msg":"trace[2147319601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4857; }","duration":"106.461152ms","start":"2026-10-14T15:49:06.268308Z","end":"2026-10-14T15:49:06.374769Z","steps":["trace[2147319601] 'agreement among raft nodes before linearized reading' (duration: 106.266696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:09.056503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.647966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:49:09.056587Z","caller":"traceutil/trace.go:171","msg":"trace[866616244] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:4955; }","duration":"166.76793ms","start":"2026-10-14T15:49:08.8898Z","end":"2026-10-14T15:49:09.056568Z","steps":["trace[866616244] 'count revisions from in-memory index tree' (duration: 166.593135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:09.057008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.720873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T15:49:09.056993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.983402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b773fcfb-aba2-4a03-9bf8-8f87496b9e37\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-14T15:49:09.057082Z","caller":"traceutil/trace.go:171","msg":"trace[1175913257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4955; }","duration":"100.836766ms","start":"2026-10-14T15:49:08.956226Z","end":"2026-10-14T15:49:09.057063Z","steps":["trace[1175913257] 'range keys from in-memory index tree' (duration: 100.650892ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:09.057103Z","caller":"traceutil/trace.go:171","msg":"trace[443819610] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b773fcfb-aba2-4a03-9bf8-8f87496b9e37; range_end:; response_count:1; response_revision:4955; }","duration":"127.116526ms","start":"2026-10-14T15:49:08.929968Z","end":"2026-10-14T15:49:09.057084Z","steps":["trace[443819610] 'range keys from in-memory index tree' (duration: 126.747085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:09.057173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.721345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-10-14T15:49:09.057207Z","caller":"traceutil/trace.go:171","msg":"trace[139138367] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4955; }","duration":"171.816858ms","start":"2026-10-14T15:49:08.885379Z","end":"2026-10-14T15:49:09.057196Z","steps":["trace[139138367] 'range keys from in-memory index tree' (duration: 171.281283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:11.057846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.868427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:49:11.057957Z","caller":"traceutil/trace.go:171","msg":"trace[1744760576] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4986; }","duration":"157.065083ms","start":"2026-10-14T15:49:10.900874Z","end":"2026-10-14T15:49:11.057939Z","steps":["trace[1744760576] 'count revisions from in-memory index tree' (duration: 156.731174ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:22.404193Z","caller":"traceutil/trace.go:171","msg":"trace[606454243] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"212.553448ms","start":"2026-10-14T15:49:22.191616Z","end":"2026-10-14T15:49:22.40417Z","steps":["trace[606454243] 'process raft request' (duration: 212.381613ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.090187Z","caller":"traceutil/trace.go:171","msg":"trace[561589329] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5317; }","duration":"844.964093ms","start":"2026-10-14T15:49:22.245197Z","end":"2026-10-14T15:49:23.090161Z","steps":["trace[561589329] 'read index received' (duration: 158.922205ms)","trace[561589329] 'applied index is now lower than readState.Index' (duration: 686.040908ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:23.090274Z","caller":"traceutil/trace.go:171","msg":"trace[319965025] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"857.273679ms","start":"2026-10-14T15:49:22.232961Z","end":"2026-10-14T15:49:23.090234Z","steps":["trace[319965025] 'process raft request' (duration: 857.044122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.090445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.231411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-10-14T15:49:23.090505Z","caller":"traceutil/trace.go:171","msg":"trace[1036778980] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5128; }","duration":"845.337764ms","start":"2026-10-14T15:49:22.245149Z","end":"2026-10-14T15:49:23.090486Z","steps":["trace[1036778980] 'agreement among raft nodes before linearized reading' (duration: 845.132228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.09055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.245129Z","time spent":"845.407946ms","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-14T15:49:23.090588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.232936Z","time spent":"857.417143ms","remote":"127.0.0.1:52140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:49:23.227065Z","caller":"traceutil/trace.go:171","msg":"trace[1409283301] linearizableReadLoop","detail":"{readStateIndex:5320; appliedIndex:5319; }","duration":"136.78688ms","start":"2026-10-14T15:49:23.090254Z","end":"2026-10-14T15:49:23.227041Z","steps":["trace[1409283301] 'read index received' (duration: 136.573094ms)","trace[1409283301] 'applied index is now lower than readState.Index' (duration: 212.936µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:49:23.22722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.229413ms","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":"warn","ts":"2026-10-14T15:49:23.227208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.296362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:49:23.227245Z","caller":"traceutil/trace.go:171","msg":"trace[1672399926] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"264.699805ms","start":"2026-10-14T15:49:22.962514Z","end":"2026-10-14T15:49:23.227213Z","steps":["trace[1672399926] 'process raft request' (duration: 264.351635ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.227328Z","caller":"traceutil/trace.go:171","msg":"trace[911086149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5129; }","duration":"269.470297ms","start":"2026-10-14T15:49:22.95784Z","end":"2026-10-14T15:49:23.22731Z","steps":["trace[911086149] 'agreement among raft nodes before linearized reading' (duration: 269.283862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.227358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.239032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-14T15:49:23.227358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.638219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-14T15:49:23.227397Z","caller":"traceutil/trace.go:171","msg":"trace[135452587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5129; }","duration":"754.310314ms","start":"2026-10-14T15:49:22.473073Z","end":"2026-10-14T15:49:23.227383Z","steps":["trace[135452587] 'agreement among raft nodes before linearized reading' (duration: 754.231982ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.227408Z","caller":"traceutil/trace.go:171","msg":"trace[873406174] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5129; }","duration":"737.708051ms","start":"2026-10-14T15:49:22.489687Z","end":"2026-10-14T15:49:23.227395Z","steps":["trace[873406174] 'agreement among raft nodes before linearized reading' (duration: 737.612669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.227448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.489675Z","time spent":"737.762553ms","remote":"127.0.0.1:52140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T15:49:23.227479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.554339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T15:49:23.227552Z","caller":"traceutil/trace.go:171","msg":"trace[434793258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5129; }","duration":"762.662522ms","start":"2026-10-14T15:49:22.464877Z","end":"2026-10-14T15:49:23.22754Z","steps":["trace[434793258] 'agreement among raft nodes before linearized reading' (duration: 762.495707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.227499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.28867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-14T15:49:23.227575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.491153ms","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":"warn","ts":"2026-10-14T15:49:23.22759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.464861Z","time spent":"762.720994ms","remote":"127.0.0.1:52140","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":"info","ts":"2026-10-14T15:49:23.227618Z","caller":"traceutil/trace.go:171","msg":"trace[1281946774] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5129; }","duration":"896.568236ms","start":"2026-10-14T15:49:22.331037Z","end":"2026-10-14T15:49:23.227605Z","steps":["trace[1281946774] 'agreement among raft nodes before linearized reading' (duration: 896.431602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.227447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.47306Z","time spent":"754.374657ms","remote":"127.0.0.1:52140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T15:49:23.227651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.331016Z","time spent":"896.628728ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-14T15:49:23.227642Z","caller":"traceutil/trace.go:171","msg":"trace[1723050431] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5129; }","duration":"670.475866ms","start":"2026-10-14T15:49:22.557114Z","end":"2026-10-14T15:49:23.22759Z","steps":["trace[1723050431] 'agreement among raft nodes before linearized reading' (duration: 670.128866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.227278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.742649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-10-14T15:49:23.227275Z","caller":"traceutil/trace.go:171","msg":"trace[1141568374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5129; }","duration":"748.325056ms","start":"2026-10-14T15:49:22.478933Z","end":"2026-10-14T15:49:23.227258Z","steps":["trace[1141568374] 'agreement among raft nodes before linearized reading' (duration: 748.141581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.2277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.557097Z","time spent":"670.59077ms","remote":"127.0.0.1:52140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T15:49:23.227786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.478921Z","time spent":"748.845272ms","remote":"127.0.0.1:52140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T15:49:23.227717Z","caller":"traceutil/trace.go:171","msg":"trace[846493014] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5129; }","duration":"753.219443ms","start":"2026-10-14T15:49:22.474487Z","end":"2026-10-14T15:49:23.227706Z","steps":["trace[846493014] 'agreement among raft nodes before linearized reading' (duration: 752.682507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.227831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.714924ms","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-10-14T15:49:23.227862Z","caller":"traceutil/trace.go:171","msg":"trace[1107734284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5129; }","duration":"761.746345ms","start":"2026-10-14T15:49:22.466107Z","end":"2026-10-14T15:49:23.227853Z","steps":["trace[1107734284] 'agreement among raft nodes before linearized reading' (duration: 761.641452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.227884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.474478Z","time spent":"753.365097ms","remote":"127.0.0.1:39968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-10-14T15:49:23.227894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:22.466097Z","time spent":"761.791607ms","remote":"127.0.0.1:52140","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-10-14T15:49:23.372641Z","caller":"traceutil/trace.go:171","msg":"trace[2075868258] linearizableReadLoop","detail":"{readStateIndex:5321; appliedIndex:5320; }","duration":"145.475737ms","start":"2026-10-14T15:49:23.227143Z","end":"2026-10-14T15:49:23.372619Z","steps":["trace[2075868258] 'read index received' (duration: 104.166482ms)","trace[2075868258] 'applied index is now lower than readState.Index' (duration: 41.308375ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:23.372764Z","caller":"traceutil/trace.go:171","msg":"trace[1174212259] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"269.89183ms","start":"2026-10-14T15:49:23.102809Z","end":"2026-10-14T15:49:23.372701Z","steps":["trace[1174212259] 'process raft request' (duration: 228.538283ms)","trace[1174212259] 'compare' (duration: 41.154971ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:49:23.372796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.987629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:49:23.372836Z","caller":"traceutil/trace.go:171","msg":"trace[796176137] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5130; }","duration":"281.084772ms","start":"2026-10-14T15:49:23.091741Z","end":"2026-10-14T15:49:23.372826Z","steps":["trace[796176137] 'agreement among raft nodes before linearized reading' (duration: 281.001529ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.389792Z","caller":"traceutil/trace.go:171","msg":"trace[2131806759] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"155.462243ms","start":"2026-10-14T15:49:23.234308Z","end":"2026-10-14T15:49:23.389771Z","steps":["trace[2131806759] 'process raft request' (duration: 155.264378ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.390016Z","caller":"traceutil/trace.go:171","msg":"trace[2075635508] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"155.68245ms","start":"2026-10-14T15:49:23.234324Z","end":"2026-10-14T15:49:23.390006Z","steps":["trace[2075635508] 'process raft request' (duration: 155.369481ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.390203Z","caller":"traceutil/trace.go:171","msg":"trace[299553731] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"155.751662ms","start":"2026-10-14T15:49:23.234442Z","end":"2026-10-14T15:49:23.390194Z","steps":["trace[299553731] 'process raft request' (duration: 155.519285ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.390383Z","caller":"traceutil/trace.go:171","msg":"trace[1140425258] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"152.579658ms","start":"2026-10-14T15:49:23.237795Z","end":"2026-10-14T15:49:23.390374Z","steps":["trace[1140425258] 'process raft request' (duration: 152.545347ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.39065Z","caller":"traceutil/trace.go:171","msg":"trace[2111692515] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"155.786912ms","start":"2026-10-14T15:49:23.234836Z","end":"2026-10-14T15:49:23.390623Z","steps":["trace[2111692515] 'process raft request' (duration: 155.320549ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.390859Z","caller":"traceutil/trace.go:171","msg":"trace[1038169633] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"155.928127ms","start":"2026-10-14T15:49:23.234914Z","end":"2026-10-14T15:49:23.390842Z","steps":["trace[1038169633] 'process raft request' (duration: 155.391481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.391058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.868706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b773fcfb-aba2-4a03-9bf8-8f87496b9e37\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-14T15:49:23.391095Z","caller":"traceutil/trace.go:171","msg":"trace[45919323] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b773fcfb-aba2-4a03-9bf8-8f87496b9e37; range_end:; response_count:1; response_revision:5136; }","duration":"159.945028ms","start":"2026-10-14T15:49:23.23114Z","end":"2026-10-14T15:49:23.391085Z","steps":["trace[45919323] 'agreement among raft nodes before linearized reading' (duration: 159.813364ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.582959Z","caller":"traceutil/trace.go:171","msg":"trace[360201925] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5327; }","duration":"192.05621ms","start":"2026-10-14T15:49:23.390881Z","end":"2026-10-14T15:49:23.582937Z","steps":["trace[360201925] 'read index received' (duration: 145.692965ms)","trace[360201925] 'applied index is now lower than readState.Index' (duration: 46.362335ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:49:23.583223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.334324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5f7c4765db-r866q\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-10-14T15:49:23.583259Z","caller":"traceutil/trace.go:171","msg":"trace[1479676079] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5f7c4765db-r866q; range_end:; response_count:1; response_revision:5140; }","duration":"204.427498ms","start":"2026-10-14T15:49:23.378821Z","end":"2026-10-14T15:49:23.583248Z","steps":["trace[1479676079] 'agreement among raft nodes before linearized reading' (duration: 204.203551ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.583343Z","caller":"traceutil/trace.go:171","msg":"trace[1938441949] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"202.465989ms","start":"2026-10-14T15:49:23.38085Z","end":"2026-10-14T15:49:23.583316Z","steps":["trace[1938441949] 'process raft request' (duration: 202.013095ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.583411Z","caller":"traceutil/trace.go:171","msg":"trace[1857184326] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"202.560471ms","start":"2026-10-14T15:49:23.380811Z","end":"2026-10-14T15:49:23.583371Z","steps":["trace[1857184326] 'process raft request' (duration: 201.991125ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.583523Z","caller":"traceutil/trace.go:171","msg":"trace[853903422] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"202.792348ms","start":"2026-10-14T15:49:23.380712Z","end":"2026-10-14T15:49:23.583504Z","steps":["trace[853903422] 'process raft request' (duration: 155.795483ms)","trace[853903422] 'compare' (duration: 46.12298ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:23.583658Z","caller":"traceutil/trace.go:171","msg":"trace[1845074284] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"201.697066ms","start":"2026-10-14T15:49:23.381944Z","end":"2026-10-14T15:49:23.583642Z","steps":["trace[1845074284] 'process raft request' (duration: 200.954334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.940319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.40324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:49:23.941088Z","caller":"traceutil/trace.go:171","msg":"trace[1778904540] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"297.291293ms","start":"2026-10-14T15:49:23.643758Z","end":"2026-10-14T15:49:23.94105Z","steps":["trace[1778904540] 'process raft request' (duration: 165.063359ms)","trace[1778904540] 'compare' (duration: 131.221524ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:23.992196Z","caller":"traceutil/trace.go:171","msg":"trace[531679918] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"332.21794ms","start":"2026-10-14T15:49:23.65995Z","end":"2026-10-14T15:49:23.992168Z","steps":["trace[531679918] 'process raft request' (duration: 331.998373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.992351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:23.659926Z","time spent":"332.351763ms","remote":"127.0.0.1:52060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:49:23.99287Z","caller":"traceutil/trace.go:171","msg":"trace[1207944082] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"329.469998ms","start":"2026-10-14T15:49:23.663387Z","end":"2026-10-14T15:49:23.992857Z","steps":["trace[1207944082] 'process raft request' (duration: 328.941742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.992942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:23.66337Z","time spent":"329.537239ms","remote":"127.0.0.1:52378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:49:23.993138Z","caller":"traceutil/trace.go:171","msg":"trace[2008419324] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"295.492259ms","start":"2026-10-14T15:49:23.697617Z","end":"2026-10-14T15:49:23.993109Z","steps":["trace[2008419324] 'process raft request' (duration: 294.974214ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:23.993392Z","caller":"traceutil/trace.go:171","msg":"trace[399138873] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"333.23342ms","start":"2026-10-14T15:49:23.660133Z","end":"2026-10-14T15:49:23.993367Z","steps":["trace[399138873] 'process raft request' (duration: 331.965842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:23.994336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:49:23.660125Z","time spent":"333.322752ms","remote":"127.0.0.1:52150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:49:24.171377Z","caller":"traceutil/trace.go:171","msg":"trace[168079092] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"161.952047ms","start":"2026-10-14T15:49:24.009405Z","end":"2026-10-14T15:49:24.171357Z","steps":["trace[168079092] 'process raft request' (duration: 142.5211ms)","trace[168079092] 'compare' (duration: 19.225131ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:46.535981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2832} {"level":"info","ts":"2026-10-14T15:49:46.618736Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2832,"took":"81.253448ms","hash":1170113554,"current-db-size-bytes":53022720,"current-db-size":"53 MB","current-db-size-in-use-bytes":28106752,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T15:49:46.618811Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1170113554,"revision":2832,"compact-revision":-1} {"level":"info","ts":"2026-10-14T15:54:46.542801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5303} {"level":"info","ts":"2026-10-14T15:54:46.63997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5303,"took":"95.879507ms","hash":734369345,"current-db-size-bytes":53022720,"current-db-size":"53 MB","current-db-size-in-use-bytes":18731008,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-14T15:54:46.640164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":734369345,"revision":5303,"compact-revision":2832}