{"level":"warn","ts":"2026-06-16T10:19:11.310537Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T10:19:11.310636Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-06-16T10:19:11.310756Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T10:19:11.310768Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-06-16T10:19:11.310791Z","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-06-16T10:19:11.311824Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-06-16T10:19:11.311937Z","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://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-06-16T10:19:11.316685Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.494283ms"} {"level":"info","ts":"2026-06-16T10:19:11.325947Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-06-16T10:19:11.326134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T10:19:11.326252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-06-16T10:19:11.326292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T10:19:11.326376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-06-16T10:19:11.32649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-06-16T10:19:11.343245Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T10:19:11.345774Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T10:19:11.347326Z","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-06-16T10:19:11.3513Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T10:19:11.351652Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T10:19:11.3523Z","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-06-16T10:19:11.353059Z","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-06-16T10:19:11.353078Z","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-06-16T10:19:11.353162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-06-16T10:19:11.357325Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-06-16T10:19:11.357593Z","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-06-16T10:19:11.357713Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-06-16T10:19:11.357758Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-06-16T10:19:11.358162Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T10:19:11.358239Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T10:19:11.526949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T10:19:11.527007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T10:19:11.527051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-06-16T10:19:11.527074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-06-16T10:19:11.527084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-06-16T10:19:11.527103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-06-16T10:19:11.527116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-06-16T10:19:11.528115Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T10:19:11.528185Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T10:19:11.528235Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:19:11.528352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T10:19:11.528397Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T10:19:11.528428Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T10:19:11.530052Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:19:11.530245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:19:11.530328Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T10:19:11.53292Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T10:19:11.535039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-06-16T10:19:35.67721Z","caller":"traceutil/trace.go:171","msg":"trace[1573530408] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"702.979513ms","start":"2026-06-16T10:19:34.974207Z","end":"2026-06-16T10:19:35.677187Z","steps":["trace[1573530408] 'process raft request' (duration: 664.911936ms)","trace[1573530408] 'compare' (duration: 37.957906ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:19:35.677662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:34.974151Z","time spent":"703.166606ms","remote":"127.0.0.1:59194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:19:35.685776Z","caller":"traceutil/trace.go:171","msg":"trace[1000721909] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:445; }","duration":"462.954424ms","start":"2026-06-16T10:19:35.222806Z","end":"2026-06-16T10:19:35.685761Z","steps":["trace[1000721909] 'read index received' (duration: 416.33448ms)","trace[1000721909] 'applied index is now lower than readState.Index' (duration: 46.619213ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:19:35.685868Z","caller":"traceutil/trace.go:171","msg":"trace[784112352] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"707.735365ms","start":"2026-06-16T10:19:34.978126Z","end":"2026-06-16T10:19:35.685861Z","steps":["trace[784112352] 'process raft request' (duration: 707.505503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:35.685993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:34.978111Z","time spent":"707.773466ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:19:35.686105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.268447ms","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-06-16T10:19:35.686258Z","caller":"traceutil/trace.go:171","msg":"trace[270337554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"463.464659ms","start":"2026-06-16T10:19:35.222764Z","end":"2026-06-16T10:19:35.686229Z","steps":["trace[270337554] 'agreement among raft nodes before linearized reading' (duration: 463.189816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:35.68631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:35.222741Z","time spent":"463.559169ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T10:19:40.810502Z","caller":"traceutil/trace.go:171","msg":"trace[789664118] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"194.622664ms","start":"2026-06-16T10:19:40.615851Z","end":"2026-06-16T10:19:40.810474Z","steps":["trace[789664118] 'process raft request' (duration: 194.238061ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:42.163376Z","caller":"traceutil/trace.go:171","msg":"trace[661014609] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"114.85588ms","start":"2026-06-16T10:19:42.048498Z","end":"2026-06-16T10:19:42.163354Z","steps":["trace[661014609] 'process raft request' (duration: 114.731829ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:45.84069Z","caller":"traceutil/trace.go:171","msg":"trace[1904520283] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"103.235975ms","start":"2026-06-16T10:19:45.737433Z","end":"2026-06-16T10:19:45.840669Z","steps":["trace[1904520283] 'process raft request' (duration: 102.759001ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.909039Z","caller":"traceutil/trace.go:171","msg":"trace[1564469435] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"204.327318ms","start":"2026-06-16T10:19:50.704683Z","end":"2026-06-16T10:19:50.90901Z","steps":["trace[1564469435] 'process raft request' (duration: 204.109897ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.917948Z","caller":"traceutil/trace.go:171","msg":"trace[472284593] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"209.885132ms","start":"2026-06-16T10:19:50.708035Z","end":"2026-06-16T10:19:50.91792Z","steps":["trace[472284593] 'process raft request' (duration: 209.746841ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.917977Z","caller":"traceutil/trace.go:171","msg":"trace[1575511022] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"209.864102ms","start":"2026-06-16T10:19:50.708077Z","end":"2026-06-16T10:19:50.917941Z","steps":["trace[1575511022] 'process raft request' (duration: 209.785862ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.918208Z","caller":"traceutil/trace.go:171","msg":"trace[2078493239] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"199.903313ms","start":"2026-06-16T10:19:50.71827Z","end":"2026-06-16T10:19:50.918173Z","steps":["trace[2078493239] 'process raft request' (duration: 199.669101ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.918436Z","caller":"traceutil/trace.go:171","msg":"trace[419536885] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"201.947689ms","start":"2026-06-16T10:19:50.716476Z","end":"2026-06-16T10:19:50.918423Z","steps":["trace[419536885] 'process raft request' (duration: 201.415515ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.918665Z","caller":"traceutil/trace.go:171","msg":"trace[1422892910] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"160.832034ms","start":"2026-06-16T10:19:50.757819Z","end":"2026-06-16T10:19:50.918651Z","steps":["trace[1422892910] 'process raft request' (duration: 160.39697ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.918674Z","caller":"traceutil/trace.go:171","msg":"trace[645203167] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"142.389238ms","start":"2026-06-16T10:19:50.776273Z","end":"2026-06-16T10:19:50.918662Z","steps":["trace[645203167] 'process raft request' (duration: 142.044415ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.918819Z","caller":"traceutil/trace.go:171","msg":"trace[66346569] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"195.674659ms","start":"2026-06-16T10:19:50.723134Z","end":"2026-06-16T10:19:50.918809Z","steps":["trace[66346569] 'process raft request' (duration: 194.983364ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.918895Z","caller":"traceutil/trace.go:171","msg":"trace[297022023] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"199.585711ms","start":"2026-06-16T10:19:50.719302Z","end":"2026-06-16T10:19:50.918888Z","steps":["trace[297022023] 'process raft request' (duration: 198.676184ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.918822Z","caller":"traceutil/trace.go:171","msg":"trace[1912187506] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"194.52885ms","start":"2026-06-16T10:19:50.724282Z","end":"2026-06-16T10:19:50.918811Z","steps":["trace[1912187506] 'process raft request' (duration: 193.872755ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:50.918894Z","caller":"traceutil/trace.go:171","msg":"trace[967742000] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"157.327386ms","start":"2026-06-16T10:19:50.761505Z","end":"2026-06-16T10:19:50.918833Z","steps":["trace[967742000] 'process raft request' (duration: 156.751802ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.171701Z","caller":"traceutil/trace.go:171","msg":"trace[1203407469] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"226.506595ms","start":"2026-06-16T10:19:50.945161Z","end":"2026-06-16T10:19:51.171668Z","steps":["trace[1203407469] 'process raft request' (duration: 226.242993ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.17255Z","caller":"traceutil/trace.go:171","msg":"trace[158664349] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"228.685513ms","start":"2026-06-16T10:19:50.941821Z","end":"2026-06-16T10:19:51.170507Z","steps":["trace[158664349] 'process raft request' (duration: 219.527811ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.181276Z","caller":"traceutil/trace.go:171","msg":"trace[2058637832] transaction","detail":"{read_only:false; number_of_response:1; response_revision:552; }","duration":"229.820762ms","start":"2026-06-16T10:19:50.951422Z","end":"2026-06-16T10:19:51.181242Z","steps":["trace[2058637832] 'process raft request' (duration: 229.65094ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.18131Z","caller":"traceutil/trace.go:171","msg":"trace[1519501780] transaction","detail":"{read_only:false; number_of_response:0; response_revision:552; }","duration":"222.584384ms","start":"2026-06-16T10:19:50.958686Z","end":"2026-06-16T10:19:51.18127Z","steps":["trace[1519501780] 'process raft request' (duration: 222.470993ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.181454Z","caller":"traceutil/trace.go:171","msg":"trace[281682919] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"195.268118ms","start":"2026-06-16T10:19:50.98617Z","end":"2026-06-16T10:19:51.181438Z","steps":["trace[281682919] 'process raft request' (duration: 195.209448ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.181578Z","caller":"traceutil/trace.go:171","msg":"trace[1223295750] transaction","detail":"{read_only:false; number_of_response:0; response_revision:552; }","duration":"222.800096ms","start":"2026-06-16T10:19:50.958773Z","end":"2026-06-16T10:19:51.181573Z","steps":["trace[1223295750] 'process raft request' (duration: 222.400023ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.181648Z","caller":"traceutil/trace.go:171","msg":"trace[1368574931] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"216.384125ms","start":"2026-06-16T10:19:50.965253Z","end":"2026-06-16T10:19:51.181637Z","steps":["trace[1368574931] 'process raft request' (duration: 215.947881ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.181699Z","caller":"traceutil/trace.go:171","msg":"trace[1156902531] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"202.834818ms","start":"2026-06-16T10:19:50.978858Z","end":"2026-06-16T10:19:51.181693Z","steps":["trace[1156902531] 'process raft request' (duration: 202.437395ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:51.181758Z","caller":"traceutil/trace.go:171","msg":"trace[1458367152] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"202.759858ms","start":"2026-06-16T10:19:50.978991Z","end":"2026-06-16T10:19:51.181751Z","steps":["trace[1458367152] 'process raft request' (duration: 202.352364ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:52.309715Z","caller":"traceutil/trace.go:171","msg":"trace[1903595215] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"166.583783ms","start":"2026-06-16T10:19:52.143112Z","end":"2026-06-16T10:19:52.309696Z","steps":["trace[1903595215] 'process raft request' (duration: 166.442112ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:52.319875Z","caller":"traceutil/trace.go:171","msg":"trace[2130765038] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"147.511524ms","start":"2026-06-16T10:19:52.172339Z","end":"2026-06-16T10:19:52.319851Z","steps":["trace[2130765038] 'process raft request' (duration: 146.439755ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:19:52.320098Z","caller":"traceutil/trace.go:171","msg":"trace[1185808704] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"114.520725ms","start":"2026-06-16T10:19:52.205556Z","end":"2026-06-16T10:19:52.320077Z","steps":["trace[1185808704] 'read index received' (duration: 104.060133ms)","trace[1185808704] 'applied index is now lower than readState.Index' (duration: 10.459632ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:19:52.320221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.645657ms","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-06-16T10:19:52.320345Z","caller":"traceutil/trace.go:171","msg":"trace[546306663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"114.803078ms","start":"2026-06-16T10:19:52.205505Z","end":"2026-06-16T10:19:52.320308Z","steps":["trace[546306663] 'agreement among raft nodes before linearized reading' (duration: 114.657857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:57.117149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.869822ms","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-06-16T10:19:57.117214Z","caller":"traceutil/trace.go:171","msg":"trace[426884556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"149.980773ms","start":"2026-06-16T10:19:56.967219Z","end":"2026-06-16T10:19:57.117199Z","steps":["trace[426884556] 'range keys from in-memory index tree' (duration: 149.677401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:57.807965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.209256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:19:57.808083Z","caller":"traceutil/trace.go:171","msg":"trace[457633094] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"686.911946ms","start":"2026-06-16T10:19:57.121154Z","end":"2026-06-16T10:19:57.808066Z","steps":["trace[457633094] 'process raft request' (duration: 303.357237ms)","trace[457633094] 'compare' (duration: 382.952954ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:19:57.808166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:57.121132Z","time spent":"687.005086ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:19:57.861462Z","caller":"traceutil/trace.go:171","msg":"trace[776181116] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"344.356981ms","start":"2026-06-16T10:19:57.517077Z","end":"2026-06-16T10:19:57.861434Z","steps":["trace[776181116] 'read index received' (duration: 343.267362ms)","trace[776181116] 'applied index is now lower than readState.Index' (duration: 1.088589ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:19:57.861574Z","caller":"traceutil/trace.go:171","msg":"trace[947413170] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"598.355813ms","start":"2026-06-16T10:19:57.263184Z","end":"2026-06-16T10:19:57.861539Z","steps":["trace[947413170] 'process raft request' (duration: 597.703958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:57.861649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.547062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:19:57.86179Z","caller":"traceutil/trace.go:171","msg":"trace[425065793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"344.746703ms","start":"2026-06-16T10:19:57.51703Z","end":"2026-06-16T10:19:57.861777Z","steps":["trace[425065793] 'agreement among raft nodes before linearized reading' (duration: 344.561212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:57.861788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:57.263149Z","time spent":"598.540275ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:19:57.861826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:57.517006Z","time spent":"344.810634ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T10:19:57.862027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.049893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wbr6v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-16T10:19:57.862145Z","caller":"traceutil/trace.go:171","msg":"trace[1738887500] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wbr6v; range_end:; response_count:1; response_revision:638; }","duration":"271.197914ms","start":"2026-06-16T10:19:57.590902Z","end":"2026-06-16T10:19:57.8621Z","steps":["trace[1738887500] 'agreement among raft nodes before linearized reading' (duration: 270.831111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:59.019527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745400496677718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T10:19:59.26649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.105758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T10:19:59.266605Z","caller":"traceutil/trace.go:171","msg":"trace[789694984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"915.27735ms","start":"2026-06-16T10:19:58.351289Z","end":"2026-06-16T10:19:59.266566Z","steps":["trace[789694984] 'range keys from in-memory index tree' (duration: 914.953137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:59.266663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:58.351264Z","time spent":"915.382981ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T10:19:59.267291Z","caller":"traceutil/trace.go:171","msg":"trace[405983862] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"748.260925ms","start":"2026-06-16T10:19:58.519003Z","end":"2026-06-16T10:19:59.267264Z","steps":["trace[405983862] 'read index received' (duration: 731.811131ms)","trace[405983862] 'applied index is now lower than readState.Index' (duration: 16.448614ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:19:59.267437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.424917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:19:59.267472Z","caller":"traceutil/trace.go:171","msg":"trace[555122176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"748.509817ms","start":"2026-06-16T10:19:58.518949Z","end":"2026-06-16T10:19:59.267459Z","steps":["trace[555122176] 'agreement among raft nodes before linearized reading' (duration: 748.420016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:19:59.267504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:58.518918Z","time spent":"748.577708ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T10:19:59.267649Z","caller":"traceutil/trace.go:171","msg":"trace[2001760921] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"817.004296ms","start":"2026-06-16T10:19:58.450628Z","end":"2026-06-16T10:19:59.267632Z","steps":["trace[2001760921] 'process raft request' (duration: 800.438321ms)","trace[2001760921] 'compare' (duration: 15.751939ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:19:59.267746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:19:58.4506Z","time spent":"817.088007ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:20:01.071036Z","caller":"traceutil/trace.go:171","msg":"trace[137344262] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"297.862401ms","start":"2026-06-16T10:20:00.773158Z","end":"2026-06-16T10:20:01.07102Z","steps":["trace[137344262] 'process raft request' (duration: 297.76433ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:01.075699Z","caller":"traceutil/trace.go:171","msg":"trace[2114250688] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"104.478791ms","start":"2026-06-16T10:20:00.971196Z","end":"2026-06-16T10:20:01.075674Z","steps":["trace[2114250688] 'process raft request' (duration: 104.35833ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:04.823269Z","caller":"traceutil/trace.go:171","msg":"trace[370983223] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"120.670108ms","start":"2026-06-16T10:20:04.702562Z","end":"2026-06-16T10:20:04.823232Z","steps":["trace[370983223] 'process raft request' (duration: 115.647581ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:04.874666Z","caller":"traceutil/trace.go:171","msg":"trace[719006421] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:753; }","duration":"105.372785ms","start":"2026-06-16T10:20:04.769268Z","end":"2026-06-16T10:20:04.87464Z","steps":["trace[719006421] 'read index received' (duration: 48.95523ms)","trace[719006421] 'applied index is now lower than readState.Index' (duration: 56.416575ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:20:04.874853Z","caller":"traceutil/trace.go:171","msg":"trace[1262240782] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"115.41235ms","start":"2026-06-16T10:20:04.759322Z","end":"2026-06-16T10:20:04.874734Z","steps":["trace[1262240782] 'process raft request' (duration: 115.136028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:04.875012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.744318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-06-16T10:20:04.876057Z","caller":"traceutil/trace.go:171","msg":"trace[1425878790] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"106.820697ms","start":"2026-06-16T10:20:04.769189Z","end":"2026-06-16T10:20:04.87601Z","steps":["trace[1425878790] 'agreement among raft nodes before linearized reading' (duration: 105.559857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:15.103904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.936606ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:20:15.10405Z","caller":"traceutil/trace.go:171","msg":"trace[1664366198] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:803; }","duration":"187.100534ms","start":"2026-06-16T10:20:14.916931Z","end":"2026-06-16T10:20:15.104032Z","steps":["trace[1664366198] 'range keys from in-memory index tree' (duration: 186.916225ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:15.104972Z","caller":"traceutil/trace.go:171","msg":"trace[134445540] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"410.834208ms","start":"2026-06-16T10:20:14.694104Z","end":"2026-06-16T10:20:15.104938Z","steps":["trace[134445540] 'read index received' (duration: 410.188276ms)","trace[134445540] 'applied index is now lower than readState.Index' (duration: 644.712µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:20:15.104979Z","caller":"traceutil/trace.go:171","msg":"trace[113162619] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"576.219083ms","start":"2026-06-16T10:20:14.528729Z","end":"2026-06-16T10:20:15.104948Z","steps":["trace[113162619] 'process raft request' (duration: 575.916587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:15.10517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.038919ms","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-06-16T10:20:15.105271Z","caller":"traceutil/trace.go:171","msg":"trace[1983851866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"411.163085ms","start":"2026-06-16T10:20:14.694049Z","end":"2026-06-16T10:20:15.105212Z","steps":["trace[1983851866] 'agreement among raft nodes before linearized reading' (duration: 410.959835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:15.105349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:20:14.694022Z","time spent":"411.287001ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T10:20:15.105458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:20:14.528705Z","time spent":"576.465305ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:20:15.118837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.927007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:20:15.119026Z","caller":"traceutil/trace.go:171","msg":"trace[826279960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"316.148328ms","start":"2026-06-16T10:20:14.802854Z","end":"2026-06-16T10:20:15.119002Z","steps":["trace[826279960] 'agreement among raft nodes before linearized reading' (duration: 315.923787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:15.119042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.978239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-16T10:20:15.119129Z","caller":"traceutil/trace.go:171","msg":"trace[1785589799] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:805; }","duration":"316.116677ms","start":"2026-06-16T10:20:14.802992Z","end":"2026-06-16T10:20:15.119109Z","steps":["trace[1785589799] 'agreement among raft nodes before linearized reading' (duration: 315.907976ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:15.118878Z","caller":"traceutil/trace.go:171","msg":"trace[1741053811] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"412.868244ms","start":"2026-06-16T10:20:14.70598Z","end":"2026-06-16T10:20:15.118848Z","steps":["trace[1741053811] 'process raft request' (duration: 412.679095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:15.119139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:20:14.802834Z","time spent":"316.262984ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T10:20:15.119296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:20:14.705958Z","time spent":"413.251964ms","remote":"127.0.0.1:59398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:20:15.119194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:20:14.802964Z","time spent":"316.213741ms","remote":"127.0.0.1:59194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-06-16T10:20:26.917316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.081969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:20:26.91744Z","caller":"traceutil/trace.go:171","msg":"trace[983665510] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"160.866868ms","start":"2026-06-16T10:20:26.756546Z","end":"2026-06-16T10:20:26.917413Z","steps":["trace[983665510] 'process raft request' (duration: 55.570839ms)","trace[983665510] 'compare' (duration: 104.921373ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:20:26.980878Z","caller":"traceutil/trace.go:171","msg":"trace[1761264269] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"218.182085ms","start":"2026-06-16T10:20:26.762664Z","end":"2026-06-16T10:20:26.980846Z","steps":["trace[1761264269] 'process raft request' (duration: 218.118802ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:26.980899Z","caller":"traceutil/trace.go:171","msg":"trace[1035051629] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"224.14815ms","start":"2026-06-16T10:20:26.756729Z","end":"2026-06-16T10:20:26.980877Z","steps":["trace[1035051629] 'process raft request' (duration: 223.885549ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:27.130356Z","caller":"traceutil/trace.go:171","msg":"trace[360493027] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"145.318773ms","start":"2026-06-16T10:20:26.985Z","end":"2026-06-16T10:20:27.130319Z","steps":["trace[360493027] 'process raft request' (duration: 95.114358ms)","trace[360493027] 'compare' (duration: 49.768225ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:20:27.130469Z","caller":"traceutil/trace.go:171","msg":"trace[386438955] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"142.149573ms","start":"2026-06-16T10:20:26.988258Z","end":"2026-06-16T10:20:27.130407Z","steps":["trace[386438955] 'process raft request' (duration: 141.8551ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:27.389967Z","caller":"traceutil/trace.go:171","msg":"trace[340083840] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"128.18939ms","start":"2026-06-16T10:20:27.26175Z","end":"2026-06-16T10:20:27.38994Z","steps":["trace[340083840] 'process raft request' (duration: 85.412561ms)","trace[340083840] 'compare' (duration: 42.315078ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:20:27.653837Z","caller":"traceutil/trace.go:171","msg":"trace[1542318944] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"150.126474ms","start":"2026-06-16T10:20:27.503688Z","end":"2026-06-16T10:20:27.653814Z","steps":["trace[1542318944] 'read index received' (duration: 150.03705ms)","trace[1542318944] 'applied index is now lower than readState.Index' (duration: 88.374µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:20:27.653871Z","caller":"traceutil/trace.go:171","msg":"trace[1480563120] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"150.793124ms","start":"2026-06-16T10:20:27.50304Z","end":"2026-06-16T10:20:27.653833Z","steps":["trace[1480563120] 'process raft request' (duration: 150.641657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:27.653998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.296342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:20:27.654206Z","caller":"traceutil/trace.go:171","msg":"trace[1164647252] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:945; }","duration":"150.518201ms","start":"2026-06-16T10:20:27.503675Z","end":"2026-06-16T10:20:27.654193Z","steps":["trace[1164647252] 'agreement among raft nodes before linearized reading' (duration: 150.245649ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:27.677374Z","caller":"traceutil/trace.go:171","msg":"trace[1822333556] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"172.679955ms","start":"2026-06-16T10:20:27.504672Z","end":"2026-06-16T10:20:27.677352Z","steps":["trace[1822333556] 'process raft request' (duration: 172.458175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:27.677402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.744097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:20:27.677461Z","caller":"traceutil/trace.go:171","msg":"trace[1813237413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"159.856872ms","start":"2026-06-16T10:20:27.517589Z","end":"2026-06-16T10:20:27.677446Z","steps":["trace[1813237413] 'agreement among raft nodes before linearized reading' (duration: 159.731586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:27.677709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.055243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-06-16T10:20:27.67783Z","caller":"traceutil/trace.go:171","msg":"trace[901562411] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:946; }","duration":"120.21618ms","start":"2026-06-16T10:20:27.557593Z","end":"2026-06-16T10:20:27.677809Z","steps":["trace[901562411] 'agreement among raft nodes before linearized reading' (duration: 119.98788ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:20:46.603125Z","caller":"traceutil/trace.go:171","msg":"trace[100546757] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"271.796354ms","start":"2026-06-16T10:20:46.331311Z","end":"2026-06-16T10:20:46.603107Z","steps":["trace[100546757] 'read index received' (duration: 271.636508ms)","trace[100546757] 'applied index is now lower than readState.Index' (duration: 159.186µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:20:46.603248Z","caller":"traceutil/trace.go:171","msg":"trace[1482583084] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"520.363391ms","start":"2026-06-16T10:20:46.082848Z","end":"2026-06-16T10:20:46.603211Z","steps":["trace[1482583084] 'process raft request' (duration: 520.151833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:46.603505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:20:46.082822Z","time spent":"520.510716ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:20:46.603539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.23299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-16T10:20:46.603576Z","caller":"traceutil/trace.go:171","msg":"trace[286136507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"272.337643ms","start":"2026-06-16T10:20:46.331228Z","end":"2026-06-16T10:20:46.603565Z","steps":["trace[286136507] 'agreement among raft nodes before linearized reading' (duration: 272.004311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:20:46.603778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.264042ms","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-06-16T10:20:46.603875Z","caller":"traceutil/trace.go:171","msg":"trace[169284413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"199.567743ms","start":"2026-06-16T10:20:46.404288Z","end":"2026-06-16T10:20:46.603855Z","steps":["trace[169284413] 'agreement among raft nodes before linearized reading' (duration: 199.008413ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:10.247815Z","caller":"traceutil/trace.go:171","msg":"trace[1722688809] linearizableReadLoop","detail":"{readStateIndex:1521; appliedIndex:1520; }","duration":"190.122059ms","start":"2026-06-16T10:21:10.057671Z","end":"2026-06-16T10:21:10.247793Z","steps":["trace[1722688809] 'read index received' (duration: 188.108322ms)","trace[1722688809] 'applied index is now lower than readState.Index' (duration: 2.012736ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:21:10.24803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.336094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T10:21:10.248075Z","caller":"traceutil/trace.go:171","msg":"trace[1945661235] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1473; }","duration":"190.430907ms","start":"2026-06-16T10:21:10.057632Z","end":"2026-06-16T10:21:10.248063Z","steps":["trace[1945661235] 'agreement among raft nodes before linearized reading' (duration: 190.245432ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.007402Z","caller":"traceutil/trace.go:171","msg":"trace[880743795] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"113.047198ms","start":"2026-06-16T10:21:19.894319Z","end":"2026-06-16T10:21:20.007366Z","steps":["trace[880743795] 'process raft request' (duration: 112.960886ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.007645Z","caller":"traceutil/trace.go:171","msg":"trace[391267987] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"113.026227ms","start":"2026-06-16T10:21:19.894586Z","end":"2026-06-16T10:21:20.007613Z","steps":["trace[391267987] 'process raft request' (duration: 112.74595ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.0077Z","caller":"traceutil/trace.go:171","msg":"trace[2121992311] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"113.866359ms","start":"2026-06-16T10:21:19.893821Z","end":"2026-06-16T10:21:20.007687Z","steps":["trace[2121992311] 'process raft request' (duration: 111.684123ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.007683Z","caller":"traceutil/trace.go:171","msg":"trace[2016806988] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"111.993092ms","start":"2026-06-16T10:21:19.895547Z","end":"2026-06-16T10:21:20.00754Z","steps":["trace[2016806988] 'process raft request' (duration: 111.93372ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.007819Z","caller":"traceutil/trace.go:171","msg":"trace[1613495286] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"113.334865ms","start":"2026-06-16T10:21:19.894475Z","end":"2026-06-16T10:21:20.00781Z","steps":["trace[1613495286] 'process raft request' (duration: 112.836583ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.00785Z","caller":"traceutil/trace.go:171","msg":"trace[1222047863] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"112.459063ms","start":"2026-06-16T10:21:19.895381Z","end":"2026-06-16T10:21:20.00784Z","steps":["trace[1222047863] 'process raft request' (duration: 112.044343ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.00789Z","caller":"traceutil/trace.go:171","msg":"trace[24644675] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"112.434022ms","start":"2026-06-16T10:21:19.895449Z","end":"2026-06-16T10:21:20.007883Z","steps":["trace[24644675] 'process raft request' (duration: 112.006901ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.007708Z","caller":"traceutil/trace.go:171","msg":"trace[31708229] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"112.146865ms","start":"2026-06-16T10:21:19.895548Z","end":"2026-06-16T10:21:20.007695Z","steps":["trace[31708229] 'process raft request' (duration: 111.96007ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:20.007936Z","caller":"traceutil/trace.go:171","msg":"trace[1758527665] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"113.037149ms","start":"2026-06-16T10:21:19.894891Z","end":"2026-06-16T10:21:20.007928Z","steps":["trace[1758527665] 'process raft request' (duration: 112.480514ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:35.266452Z","caller":"traceutil/trace.go:171","msg":"trace[1543609850] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"170.267143ms","start":"2026-06-16T10:21:35.096145Z","end":"2026-06-16T10:21:35.266412Z","steps":["trace[1543609850] 'read index received' (duration: 148.555369ms)","trace[1543609850] 'applied index is now lower than readState.Index' (duration: 21.695064ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:21:35.26668Z","caller":"traceutil/trace.go:171","msg":"trace[1186440914] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"183.852593ms","start":"2026-06-16T10:21:35.082794Z","end":"2026-06-16T10:21:35.266647Z","steps":["trace[1186440914] 'process raft request' (duration: 161.963274ms)","trace[1186440914] 'compare' (duration: 21.299315ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:21:35.266845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.673282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5986242-a011-4bfe-968b-f5a08def8d00\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-16T10:21:35.266962Z","caller":"traceutil/trace.go:171","msg":"trace[419014849] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5986242-a011-4bfe-968b-f5a08def8d00; range_end:; response_count:1; response_revision:1884; }","duration":"170.825506ms","start":"2026-06-16T10:21:35.09611Z","end":"2026-06-16T10:21:35.266935Z","steps":["trace[419014849] 'agreement among raft nodes before linearized reading' (duration: 170.56063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:36.12933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745400496680180,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T10:21:36.285903Z","caller":"traceutil/trace.go:171","msg":"trace[2101878207] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"675.597562ms","start":"2026-06-16T10:21:35.610283Z","end":"2026-06-16T10:21:36.28588Z","steps":["trace[2101878207] 'process raft request' (duration: 675.461459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:36.28798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:35.610256Z","time spent":"675.755646ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:21:36.287619Z","caller":"traceutil/trace.go:171","msg":"trace[1861957782] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"880.273658ms","start":"2026-06-16T10:21:35.407304Z","end":"2026-06-16T10:21:36.287578Z","steps":["trace[1861957782] 'process raft request' (duration: 804.286152ms)","trace[1861957782] 'compare' (duration: 73.861968ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:21:36.288091Z","caller":"traceutil/trace.go:171","msg":"trace[727619462] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1960; }","duration":"659.624868ms","start":"2026-06-16T10:21:35.628317Z","end":"2026-06-16T10:21:36.287942Z","steps":["trace[727619462] 'read index received' (duration: 583.289254ms)","trace[727619462] 'applied index is now lower than readState.Index' (duration: 76.334614ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:21:36.288192Z","caller":"traceutil/trace.go:171","msg":"trace[1222486687] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"569.359466ms","start":"2026-06-16T10:21:35.718823Z","end":"2026-06-16T10:21:36.288183Z","steps":["trace[1222486687] 'process raft request' (duration: 567.026983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:36.288215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:35.407265Z","time spent":"880.868502ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:21:36.288275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:35.71879Z","time spent":"569.433457ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:21:36.288469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.640501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T10:21:36.288505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.590069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b988932bc1bfa2\" ","response":"range_response_count:1 size:982"} {"level":"warn","ts":"2026-06-16T10:21:36.288516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.47691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T10:21:36.288579Z","caller":"traceutil/trace.go:171","msg":"trace[1563362942] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b988932bc1bfa2; range_end:; response_count:1; response_revision:1888; }","duration":"550.70946ms","start":"2026-06-16T10:21:35.737848Z","end":"2026-06-16T10:21:36.288557Z","steps":["trace[1563362942] 'agreement among raft nodes before linearized reading' (duration: 550.514536ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:36.28859Z","caller":"traceutil/trace.go:171","msg":"trace[1704428073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1888; }","duration":"395.595703ms","start":"2026-06-16T10:21:35.892976Z","end":"2026-06-16T10:21:36.288572Z","steps":["trace[1704428073] 'agreement among raft nodes before linearized reading' (duration: 395.392558ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:36.288591Z","caller":"traceutil/trace.go:171","msg":"trace[208221682] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1888; }","duration":"463.761233ms","start":"2026-06-16T10:21:35.824785Z","end":"2026-06-16T10:21:36.288546Z","steps":["trace[208221682] 'agreement among raft nodes before linearized reading' (duration: 463.591129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:36.288482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.163881ms","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":"warn","ts":"2026-06-16T10:21:36.288738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:35.824768Z","time spent":"463.928268ms","remote":"127.0.0.1:52170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-06-16T10:21:36.288742Z","caller":"traceutil/trace.go:171","msg":"trace[2099829323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1888; }","duration":"660.490078ms","start":"2026-06-16T10:21:35.628237Z","end":"2026-06-16T10:21:36.288727Z","steps":["trace[2099829323] 'agreement among raft nodes before linearized reading' (duration: 660.13565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:36.288639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:35.737824Z","time spent":"550.792613ms","remote":"127.0.0.1:59248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1004,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b988932bc1bfa2\" "} {"level":"warn","ts":"2026-06-16T10:21:36.288814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:35.628204Z","time spent":"660.591931ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T10:21:36.289736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:35.892947Z","time spent":"395.676195ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T10:21:37.151068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.78872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:21:37.151186Z","caller":"traceutil/trace.go:171","msg":"trace[1947012071] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"857.301843ms","start":"2026-06-16T10:21:36.293868Z","end":"2026-06-16T10:21:37.15117Z","steps":["trace[1947012071] 'process raft request' (duration: 512.345139ms)","trace[1947012071] 'compare' (duration: 344.491874ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:21:37.151439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:36.29385Z","time spent":"857.359164ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:21:37.15189Z","caller":"traceutil/trace.go:171","msg":"trace[343168163] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"856.853923ms","start":"2026-06-16T10:21:36.295009Z","end":"2026-06-16T10:21:37.151863Z","steps":["trace[343168163] 'process raft request' (duration: 856.129857ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:37.152Z","caller":"traceutil/trace.go:171","msg":"trace[1967153024] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"697.831212ms","start":"2026-06-16T10:21:36.454149Z","end":"2026-06-16T10:21:37.15198Z","steps":["trace[1967153024] 'process raft request' (duration: 697.726179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:37.152031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:36.294982Z","time spent":"856.973186ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:21:37.152145Z","caller":"traceutil/trace.go:171","msg":"trace[1282946972] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"525.749617ms","start":"2026-06-16T10:21:36.626364Z","end":"2026-06-16T10:21:37.152114Z","steps":["trace[1282946972] 'process raft request' (duration: 525.579883ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:37.15202Z","caller":"traceutil/trace.go:171","msg":"trace[600098284] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1964; }","duration":"781.574413ms","start":"2026-06-16T10:21:36.370386Z","end":"2026-06-16T10:21:37.15196Z","steps":["trace[600098284] 'read index received' (duration: 435.840291ms)","trace[600098284] 'applied index is now lower than readState.Index' (duration: 345.731712ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:21:37.152233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.842229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5986242-a011-4bfe-968b-f5a08def8d00\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-06-16T10:21:37.152305Z","caller":"traceutil/trace.go:171","msg":"trace[953664305] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5986242-a011-4bfe-968b-f5a08def8d00; range_end:; response_count:1; response_revision:1893; }","duration":"781.956741ms","start":"2026-06-16T10:21:36.370335Z","end":"2026-06-16T10:21:37.152291Z","steps":["trace[953664305] 'agreement among raft nodes before linearized reading' (duration: 781.741027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:37.152347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:36.370315Z","time spent":"782.023913ms","remote":"127.0.0.1:59334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5986242-a011-4bfe-968b-f5a08def8d00\" "} {"level":"warn","ts":"2026-06-16T10:21:37.15234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:36.626344Z","time spent":"525.884739ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:21:37.152178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:36.454124Z","time spent":"697.983845ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:21:37.177106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.099176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:21:37.177268Z","caller":"traceutil/trace.go:171","msg":"trace[122986991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1894; }","duration":"660.30079ms","start":"2026-06-16T10:21:36.516949Z","end":"2026-06-16T10:21:37.17725Z","steps":["trace[122986991] 'agreement among raft nodes before linearized reading' (duration: 660.039944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:37.177314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.615788ms","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-06-16T10:21:37.177361Z","caller":"traceutil/trace.go:171","msg":"trace[958504917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1894; }","duration":"257.680539ms","start":"2026-06-16T10:21:36.919671Z","end":"2026-06-16T10:21:37.177352Z","steps":["trace[958504917] 'agreement among raft nodes before linearized reading' (duration: 257.482005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:37.177336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:36.516927Z","time spent":"660.396712ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T10:21:37.177101Z","caller":"traceutil/trace.go:171","msg":"trace[2121532340] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"335.136509ms","start":"2026-06-16T10:21:36.841935Z","end":"2026-06-16T10:21:37.177072Z","steps":["trace[2121532340] 'process raft request' (duration: 334.940094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:37.177206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.908611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:21:37.177719Z","caller":"traceutil/trace.go:171","msg":"trace[366132950] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1894; }","duration":"218.476004ms","start":"2026-06-16T10:21:36.959229Z","end":"2026-06-16T10:21:37.177705Z","steps":["trace[366132950] 'agreement among raft nodes before linearized reading' (duration: 217.878531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:37.178229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:36.841916Z","time spent":"336.241853ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:21:49.755344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745400496680413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T10:21:49.877196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.753459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:21:49.877407Z","caller":"traceutil/trace.go:171","msg":"trace[1905761763] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"660.588147ms","start":"2026-06-16T10:21:49.216756Z","end":"2026-06-16T10:21:49.877344Z","steps":["trace[1905761763] 'process raft request' (duration: 546.489791ms)","trace[1905761763] 'compare' (duration: 113.062045ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:21:49.877494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:49.216745Z","time spent":"660.72238ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:21:49.878367Z","caller":"traceutil/trace.go:171","msg":"trace[1626997239] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2076; }","duration":"624.069846ms","start":"2026-06-16T10:21:49.254258Z","end":"2026-06-16T10:21:49.878327Z","steps":["trace[1626997239] 'read index received' (duration: 509.002259ms)","trace[1626997239] 'applied index is now lower than readState.Index' (duration: 115.066117ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:21:49.878388Z","caller":"traceutil/trace.go:171","msg":"trace[1089732527] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"658.938243ms","start":"2026-06-16T10:21:49.219405Z","end":"2026-06-16T10:21:49.878343Z","steps":["trace[1089732527] 'process raft request' (duration: 658.658327ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:49.878825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:49.219383Z","time spent":"659.339831ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:21:49.878935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.073036ms","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-06-16T10:21:49.87901Z","caller":"traceutil/trace.go:171","msg":"trace[1549868642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2001; }","duration":"624.176928ms","start":"2026-06-16T10:21:49.254812Z","end":"2026-06-16T10:21:49.878989Z","steps":["trace[1549868642] 'agreement among raft nodes before linearized reading' (duration: 623.943533ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:21:49.879075Z","caller":"traceutil/trace.go:171","msg":"trace[462178708] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"357.675337ms","start":"2026-06-16T10:21:49.521385Z","end":"2026-06-16T10:21:49.87906Z","steps":["trace[462178708] 'process raft request' (duration: 356.822259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:49.879087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:49.254802Z","time spent":"624.26924ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T10:21:49.879137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:49.521364Z","time spent":"357.738828ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7927,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:21:49.879433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.170358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-16T10:21:49.879454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.5136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:21:49.879489Z","caller":"traceutil/trace.go:171","msg":"trace[1339996773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2001; }","duration":"625.27199ms","start":"2026-06-16T10:21:49.254203Z","end":"2026-06-16T10:21:49.879475Z","steps":["trace[1339996773] 'agreement among raft nodes before linearized reading' (duration: 625.113237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:49.879523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:49.254179Z","time spent":"625.334662ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T10:21:49.879624Z","caller":"traceutil/trace.go:171","msg":"trace[750585732] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2001; }","duration":"177.606213ms","start":"2026-06-16T10:21:49.701886Z","end":"2026-06-16T10:21:49.879492Z","steps":["trace[750585732] 'agreement among raft nodes before linearized reading' (duration: 177.513171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:49.879924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.159309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:21:49.879981Z","caller":"traceutil/trace.go:171","msg":"trace[302847714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2001; }","duration":"362.24525ms","start":"2026-06-16T10:21:49.517721Z","end":"2026-06-16T10:21:49.879966Z","steps":["trace[302847714] 'agreement among raft nodes before linearized reading' (duration: 362.161379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:49.880029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:49.517702Z","time spent":"362.316502ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T10:21:49.880905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.346675ms","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-06-16T10:21:49.880966Z","caller":"traceutil/trace.go:171","msg":"trace[1453025609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2001; }","duration":"525.443838ms","start":"2026-06-16T10:21:49.355507Z","end":"2026-06-16T10:21:49.88095Z","steps":["trace[1453025609] 'agreement among raft nodes before linearized reading' (duration: 525.267924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:49.88101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:49.355486Z","time spent":"525.512309ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T10:21:49.88168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.800048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T10:21:49.881747Z","caller":"traceutil/trace.go:171","msg":"trace[1940288824] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2001; }","duration":"496.908591ms","start":"2026-06-16T10:21:49.384821Z","end":"2026-06-16T10:21:49.881729Z","steps":["trace[1940288824] 'agreement among raft nodes before linearized reading' (duration: 495.331108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:21:49.881783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:21:49.384791Z","time spent":"496.981332ms","remote":"127.0.0.1:53720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-16T10:21:50.408615Z","caller":"traceutil/trace.go:171","msg":"trace[1177898475] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"252.050719ms","start":"2026-06-16T10:21:50.156534Z","end":"2026-06-16T10:21:50.408585Z","steps":["trace[1177898475] 'read index received' (duration: 238.725607ms)","trace[1177898475] 'applied index is now lower than readState.Index' (duration: 13.323302ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:21:50.408767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.213333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:21:50.408846Z","caller":"traceutil/trace.go:171","msg":"trace[1607285792] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2004; }","duration":"252.345436ms","start":"2026-06-16T10:21:50.156483Z","end":"2026-06-16T10:21:50.408828Z","steps":["trace[1607285792] 'agreement among raft nodes before linearized reading' (duration: 252.199373ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:07.403267Z","caller":"traceutil/trace.go:171","msg":"trace[1158388608] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"226.662926ms","start":"2026-06-16T10:22:07.176576Z","end":"2026-06-16T10:22:07.403239Z","steps":["trace[1158388608] 'process raft request' (duration: 226.450862ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:07.917177Z","caller":"traceutil/trace.go:171","msg":"trace[464729907] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"489.647052ms","start":"2026-06-16T10:22:07.427501Z","end":"2026-06-16T10:22:07.917149Z","steps":["trace[464729907] 'read index received' (duration: 489.321246ms)","trace[464729907] 'applied index is now lower than readState.Index' (duration: 324.426µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:22:07.917304Z","caller":"traceutil/trace.go:171","msg":"trace[243208942] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"634.485684ms","start":"2026-06-16T10:22:07.282781Z","end":"2026-06-16T10:22:07.917267Z","steps":["trace[243208942] 'process raft request' (duration: 634.115297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:07.917454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.966095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T10:22:07.917628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:07.282763Z","time spent":"634.769109ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:22:07.917777Z","caller":"traceutil/trace.go:171","msg":"trace[984237752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2115; }","duration":"399.319801ms","start":"2026-06-16T10:22:07.518428Z","end":"2026-06-16T10:22:07.917748Z","steps":["trace[984237752] 'agreement among raft nodes before linearized reading' (duration: 398.945514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:07.918067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:07.51841Z","time spent":"399.550555ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T10:22:07.918444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.913665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-16T10:22:07.918553Z","caller":"traceutil/trace.go:171","msg":"trace[370020509] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2115; }","duration":"491.22539ms","start":"2026-06-16T10:22:07.427291Z","end":"2026-06-16T10:22:07.918517Z","steps":["trace[370020509] 'agreement among raft nodes before linearized reading' (duration: 490.080199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:07.91862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:07.427274Z","time spent":"491.331672ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-16T10:22:08.112482Z","caller":"traceutil/trace.go:171","msg":"trace[311996627] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"103.006383ms","start":"2026-06-16T10:22:08.00945Z","end":"2026-06-16T10:22:08.112456Z","steps":["trace[311996627] 'process raft request' (duration: 102.84766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:08.112667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.13358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T10:22:08.11275Z","caller":"traceutil/trace.go:171","msg":"trace[506098164] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2119; }","duration":"106.269693ms","start":"2026-06-16T10:22:08.00646Z","end":"2026-06-16T10:22:08.112729Z","steps":["trace[506098164] 'agreement among raft nodes before linearized reading' (duration: 105.989487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:08.112697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.947271ms","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-06-16T10:22:08.112975Z","caller":"traceutil/trace.go:171","msg":"trace[1734414821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2119; }","duration":"103.276488ms","start":"2026-06-16T10:22:08.009676Z","end":"2026-06-16T10:22:08.112952Z","steps":["trace[1734414821] 'agreement among raft nodes before linearized reading' (duration: 102.824749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:08.112757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.974876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-16T10:22:08.113084Z","caller":"traceutil/trace.go:171","msg":"trace[2127901676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2119; }","duration":"106.365934ms","start":"2026-06-16T10:22:08.00671Z","end":"2026-06-16T10:22:08.113076Z","steps":["trace[2127901676] 'agreement among raft nodes before linearized reading' (duration: 105.866625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:08.334824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.40509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:22:08.334939Z","caller":"traceutil/trace.go:171","msg":"trace[14298521] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"301.208066ms","start":"2026-06-16T10:22:08.033699Z","end":"2026-06-16T10:22:08.334907Z","steps":["trace[14298521] 'process raft request' (duration: 121.639454ms)","trace[14298521] 'compare' (duration: 179.170955ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:08.335003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:08.03368Z","time spent":"301.295787ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:22:08.35657Z","caller":"traceutil/trace.go:171","msg":"trace[255831354] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"239.210783ms","start":"2026-06-16T10:22:08.117341Z","end":"2026-06-16T10:22:08.356551Z","steps":["trace[255831354] 'process raft request' (duration: 238.943198ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:08.356584Z","caller":"traceutil/trace.go:171","msg":"trace[1100327834] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"238.805745ms","start":"2026-06-16T10:22:08.11776Z","end":"2026-06-16T10:22:08.356565Z","steps":["trace[1100327834] 'process raft request' (duration: 238.694573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:08.776937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.95948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:22:08.777275Z","caller":"traceutil/trace.go:171","msg":"trace[807470751] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2204; }","duration":"171.567275ms","start":"2026-06-16T10:22:08.605642Z","end":"2026-06-16T10:22:08.777209Z","steps":["trace[807470751] 'read index received' (duration: 54.481µs)","trace[807470751] 'applied index is now lower than readState.Index' (duration: 171.511954ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:22:08.777367Z","caller":"traceutil/trace.go:171","msg":"trace[516949641] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"304.037007ms","start":"2026-06-16T10:22:08.473319Z","end":"2026-06-16T10:22:08.777356Z","steps":["trace[516949641] 'process raft request' (duration: 303.755042ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:08.777411Z","caller":"traceutil/trace.go:171","msg":"trace[270148469] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"312.90398ms","start":"2026-06-16T10:22:08.464479Z","end":"2026-06-16T10:22:08.777382Z","steps":["trace[270148469] 'process raft request' (duration: 22.40252ms)","trace[270148469] 'compare' (duration: 289.757676ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:08.77744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:08.473293Z","time spent":"304.100359ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:22:08.777552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.904242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b98899ed205da5\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-16T10:22:08.777612Z","caller":"traceutil/trace.go:171","msg":"trace[436926330] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b98899ed205da5; range_end:; response_count:1; response_revision:2125; }","duration":"171.977194ms","start":"2026-06-16T10:22:08.60562Z","end":"2026-06-16T10:22:08.777597Z","steps":["trace[436926330] 'agreement among raft nodes before linearized reading' (duration: 171.78663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:08.777727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:08.464456Z","time spent":"313.012531ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:22:08.7778Z","caller":"traceutil/trace.go:171","msg":"trace[1810444604] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"300.827549ms","start":"2026-06-16T10:22:08.476961Z","end":"2026-06-16T10:22:08.777789Z","steps":["trace[1810444604] 'process raft request' (duration: 300.206978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:08.77787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:08.476951Z","time spent":"300.884ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:22:08.904153Z","caller":"traceutil/trace.go:171","msg":"trace[1110336371] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2207; }","duration":"114.709046ms","start":"2026-06-16T10:22:08.78942Z","end":"2026-06-16T10:22:08.904129Z","steps":["trace[1110336371] 'read index received' (duration: 93.630351ms)","trace[1110336371] 'applied index is now lower than readState.Index' (duration: 21.077435ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:08.904272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.83341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:22:08.904308Z","caller":"traceutil/trace.go:171","msg":"trace[1217964351] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2126; }","duration":"114.914611ms","start":"2026-06-16T10:22:08.789384Z","end":"2026-06-16T10:22:08.904299Z","steps":["trace[1217964351] 'agreement among raft nodes before linearized reading' (duration: 114.835299ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:08.904595Z","caller":"traceutil/trace.go:171","msg":"trace[951896669] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"124.449475ms","start":"2026-06-16T10:22:08.78011Z","end":"2026-06-16T10:22:08.90456Z","steps":["trace[951896669] 'process raft request' (duration: 103.055134ms)","trace[951896669] 'compare' (duration: 20.478524ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:09.236677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.588661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T10:22:09.236743Z","caller":"traceutil/trace.go:171","msg":"trace[1977784171] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2126; }","duration":"115.705113ms","start":"2026-06-16T10:22:09.121022Z","end":"2026-06-16T10:22:09.236727Z","steps":["trace[1977784171] 'range keys from in-memory index tree' (duration: 115.425958ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:10.061747Z","caller":"traceutil/trace.go:171","msg":"trace[321676766] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"136.370453ms","start":"2026-06-16T10:22:09.925275Z","end":"2026-06-16T10:22:10.061646Z","steps":["trace[321676766] 'process raft request' (duration: 36.926331ms)","trace[321676766] 'compare' (duration: 99.281989ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:10.211602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.560854ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-16T10:22:10.212081Z","caller":"traceutil/trace.go:171","msg":"trace[80316714] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"146.290294ms","start":"2026-06-16T10:22:10.065765Z","end":"2026-06-16T10:22:10.212056Z","steps":["trace[80316714] 'process raft request' (duration: 145.947838ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:10.28141Z","caller":"traceutil/trace.go:171","msg":"trace[167321290] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"215.22594ms","start":"2026-06-16T10:22:10.066149Z","end":"2026-06-16T10:22:10.281375Z","steps":["trace[167321290] 'process raft request' (duration: 145.881827ms)","trace[167321290] 'compare' (duration: 69.125639ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:10.860785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745400496680717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T10:22:11.217171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.515686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:22:11.217273Z","caller":"traceutil/trace.go:171","msg":"trace[1428882251] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"874.007467ms","start":"2026-06-16T10:22:10.343249Z","end":"2026-06-16T10:22:11.217256Z","steps":["trace[1428882251] 'process raft request' (duration: 748.347048ms)","trace[1428882251] 'compare' (duration: 125.369003ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:11.217315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.343232Z","time spent":"874.063177ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:22:11.218137Z","caller":"traceutil/trace.go:171","msg":"trace[1080538826] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"435.354388ms","start":"2026-06-16T10:22:10.782767Z","end":"2026-06-16T10:22:11.218122Z","steps":["trace[1080538826] 'process raft request' (duration: 435.253686ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:11.218122Z","caller":"traceutil/trace.go:171","msg":"trace[236791683] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"582.774873ms","start":"2026-06-16T10:22:10.635316Z","end":"2026-06-16T10:22:11.218091Z","steps":["trace[236791683] 'process raft request' (duration: 582.64185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.21824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.782736Z","time spent":"435.45761ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:22:11.218414Z","caller":"traceutil/trace.go:171","msg":"trace[1257149459] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2213; }","duration":"857.930815ms","start":"2026-06-16T10:22:10.36047Z","end":"2026-06-16T10:22:11.218401Z","steps":["trace[1257149459] 'read index received' (duration: 731.144106ms)","trace[1257149459] 'applied index is now lower than readState.Index' (duration: 126.761689ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:22:11.218493Z","caller":"traceutil/trace.go:171","msg":"trace[1701244793] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"434.618835ms","start":"2026-06-16T10:22:10.783861Z","end":"2026-06-16T10:22:11.21848Z","steps":["trace[1701244793] 'process raft request' (duration: 434.191707ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:11.218545Z","caller":"traceutil/trace.go:171","msg":"trace[174646947] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"433.733489ms","start":"2026-06-16T10:22:10.784798Z","end":"2026-06-16T10:22:11.218532Z","steps":["trace[174646947] 'process raft request' (duration: 433.525335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.218578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.783847Z","time spent":"434.689836ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:22:11.218636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.784766Z","time spent":"433.834531ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:22:11.21887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.303082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-16T10:22:11.219012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.878437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:22:11.219027Z","caller":"traceutil/trace.go:171","msg":"trace[2136216442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2135; }","duration":"858.463615ms","start":"2026-06-16T10:22:10.360507Z","end":"2026-06-16T10:22:11.218971Z","steps":["trace[2136216442] 'agreement among raft nodes before linearized reading' (duration: 858.211081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.218841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.635298Z","time spent":"582.912315ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:22:11.219203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.360488Z","time spent":"858.693389ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T10:22:11.219051Z","caller":"traceutil/trace.go:171","msg":"trace[1271882938] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2135; }","duration":"622.926678ms","start":"2026-06-16T10:22:10.596114Z","end":"2026-06-16T10:22:11.21904Z","steps":["trace[1271882938] 'agreement among raft nodes before linearized reading' (duration: 622.853156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.219346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.596039Z","time spent":"623.290144ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:22:11.219357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.319526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:22:11.219433Z","caller":"traceutil/trace.go:171","msg":"trace[1967087593] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2135; }","duration":"856.446588ms","start":"2026-06-16T10:22:10.362965Z","end":"2026-06-16T10:22:11.219411Z","steps":["trace[1967087593] 'agreement among raft nodes before linearized reading' (duration: 856.267755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.219489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.362943Z","time spent":"856.534571ms","remote":"127.0.0.1:52160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:22:11.21916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.688609ms","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-06-16T10:22:11.219684Z","caller":"traceutil/trace.go:171","msg":"trace[1418128564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2135; }","duration":"859.237569ms","start":"2026-06-16T10:22:10.360433Z","end":"2026-06-16T10:22:11.219671Z","steps":["trace[1418128564] 'agreement among raft nodes before linearized reading' (duration: 858.641928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.219772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:10.360422Z","time spent":"859.331821ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T10:22:11.868269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.320651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:22:11.868485Z","caller":"traceutil/trace.go:171","msg":"trace[1305405817] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"578.755094ms","start":"2026-06-16T10:22:11.289708Z","end":"2026-06-16T10:22:11.868463Z","steps":["trace[1305405817] 'read index received' (duration: 161.134167ms)","trace[1305405817] 'applied index is now lower than readState.Index' (duration: 417.619667ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:11.868843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.131019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:22:11.868894Z","caller":"traceutil/trace.go:171","msg":"trace[547821401] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2137; }","duration":"382.18965ms","start":"2026-06-16T10:22:11.486689Z","end":"2026-06-16T10:22:11.868879Z","steps":["trace[547821401] 'agreement among raft nodes before linearized reading' (duration: 382.103909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.868913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.205422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-16T10:22:11.868941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:11.486635Z","time spent":"382.293062ms","remote":"127.0.0.1:52158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-06-16T10:22:11.86906Z","caller":"traceutil/trace.go:171","msg":"trace[212321045] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2137; }","duration":"579.471277ms","start":"2026-06-16T10:22:11.289488Z","end":"2026-06-16T10:22:11.868959Z","steps":["trace[212321045] 'agreement among raft nodes before linearized reading' (duration: 579.265373ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:11.86913Z","caller":"traceutil/trace.go:171","msg":"trace[1059398253] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"643.730591ms","start":"2026-06-16T10:22:11.225373Z","end":"2026-06-16T10:22:11.869104Z","steps":["trace[1059398253] 'process raft request' (duration: 225.474473ms)","trace[1059398253] 'compare' (duration: 416.932345ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:22:11.869283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.627091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:22:11.869339Z","caller":"traceutil/trace.go:171","msg":"trace[941596934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2137; }","duration":"351.807954ms","start":"2026-06-16T10:22:11.517517Z","end":"2026-06-16T10:22:11.869325Z","steps":["trace[941596934] 'agreement among raft nodes before linearized reading' (duration: 351.632361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.869359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:11.225352Z","time spent":"643.950146ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:22:11.869388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:11.517502Z","time spent":"351.874095ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T10:22:11.869166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:11.289469Z","time spent":"579.67776ms","remote":"127.0.0.1:53720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-16T10:22:11.869405Z","caller":"traceutil/trace.go:171","msg":"trace[1303343102] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"643.179882ms","start":"2026-06-16T10:22:11.226181Z","end":"2026-06-16T10:22:11.869361Z","steps":["trace[1303343102] 'process raft request' (duration: 642.206115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:22:11.869617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:22:11.226133Z","time spent":"643.374396ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:22:31.67129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.914405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:22:31.671387Z","caller":"traceutil/trace.go:171","msg":"trace[452091851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2240; }","duration":"157.076678ms","start":"2026-06-16T10:22:31.514287Z","end":"2026-06-16T10:22:31.671364Z","steps":["trace[452091851] 'range keys from in-memory index tree' (duration: 156.836884ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:22:33.718621Z","caller":"traceutil/trace.go:171","msg":"trace[1070000496] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"114.228684ms","start":"2026-06-16T10:22:33.604343Z","end":"2026-06-16T10:22:33.718572Z","steps":["trace[1070000496] 'process raft request' (duration: 114.056041ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:23:53.19358Z","caller":"traceutil/trace.go:171","msg":"trace[1304039615] linearizableReadLoop","detail":"{readStateIndex:2877; appliedIndex:2876; }","duration":"495.490223ms","start":"2026-06-16T10:23:52.69807Z","end":"2026-06-16T10:23:53.193561Z","steps":["trace[1304039615] 'read index received' (duration: 495.327111ms)","trace[1304039615] 'applied index is now lower than readState.Index' (duration: 162.132µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:23:53.193665Z","caller":"traceutil/trace.go:171","msg":"trace[1418821349] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"539.11734ms","start":"2026-06-16T10:23:52.654514Z","end":"2026-06-16T10:23:53.193631Z","steps":["trace[1418821349] 'process raft request' (duration: 538.909197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:23:53.193834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.316869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-16T10:23:53.193844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:23:52.654491Z","time spent":"539.261391ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8749,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:23:53.193884Z","caller":"traceutil/trace.go:171","msg":"trace[1410965863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2771; }","duration":"491.40074ms","start":"2026-06-16T10:23:52.702473Z","end":"2026-06-16T10:23:53.193874Z","steps":["trace[1410965863] 'agreement among raft nodes before linearized reading' (duration: 491.226988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:23:53.193912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:23:52.702463Z","time spent":"491.44256ms","remote":"127.0.0.1:59426","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-06-16T10:23:53.193834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.598194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-06-16T10:23:53.193934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.55355ms","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-06-16T10:23:53.194004Z","caller":"traceutil/trace.go:171","msg":"trace[918882560] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2771; }","duration":"311.779406ms","start":"2026-06-16T10:23:52.88221Z","end":"2026-06-16T10:23:53.19399Z","steps":["trace[918882560] 'agreement among raft nodes before linearized reading' (duration: 311.492483ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:23:53.194023Z","caller":"traceutil/trace.go:171","msg":"trace[1977797612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2771; }","duration":"407.684193ms","start":"2026-06-16T10:23:52.786318Z","end":"2026-06-16T10:23:53.194002Z","steps":["trace[1977797612] 'agreement among raft nodes before linearized reading' (duration: 407.449619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:23:53.194053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:23:52.882143Z","time spent":"311.892988ms","remote":"127.0.0.1:59334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-16T10:23:53.194075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:23:52.786302Z","time spent":"407.762483ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T10:23:53.194352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.279213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-16T10:23:53.194389Z","caller":"traceutil/trace.go:171","msg":"trace[1634818074] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2771; }","duration":"496.352273ms","start":"2026-06-16T10:23:52.698028Z","end":"2026-06-16T10:23:53.194381Z","steps":["trace[1634818074] 'agreement among raft nodes before linearized reading' (duration: 496.266622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:23:53.194409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:23:52.698011Z","time spent":"496.392605ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-16T10:23:53.194577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.166113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-16T10:23:53.195597Z","caller":"traceutil/trace.go:171","msg":"trace[268240234] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2771; }","duration":"193.219226ms","start":"2026-06-16T10:23:53.002363Z","end":"2026-06-16T10:23:53.195582Z","steps":["trace[268240234] 'agreement among raft nodes before linearized reading' (duration: 191.471923ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:24:26.344313Z","caller":"traceutil/trace.go:171","msg":"trace[1259939340] linearizableReadLoop","detail":"{readStateIndex:3072; appliedIndex:3071; }","duration":"151.487507ms","start":"2026-06-16T10:24:26.192798Z","end":"2026-06-16T10:24:26.344285Z","steps":["trace[1259939340] 'read index received' (duration: 151.224924ms)","trace[1259939340] 'applied index is now lower than readState.Index' (duration: 261.073µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:24:26.344421Z","caller":"traceutil/trace.go:171","msg":"trace[1520606855] transaction","detail":"{read_only:false; response_revision:2959; number_of_response:1; }","duration":"272.292887ms","start":"2026-06-16T10:24:26.072101Z","end":"2026-06-16T10:24:26.344394Z","steps":["trace[1520606855] 'process raft request' (duration: 272.010713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:24:26.344851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.974991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:24:26.344913Z","caller":"traceutil/trace.go:171","msg":"trace[1845441537] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2959; }","duration":"152.133409ms","start":"2026-06-16T10:24:26.192762Z","end":"2026-06-16T10:24:26.344896Z","steps":["trace[1845441537] 'agreement among raft nodes before linearized reading' (duration: 151.73556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:24:26.567351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.673559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:24:26.567438Z","caller":"traceutil/trace.go:171","msg":"trace[2071046396] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2959; }","duration":"163.812506ms","start":"2026-06-16T10:24:26.403608Z","end":"2026-06-16T10:24:26.567421Z","steps":["trace[2071046396] 'count revisions from in-memory index tree' (duration: 163.531052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:24:27.039014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.311619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-16T10:24:27.039186Z","caller":"traceutil/trace.go:171","msg":"trace[482012676] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2962; }","duration":"341.53173ms","start":"2026-06-16T10:24:26.697635Z","end":"2026-06-16T10:24:27.039166Z","steps":["trace[482012676] 'range keys from in-memory index tree' (duration: 341.120219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:24:27.039261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:24:26.697617Z","time spent":"341.630694ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-06-16T10:24:27.77011Z","caller":"traceutil/trace.go:171","msg":"trace[604892313] transaction","detail":"{read_only:false; response_revision:2963; number_of_response:1; }","duration":"360.916213ms","start":"2026-06-16T10:24:27.409168Z","end":"2026-06-16T10:24:27.770084Z","steps":["trace[604892313] 'process raft request' (duration: 360.757776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:24:27.770415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:24:27.409147Z","time spent":"361.04943ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:24:27.804663Z","caller":"traceutil/trace.go:171","msg":"trace[519553858] transaction","detail":"{read_only:false; response_revision:2964; number_of_response:1; }","duration":"391.992421ms","start":"2026-06-16T10:24:27.412628Z","end":"2026-06-16T10:24:27.804621Z","steps":["trace[519553858] 'process raft request' (duration: 391.838824ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:24:27.804881Z","caller":"traceutil/trace.go:171","msg":"trace[828051997] linearizableReadLoop","detail":"{readStateIndex:3077; appliedIndex:3076; }","duration":"286.469743ms","start":"2026-06-16T10:24:27.518389Z","end":"2026-06-16T10:24:27.804859Z","steps":["trace[828051997] 'read index received' (duration: 252.050841ms)","trace[828051997] 'applied index is now lower than readState.Index' (duration: 34.417952ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:24:27.804971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.565538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:24:27.805452Z","caller":"traceutil/trace.go:171","msg":"trace[112701437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2965; }","duration":"287.086674ms","start":"2026-06-16T10:24:27.518355Z","end":"2026-06-16T10:24:27.805442Z","steps":["trace[112701437] 'agreement among raft nodes before linearized reading' (duration: 286.572328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:24:27.805465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:24:27.412607Z","time spent":"392.756969ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:14.768427Z","caller":"traceutil/trace.go:171","msg":"trace[472264850] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"108.324821ms","start":"2026-06-16T10:28:14.660072Z","end":"2026-06-16T10:28:14.768397Z","steps":["trace[472264850] 'process raft request' (duration: 77.13133ms)","trace[472264850] 'compare' (duration: 30.992667ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:14.768591Z","caller":"traceutil/trace.go:171","msg":"trace[1060570968] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"108.426554ms","start":"2026-06-16T10:28:14.660135Z","end":"2026-06-16T10:28:14.768561Z","steps":["trace[1060570968] 'process raft request' (duration: 108.191339ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.768794Z","caller":"traceutil/trace.go:171","msg":"trace[1913249527] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"108.562696ms","start":"2026-06-16T10:28:14.660213Z","end":"2026-06-16T10:28:14.768776Z","steps":["trace[1913249527] 'process raft request' (duration: 108.288531ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.769307Z","caller":"traceutil/trace.go:171","msg":"trace[285373682] transaction","detail":"{read_only:false; response_revision:4481; number_of_response:1; }","duration":"107.35009ms","start":"2026-06-16T10:28:14.661934Z","end":"2026-06-16T10:28:14.769284Z","steps":["trace[285373682] 'process raft request' (duration: 106.762628ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.769452Z","caller":"traceutil/trace.go:171","msg":"trace[1426681929] transaction","detail":"{read_only:false; response_revision:4482; number_of_response:1; }","duration":"106.362579ms","start":"2026-06-16T10:28:14.66305Z","end":"2026-06-16T10:28:14.769413Z","steps":["trace[1426681929] 'process raft request' (duration: 106.103744ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.769678Z","caller":"traceutil/trace.go:171","msg":"trace[39009270] transaction","detail":"{read_only:false; response_revision:4483; number_of_response:1; }","duration":"104.039229ms","start":"2026-06-16T10:28:14.665612Z","end":"2026-06-16T10:28:14.769651Z","steps":["trace[39009270] 'process raft request' (duration: 103.671911ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.769737Z","caller":"traceutil/trace.go:171","msg":"trace[700265901] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"103.988409ms","start":"2026-06-16T10:28:14.665736Z","end":"2026-06-16T10:28:14.769724Z","steps":["trace[700265901] 'process raft request' (duration: 103.613641ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.770567Z","caller":"traceutil/trace.go:171","msg":"trace[1682059464] transaction","detail":"{read_only:false; response_revision:4485; number_of_response:1; }","duration":"104.705634ms","start":"2026-06-16T10:28:14.665848Z","end":"2026-06-16T10:28:14.770553Z","steps":["trace[1682059464] 'process raft request' (duration: 103.683832ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.770935Z","caller":"traceutil/trace.go:171","msg":"trace[1084756651] transaction","detail":"{read_only:false; response_revision:4486; number_of_response:1; }","duration":"104.850507ms","start":"2026-06-16T10:28:14.666072Z","end":"2026-06-16T10:28:14.770923Z","steps":["trace[1084756651] 'process raft request' (duration: 103.648761ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.775245Z","caller":"traceutil/trace.go:171","msg":"trace[124200251] transaction","detail":"{read_only:false; response_revision:4491; number_of_response:1; }","duration":"106.980703ms","start":"2026-06-16T10:28:14.668216Z","end":"2026-06-16T10:28:14.775197Z","steps":["trace[124200251] 'process raft request' (duration: 106.673516ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.775425Z","caller":"traceutil/trace.go:171","msg":"trace[1204596756] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"106.256557ms","start":"2026-06-16T10:28:14.669137Z","end":"2026-06-16T10:28:14.775393Z","steps":["trace[1204596756] 'process raft request' (duration: 105.861188ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.775664Z","caller":"traceutil/trace.go:171","msg":"trace[2064061670] transaction","detail":"{read_only:false; response_revision:4492; number_of_response:1; }","duration":"107.431172ms","start":"2026-06-16T10:28:14.668215Z","end":"2026-06-16T10:28:14.775646Z","steps":["trace[2064061670] 'process raft request' (duration: 106.708087ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.775842Z","caller":"traceutil/trace.go:171","msg":"trace[1549420875] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"107.588115ms","start":"2026-06-16T10:28:14.66824Z","end":"2026-06-16T10:28:14.775828Z","steps":["trace[1549420875] 'process raft request' (duration: 106.709816ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.776013Z","caller":"traceutil/trace.go:171","msg":"trace[1021029197] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"107.749299ms","start":"2026-06-16T10:28:14.668249Z","end":"2026-06-16T10:28:14.775998Z","steps":["trace[1021029197] 'process raft request' (duration: 106.723237ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.776184Z","caller":"traceutil/trace.go:171","msg":"trace[1504021098] transaction","detail":"{read_only:false; response_revision:4487; number_of_response:1; }","duration":"108.055546ms","start":"2026-06-16T10:28:14.668113Z","end":"2026-06-16T10:28:14.776169Z","steps":["trace[1504021098] 'process raft request' (duration: 101.801651ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.776192Z","caller":"traceutil/trace.go:171","msg":"trace[1101723808] transaction","detail":"{read_only:false; response_revision:4489; number_of_response:1; }","duration":"107.991725ms","start":"2026-06-16T10:28:14.668181Z","end":"2026-06-16T10:28:14.776172Z","steps":["trace[1101723808] 'process raft request' (duration: 106.644066ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.776299Z","caller":"traceutil/trace.go:171","msg":"trace[1666315660] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"108.175158ms","start":"2026-06-16T10:28:14.668113Z","end":"2026-06-16T10:28:14.776289Z","steps":["trace[1666315660] 'process raft request' (duration: 106.642075ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.776406Z","caller":"traceutil/trace.go:171","msg":"trace[963230281] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"107.162376ms","start":"2026-06-16T10:28:14.669215Z","end":"2026-06-16T10:28:14.776377Z","steps":["trace[963230281] 'process raft request' (duration: 105.813817ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.776429Z","caller":"traceutil/trace.go:171","msg":"trace[1869867390] transaction","detail":"{read_only:false; response_revision:4497; number_of_response:1; }","duration":"105.375498ms","start":"2026-06-16T10:28:14.671045Z","end":"2026-06-16T10:28:14.77642Z","steps":["trace[1869867390] 'process raft request' (duration: 104.010509ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.776482Z","caller":"traceutil/trace.go:171","msg":"trace[1846728357] transaction","detail":"{read_only:false; response_revision:4498; number_of_response:1; }","duration":"104.811136ms","start":"2026-06-16T10:28:14.671655Z","end":"2026-06-16T10:28:14.776466Z","steps":["trace[1846728357] 'process raft request' (duration: 103.435526ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.777797Z","caller":"traceutil/trace.go:171","msg":"trace[697493899] transaction","detail":"{read_only:false; response_revision:4499; number_of_response:1; }","duration":"106.073153ms","start":"2026-06-16T10:28:14.67169Z","end":"2026-06-16T10:28:14.777763Z","steps":["trace[697493899] 'process raft request' (duration: 103.437226ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.778011Z","caller":"traceutil/trace.go:171","msg":"trace[1322745752] transaction","detail":"{read_only:false; response_revision:4490; number_of_response:1; }","duration":"108.019705ms","start":"2026-06-16T10:28:14.668199Z","end":"2026-06-16T10:28:14.776219Z","steps":["trace[1322745752] 'process raft request' (duration: 106.659565ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.779653Z","caller":"traceutil/trace.go:171","msg":"trace[1090615256] transaction","detail":"{read_only:false; response_revision:4501; number_of_response:1; }","duration":"106.981182ms","start":"2026-06-16T10:28:14.672647Z","end":"2026-06-16T10:28:14.779628Z","steps":["trace[1090615256] 'process raft request' (duration: 106.540283ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.779772Z","caller":"traceutil/trace.go:171","msg":"trace[1526214841] transaction","detail":"{read_only:false; response_revision:4500; number_of_response:1; }","duration":"108.047785ms","start":"2026-06-16T10:28:14.671718Z","end":"2026-06-16T10:28:14.779765Z","steps":["trace[1526214841] 'process raft request' (duration: 107.297329ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.779838Z","caller":"traceutil/trace.go:171","msg":"trace[1236178308] transaction","detail":"{read_only:false; response_revision:4502; number_of_response:1; }","duration":"104.028499ms","start":"2026-06-16T10:28:14.675798Z","end":"2026-06-16T10:28:14.779827Z","steps":["trace[1236178308] 'process raft request' (duration: 103.463337ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.779864Z","caller":"traceutil/trace.go:171","msg":"trace[594860362] transaction","detail":"{read_only:false; response_revision:4503; number_of_response:1; }","duration":"101.856262ms","start":"2026-06-16T10:28:14.678002Z","end":"2026-06-16T10:28:14.779858Z","steps":["trace[594860362] 'process raft request' (duration: 101.459334ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:14.779979Z","caller":"traceutil/trace.go:171","msg":"trace[1832446222] linearizableReadLoop","detail":"{readStateIndex:4662; appliedIndex:4651; }","duration":"113.978893ms","start":"2026-06-16T10:28:14.665986Z","end":"2026-06-16T10:28:14.779965Z","steps":["trace[1832446222] 'read index received' (duration: 33.619693ms)","trace[1832446222] 'applied index is now lower than readState.Index' (duration: 80.35627ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:14.780103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.098846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-operator\" ","response":"range_response_count:1 size:5072"} {"level":"info","ts":"2026-06-16T10:28:14.782539Z","caller":"traceutil/trace.go:171","msg":"trace[1838157558] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-operator; range_end:; response_count:1; response_revision:4507; }","duration":"116.553229ms","start":"2026-06-16T10:28:14.665964Z","end":"2026-06-16T10:28:14.782518Z","steps":["trace[1838157558] 'agreement among raft nodes before linearized reading' (duration: 114.037395ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:18.230709Z","caller":"traceutil/trace.go:171","msg":"trace[615491711] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"132.109182ms","start":"2026-06-16T10:28:18.098572Z","end":"2026-06-16T10:28:18.230681Z","steps":["trace[615491711] 'process raft request' (duration: 119.325449ms)","trace[615491711] 'compare' (duration: 12.334434ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:22.348261Z","caller":"traceutil/trace.go:171","msg":"trace[842058139] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"100.095891ms","start":"2026-06-16T10:28:22.248131Z","end":"2026-06-16T10:28:22.348227Z","steps":["trace[842058139] 'process raft request' (duration: 98.61818ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:22.938641Z","caller":"traceutil/trace.go:171","msg":"trace[649928083] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"148.982248ms","start":"2026-06-16T10:28:22.789611Z","end":"2026-06-16T10:28:22.938593Z","steps":["trace[649928083] 'process raft request' (duration: 147.707691ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:25.355177Z","caller":"traceutil/trace.go:171","msg":"trace[1641798661] linearizableReadLoop","detail":"{readStateIndex:5073; appliedIndex:5072; }","duration":"128.604539ms","start":"2026-06-16T10:28:25.226546Z","end":"2026-06-16T10:28:25.355151Z","steps":["trace[1641798661] 'read index received' (duration: 128.456986ms)","trace[1641798661] 'applied index is now lower than readState.Index' (duration: 146.493µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:25.355349Z","caller":"traceutil/trace.go:171","msg":"trace[1189738843] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"168.774416ms","start":"2026-06-16T10:28:25.186531Z","end":"2026-06-16T10:28:25.355305Z","steps":["trace[1189738843] 'process raft request' (duration: 168.46693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:25.355385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.827645ms","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-06-16T10:28:25.35556Z","caller":"traceutil/trace.go:171","msg":"trace[1881019356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4884; }","duration":"129.05349ms","start":"2026-06-16T10:28:25.226492Z","end":"2026-06-16T10:28:25.355545Z","steps":["trace[1881019356] 'agreement among raft nodes before linearized reading' (duration: 128.762623ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:33.04887Z","caller":"traceutil/trace.go:171","msg":"trace[5083243] linearizableReadLoop","detail":"{readStateIndex:5212; appliedIndex:5211; }","duration":"148.947226ms","start":"2026-06-16T10:28:32.899901Z","end":"2026-06-16T10:28:33.048849Z","steps":["trace[5083243] 'read index received' (duration: 148.823243ms)","trace[5083243] 'applied index is now lower than readState.Index' (duration: 122.973µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:33.048908Z","caller":"traceutil/trace.go:171","msg":"trace[2061571738] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"199.343334ms","start":"2026-06-16T10:28:32.849536Z","end":"2026-06-16T10:28:33.048879Z","steps":["trace[2061571738] 'process raft request' (duration: 199.13584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:33.049197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.258982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e189764b-31eb-4d9a-807d-a24f8f23e930\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-16T10:28:33.049302Z","caller":"traceutil/trace.go:171","msg":"trace[1499325572] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e189764b-31eb-4d9a-807d-a24f8f23e930; range_end:; response_count:1; response_revision:5021; }","duration":"149.428676ms","start":"2026-06-16T10:28:32.899858Z","end":"2026-06-16T10:28:33.049287Z","steps":["trace[1499325572] 'agreement among raft nodes before linearized reading' (duration: 149.084789ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:33.16184Z","caller":"traceutil/trace.go:171","msg":"trace[1006823901] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"113.916035ms","start":"2026-06-16T10:28:33.047889Z","end":"2026-06-16T10:28:33.161805Z","steps":["trace[1006823901] 'process raft request' (duration: 113.785242ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:33.161865Z","caller":"traceutil/trace.go:171","msg":"trace[1148711753] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"130.460798ms","start":"2026-06-16T10:28:33.031374Z","end":"2026-06-16T10:28:33.161835Z","steps":["trace[1148711753] 'process raft request' (duration: 112.305372ms)","trace[1148711753] 'compare' (duration: 17.881101ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:33.161852Z","caller":"traceutil/trace.go:171","msg":"trace[1931668842] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"108.469482ms","start":"2026-06-16T10:28:33.053353Z","end":"2026-06-16T10:28:33.161823Z","steps":["trace[1931668842] 'process raft request' (duration: 108.38365ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:33.529735Z","caller":"traceutil/trace.go:171","msg":"trace[641110923] linearizableReadLoop","detail":"{readStateIndex:5228; appliedIndex:5224; }","duration":"142.377756ms","start":"2026-06-16T10:28:33.387336Z","end":"2026-06-16T10:28:33.529713Z","steps":["trace[641110923] 'read index received' (duration: 66.627764ms)","trace[641110923] 'applied index is now lower than readState.Index' (duration: 75.749182ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:33.530002Z","caller":"traceutil/trace.go:171","msg":"trace[162279867] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"149.397031ms","start":"2026-06-16T10:28:33.380562Z","end":"2026-06-16T10:28:33.529959Z","steps":["trace[162279867] 'process raft request' (duration: 149.026853ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:33.530079Z","caller":"traceutil/trace.go:171","msg":"trace[390188444] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"149.405681ms","start":"2026-06-16T10:28:33.380655Z","end":"2026-06-16T10:28:33.53006Z","steps":["trace[390188444] 'process raft request' (duration: 148.984523ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:33.530173Z","caller":"traceutil/trace.go:171","msg":"trace[861633371] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"148.410941ms","start":"2026-06-16T10:28:33.381734Z","end":"2026-06-16T10:28:33.530145Z","steps":["trace[861633371] 'process raft request' (duration: 147.937861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:33.530242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.889037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-16T10:28:33.530282Z","caller":"traceutil/trace.go:171","msg":"trace[1578080164] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5037; }","duration":"142.966788ms","start":"2026-06-16T10:28:33.387307Z","end":"2026-06-16T10:28:33.530273Z","steps":["trace[1578080164] 'agreement among raft nodes before linearized reading' (duration: 142.780044ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:33.530035Z","caller":"traceutil/trace.go:171","msg":"trace[224885551] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"149.429142ms","start":"2026-06-16T10:28:33.38054Z","end":"2026-06-16T10:28:33.529969Z","steps":["trace[224885551] 'process raft request' (duration: 73.477896ms)","trace[224885551] 'compare' (duration: 75.447456ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:33.546253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.708293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-16T10:28:33.546298Z","caller":"traceutil/trace.go:171","msg":"trace[2076377594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5038; }","duration":"129.801884ms","start":"2026-06-16T10:28:33.416486Z","end":"2026-06-16T10:28:33.546287Z","steps":["trace[2076377594] 'agreement among raft nodes before linearized reading' (duration: 129.674572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:33.95582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.058153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:28:33.955992Z","caller":"traceutil/trace.go:171","msg":"trace[294258205] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5229; }","duration":"399.847561ms","start":"2026-06-16T10:28:33.556127Z","end":"2026-06-16T10:28:33.955974Z","steps":["trace[294258205] 'read index received' (duration: 291.484342ms)","trace[294258205] 'applied index is now lower than readState.Index' (duration: 108.361829ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:33.956087Z","caller":"traceutil/trace.go:171","msg":"trace[864926945] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"406.488118ms","start":"2026-06-16T10:28:33.549586Z","end":"2026-06-16T10:28:33.956074Z","steps":["trace[864926945] 'process raft request' (duration: 298.085648ms)","trace[864926945] 'compare' (duration: 107.833148ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:33.95615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:33.549575Z","time spent":"406.54466ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:28:33.956505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.831624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-16T10:28:33.95658Z","caller":"traceutil/trace.go:171","msg":"trace[869356266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5039; }","duration":"298.946016ms","start":"2026-06-16T10:28:33.657614Z","end":"2026-06-16T10:28:33.95656Z","steps":["trace[869356266] 'agreement among raft nodes before linearized reading' (duration: 298.737392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:33.957407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.26451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-16T10:28:33.957495Z","caller":"traceutil/trace.go:171","msg":"trace[1769840203] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5039; }","duration":"401.387202ms","start":"2026-06-16T10:28:33.556089Z","end":"2026-06-16T10:28:33.957476Z","steps":["trace[1769840203] 'agreement among raft nodes before linearized reading' (duration: 400.403312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:33.957549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:33.556073Z","time spent":"401.460224ms","remote":"127.0.0.1:53720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5673,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-16T10:28:34.170683Z","caller":"traceutil/trace.go:171","msg":"trace[1597928381] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"209.745417ms","start":"2026-06-16T10:28:33.960912Z","end":"2026-06-16T10:28:34.170657Z","steps":["trace[1597928381] 'process raft request' (duration: 202.888075ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:34.193379Z","caller":"traceutil/trace.go:171","msg":"trace[82232289] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5230; }","duration":"230.180751ms","start":"2026-06-16T10:28:33.963178Z","end":"2026-06-16T10:28:34.193359Z","steps":["trace[82232289] 'read index received' (duration: 200.629218ms)","trace[82232289] 'applied index is now lower than readState.Index' (duration: 29.550433ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:34.193481Z","caller":"traceutil/trace.go:171","msg":"trace[1887099129] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"232.233484ms","start":"2026-06-16T10:28:33.961237Z","end":"2026-06-16T10:28:34.19347Z","steps":["trace[1887099129] 'process raft request' (duration: 231.963398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:34.193708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.692534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-16T10:28:34.193814Z","caller":"traceutil/trace.go:171","msg":"trace[674661032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5041; }","duration":"207.854698ms","start":"2026-06-16T10:28:33.985941Z","end":"2026-06-16T10:28:34.193795Z","steps":["trace[674661032] 'agreement among raft nodes before linearized reading' (duration: 207.630873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:34.193846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.646061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"warn","ts":"2026-06-16T10:28:34.193789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.050795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-55b85f4f7f-9hbdt.18b988f4ad593b51\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-06-16T10:28:34.193932Z","caller":"traceutil/trace.go:171","msg":"trace[66569879] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:5041; }","duration":"230.773193ms","start":"2026-06-16T10:28:33.96314Z","end":"2026-06-16T10:28:34.193913Z","steps":["trace[66569879] 'agreement among raft nodes before linearized reading' (duration: 230.512698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:34.193956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.528142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e189764b-31eb-4d9a-807d-a24f8f23e930\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-16T10:28:34.194003Z","caller":"traceutil/trace.go:171","msg":"trace[64586169] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e189764b-31eb-4d9a-807d-a24f8f23e930; range_end:; response_count:1; response_revision:5041; }","duration":"139.632274ms","start":"2026-06-16T10:28:34.054357Z","end":"2026-06-16T10:28:34.193989Z","steps":["trace[64586169] 'agreement among raft nodes before linearized reading' (duration: 139.481301ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:34.193977Z","caller":"traceutil/trace.go:171","msg":"trace[2000195298] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-55b85f4f7f-9hbdt.18b988f4ad593b51; range_end:; response_count:1; response_revision:5041; }","duration":"103.252899ms","start":"2026-06-16T10:28:34.090674Z","end":"2026-06-16T10:28:34.193927Z","steps":["trace[2000195298] 'agreement among raft nodes before linearized reading' (duration: 102.988494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:34.440134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.102449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:28:34.440268Z","caller":"traceutil/trace.go:171","msg":"trace[2111525661] linearizableReadLoop","detail":"{readStateIndex:5235; appliedIndex:5234; }","duration":"184.689437ms","start":"2026-06-16T10:28:34.255556Z","end":"2026-06-16T10:28:34.440246Z","steps":["trace[2111525661] 'read index received' (duration: 50.312043ms)","trace[2111525661] 'applied index is now lower than readState.Index' (duration: 134.375654ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:34.440353Z","caller":"traceutil/trace.go:171","msg":"trace[1836588609] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"185.146386ms","start":"2026-06-16T10:28:34.25515Z","end":"2026-06-16T10:28:34.440297Z","steps":["trace[1836588609] 'process raft request' (duration: 50.787812ms)","trace[1836588609] 'compare' (duration: 133.818383ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:34.440459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.896472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-06-16T10:28:34.440501Z","caller":"traceutil/trace.go:171","msg":"trace[410053640] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5044; }","duration":"184.969872ms","start":"2026-06-16T10:28:34.25552Z","end":"2026-06-16T10:28:34.44049Z","steps":["trace[410053640] 'agreement among raft nodes before linearized reading' (duration: 184.793759ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:34.501989Z","caller":"traceutil/trace.go:171","msg":"trace[651414010] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"219.007558ms","start":"2026-06-16T10:28:34.282952Z","end":"2026-06-16T10:28:34.501959Z","steps":["trace[651414010] 'process raft request' (duration: 218.778233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:36.095035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.646701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-16T10:28:36.095127Z","caller":"traceutil/trace.go:171","msg":"trace[987307766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5063; }","duration":"133.790644ms","start":"2026-06-16T10:28:35.961316Z","end":"2026-06-16T10:28:36.095107Z","steps":["trace[987307766] 'range keys from in-memory index tree' (duration: 133.428657ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:41.741338Z","caller":"traceutil/trace.go:171","msg":"trace[614813251] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"171.532071ms","start":"2026-06-16T10:28:41.569773Z","end":"2026-06-16T10:28:41.741305Z","steps":["trace[614813251] 'process raft request' (duration: 171.321546ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:46.667797Z","caller":"traceutil/trace.go:171","msg":"trace[1026663930] linearizableReadLoop","detail":"{readStateIndex:5380; appliedIndex:5379; }","duration":"479.164903ms","start":"2026-06-16T10:28:46.188611Z","end":"2026-06-16T10:28:46.667775Z","steps":["trace[1026663930] 'read index received' (duration: 478.943068ms)","trace[1026663930] 'applied index is now lower than readState.Index' (duration: 220.974µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:46.667831Z","caller":"traceutil/trace.go:171","msg":"trace[133764627] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"521.027002ms","start":"2026-06-16T10:28:46.146785Z","end":"2026-06-16T10:28:46.667812Z","steps":["trace[133764627] 'process raft request' (duration: 520.843569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:46.667937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.861386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:46.66797Z","caller":"traceutil/trace.go:171","msg":"trace[159531948] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:5183; }","duration":"477.919347ms","start":"2026-06-16T10:28:46.190041Z","end":"2026-06-16T10:28:46.667961Z","steps":["trace[159531948] 'agreement among raft nodes before linearized reading' (duration: 477.852876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:46.667966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.146758Z","time spent":"521.117965ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:28:46.668008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.190032Z","time spent":"477.967148ms","remote":"127.0.0.1:53604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:28:46.668143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.395978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T10:28:46.668162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.512329ms","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-06-16T10:28:46.66825Z","caller":"traceutil/trace.go:171","msg":"trace[378323850] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5183; }","duration":"342.5178ms","start":"2026-06-16T10:28:46.325675Z","end":"2026-06-16T10:28:46.668193Z","steps":["trace[378323850] 'agreement among raft nodes before linearized reading' (duration: 342.352277ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:46.668256Z","caller":"traceutil/trace.go:171","msg":"trace[441080984] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5183; }","duration":"479.657153ms","start":"2026-06-16T10:28:46.188575Z","end":"2026-06-16T10:28:46.668232Z","steps":["trace[441080984] 'agreement among raft nodes before linearized reading' (duration: 479.330446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:46.668288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.043509ms","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-06-16T10:28:46.668318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.188559Z","time spent":"479.748184ms","remote":"127.0.0.1:59334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-16T10:28:46.668326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.0327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-16T10:28:46.668259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.178737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:46.668329Z","caller":"traceutil/trace.go:171","msg":"trace[36787697] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5183; }","duration":"472.096539ms","start":"2026-06-16T10:28:46.19622Z","end":"2026-06-16T10:28:46.668316Z","steps":["trace[36787697] 'agreement among raft nodes before linearized reading' (duration: 471.997917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:46.668499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.196211Z","time spent":"472.278823ms","remote":"127.0.0.1:59310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-16T10:28:46.668378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.325658Z","time spent":"342.636533ms","remote":"127.0.0.1:51968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:28:46.668505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.55899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-16T10:28:46.66864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.102716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-16T10:28:46.668697Z","caller":"traceutil/trace.go:171","msg":"trace[69257198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5183; }","duration":"372.763333ms","start":"2026-06-16T10:28:46.295898Z","end":"2026-06-16T10:28:46.668662Z","steps":["trace[69257198] 'agreement among raft nodes before linearized reading' (duration: 372.513738ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:46.668385Z","caller":"traceutil/trace.go:171","msg":"trace[1204974417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5183; }","duration":"443.164933ms","start":"2026-06-16T10:28:46.225205Z","end":"2026-06-16T10:28:46.66837Z","steps":["trace[1204974417] 'agreement among raft nodes before linearized reading' (duration: 442.934048ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:46.668726Z","caller":"traceutil/trace.go:171","msg":"trace[1691286448] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5183; }","duration":"217.230778ms","start":"2026-06-16T10:28:46.451478Z","end":"2026-06-16T10:28:46.668709Z","steps":["trace[1691286448] 'agreement among raft nodes before linearized reading' (duration: 216.931932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:46.66875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.225173Z","time spent":"443.56836ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T10:28:46.66845Z","caller":"traceutil/trace.go:171","msg":"trace[1959126327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5183; }","duration":"150.474384ms","start":"2026-06-16T10:28:46.517954Z","end":"2026-06-16T10:28:46.668428Z","steps":["trace[1959126327] 'agreement among raft nodes before linearized reading' (duration: 150.144827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:46.668911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.38453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-16T10:28:46.668753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.295879Z","time spent":"372.858596ms","remote":"127.0.0.1:59426","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-06-16T10:28:46.669186Z","caller":"traceutil/trace.go:171","msg":"trace[2082020447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5183; }","duration":"420.719877ms","start":"2026-06-16T10:28:46.248446Z","end":"2026-06-16T10:28:46.669165Z","steps":["trace[2082020447] 'agreement among raft nodes before linearized reading' (duration: 420.020412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:46.669442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.24843Z","time spent":"420.991123ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T10:28:47.769139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.145708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:28:47.769254Z","caller":"traceutil/trace.go:171","msg":"trace[1929699277] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"1.096156046s","start":"2026-06-16T10:28:46.673054Z","end":"2026-06-16T10:28:47.76921Z","steps":["trace[1929699277] 'process raft request' (duration: 532.869755ms)","trace[1929699277] 'compare' (duration: 562.702539ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:47.76931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.673031Z","time spent":"1.096253517s","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:47.798546Z","caller":"traceutil/trace.go:171","msg":"trace[1501885850] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"1.123881506s","start":"2026-06-16T10:28:46.67464Z","end":"2026-06-16T10:28:47.798521Z","steps":["trace[1501885850] 'process raft request' (duration: 1.123620841s)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:47.798765Z","caller":"traceutil/trace.go:171","msg":"trace[1904506267] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"1.123789805s","start":"2026-06-16T10:28:46.674944Z","end":"2026-06-16T10:28:47.798734Z","steps":["trace[1904506267] 'process raft request' (duration: 1.123519759s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.798871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.674624Z","time spent":"1.123997969s","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:47.798909Z","caller":"traceutil/trace.go:171","msg":"trace[17241601] linearizableReadLoop","detail":"{readStateIndex:5384; appliedIndex:5380; }","duration":"869.943865ms","start":"2026-06-16T10:28:46.928941Z","end":"2026-06-16T10:28:47.798885Z","steps":["trace[17241601] 'read index received' (duration: 276.944092ms)","trace[17241601] 'applied index is now lower than readState.Index' (duration: 592.998333ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:47.798926Z","caller":"traceutil/trace.go:171","msg":"trace[1978455106] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"1.117011327s","start":"2026-06-16T10:28:46.68189Z","end":"2026-06-16T10:28:47.798901Z","steps":["trace[1978455106] 'process raft request' (duration: 1.116777692s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.799062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.674925Z","time spent":"1.123969269s","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:28:47.79905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.681884Z","time spent":"1.117096649s","remote":"127.0.0.1:59334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:28:47.799095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.149399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:28:47.799141Z","caller":"traceutil/trace.go:171","msg":"trace[1245549201] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5187; }","duration":"870.230531ms","start":"2026-06-16T10:28:46.928896Z","end":"2026-06-16T10:28:47.799126Z","steps":["trace[1245549201] 'agreement among raft nodes before linearized reading' (duration: 870.117148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.799192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:46.928879Z","time spent":"870.300142ms","remote":"127.0.0.1:59334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":32,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-06-16T10:28:47.965074Z","caller":"traceutil/trace.go:171","msg":"trace[497767034] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"734.101251ms","start":"2026-06-16T10:28:47.230944Z","end":"2026-06-16T10:28:47.965045Z","steps":["trace[497767034] 'process raft request' (duration: 688.817503ms)","trace[497767034] 'compare' (duration: 45.048753ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:47.965269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.230896Z","time spent":"734.257784ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:47.965313Z","caller":"traceutil/trace.go:171","msg":"trace[30760379] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"729.519458ms","start":"2026-06-16T10:28:47.23577Z","end":"2026-06-16T10:28:47.965289Z","steps":["trace[30760379] 'process raft request' (duration: 729.209472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.965524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.235747Z","time spent":"729.701492ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:47.965773Z","caller":"traceutil/trace.go:171","msg":"trace[1708225896] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"727.797323ms","start":"2026-06-16T10:28:47.237958Z","end":"2026-06-16T10:28:47.965755Z","steps":["trace[1708225896] 'process raft request' (duration: 727.211831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.96587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.23795Z","time spent":"727.875825ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:47.966473Z","caller":"traceutil/trace.go:171","msg":"trace[768776574] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"582.422268ms","start":"2026-06-16T10:28:47.384035Z","end":"2026-06-16T10:28:47.966457Z","steps":["trace[768776574] 'process raft request' (duration: 581.667133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.966564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.384019Z","time spent":"582.5057ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:47.967057Z","caller":"traceutil/trace.go:171","msg":"trace[1154201565] linearizableReadLoop","detail":"{readStateIndex:5389; appliedIndex:5384; }","duration":"168.073865ms","start":"2026-06-16T10:28:47.798965Z","end":"2026-06-16T10:28:47.967039Z","steps":["trace[1154201565] 'read index received' (duration: 120.891959ms)","trace[1154201565] 'applied index is now lower than readState.Index' (duration: 47.181246ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:47.967294Z","caller":"traceutil/trace.go:171","msg":"trace[1903116882] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"176.453185ms","start":"2026-06-16T10:28:47.790831Z","end":"2026-06-16T10:28:47.967284Z","steps":["trace[1903116882] 'process raft request' (duration: 175.058797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.967748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.770617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:47.967815Z","caller":"traceutil/trace.go:171","msg":"trace[562313112] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5192; }","duration":"279.89392ms","start":"2026-06-16T10:28:47.687913Z","end":"2026-06-16T10:28:47.967807Z","steps":["trace[562313112] 'agreement among raft nodes before linearized reading' (duration: 279.765737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.967973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.779732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-16T10:28:47.968028Z","caller":"traceutil/trace.go:171","msg":"trace[745373222] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5192; }","duration":"686.838583ms","start":"2026-06-16T10:28:47.281176Z","end":"2026-06-16T10:28:47.968015Z","steps":["trace[745373222] 'agreement among raft nodes before linearized reading' (duration: 686.67529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.968069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.281081Z","time spent":"686.980236ms","remote":"127.0.0.1:59310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-16T10:28:47.968307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.72476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:47.968346Z","caller":"traceutil/trace.go:171","msg":"trace[1759665072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5192; }","duration":"449.791861ms","start":"2026-06-16T10:28:47.518544Z","end":"2026-06-16T10:28:47.968336Z","steps":["trace[1759665072] 'agreement among raft nodes before linearized reading' (duration: 449.73347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.968376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.518528Z","time spent":"449.842542ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T10:28:47.968606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.994985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-16T10:28:47.968645Z","caller":"traceutil/trace.go:171","msg":"trace[2124604713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5192; }","duration":"565.092677ms","start":"2026-06-16T10:28:47.403541Z","end":"2026-06-16T10:28:47.968634Z","steps":["trace[2124604713] 'agreement among raft nodes before linearized reading' (duration: 564.988735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:47.968679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.403493Z","time spent":"565.179879ms","remote":"127.0.0.1:59426","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-06-16T10:28:48.237726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.100708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:28:48.238912Z","caller":"traceutil/trace.go:171","msg":"trace[1989590735] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"432.668377ms","start":"2026-06-16T10:28:47.806224Z","end":"2026-06-16T10:28:48.238892Z","steps":["trace[1989590735] 'process raft request' (duration: 270.285543ms)","trace[1989590735] 'compare' (duration: 160.963976ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:48.238991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.806198Z","time spent":"432.762469ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:48.239571Z","caller":"traceutil/trace.go:171","msg":"trace[1881512813] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"432.922442ms","start":"2026-06-16T10:28:47.806639Z","end":"2026-06-16T10:28:48.239562Z","steps":["trace[1881512813] 'process raft request' (duration: 432.752378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:48.23963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.806629Z","time spent":"432.971372ms","remote":"127.0.0.1:59316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:48.239723Z","caller":"traceutil/trace.go:171","msg":"trace[19720077] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"433.30055ms","start":"2026-06-16T10:28:47.806417Z","end":"2026-06-16T10:28:48.239717Z","steps":["trace[19720077] 'process raft request' (duration: 432.405712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:48.239761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.806399Z","time spent":"433.34311ms","remote":"127.0.0.1:59316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:48.240063Z","caller":"traceutil/trace.go:171","msg":"trace[69882170] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"433.022934ms","start":"2026-06-16T10:28:47.807034Z","end":"2026-06-16T10:28:48.240056Z","steps":["trace[69882170] 'process raft request' (duration: 432.425322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:48.240105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.807022Z","time spent":"433.063274ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:48.240226Z","caller":"traceutil/trace.go:171","msg":"trace[1284652176] linearizableReadLoop","detail":"{readStateIndex:5393; appliedIndex:5389; }","duration":"273.086544ms","start":"2026-06-16T10:28:47.967131Z","end":"2026-06-16T10:28:48.240218Z","steps":["trace[1284652176] 'read index received' (duration: 109.336653ms)","trace[1284652176] 'applied index is now lower than readState.Index' (duration: 163.749001ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:48.240353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.627456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10682"} {"level":"info","ts":"2026-06-16T10:28:48.240374Z","caller":"traceutil/trace.go:171","msg":"trace[1746477571] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5196; }","duration":"434.686058ms","start":"2026-06-16T10:28:47.805683Z","end":"2026-06-16T10:28:48.240369Z","steps":["trace[1746477571] 'agreement among raft nodes before linearized reading' (duration: 434.598686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:48.240392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:47.805662Z","time spent":"434.726138ms","remote":"127.0.0.1:59334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10704,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-16T10:28:48.241744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.9395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:28:48.241882Z","caller":"traceutil/trace.go:171","msg":"trace[954688181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5197; }","duration":"272.102034ms","start":"2026-06-16T10:28:47.96976Z","end":"2026-06-16T10:28:48.241862Z","steps":["trace[954688181] 'agreement among raft nodes before linearized reading' (duration: 271.92296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:48.24201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.90073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e189764b-31eb-4d9a-807d-a24f8f23e930\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-16T10:28:48.242072Z","caller":"traceutil/trace.go:171","msg":"trace[2070409933] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e189764b-31eb-4d9a-807d-a24f8f23e930; range_end:; response_count:1; response_revision:5197; }","duration":"269.980411ms","start":"2026-06-16T10:28:47.972082Z","end":"2026-06-16T10:28:48.242063Z","steps":["trace[2070409933] 'agreement among raft nodes before linearized reading' (duration: 269.782407ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:48.241848Z","caller":"traceutil/trace.go:171","msg":"trace[1149098439] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"269.937951ms","start":"2026-06-16T10:28:47.9719Z","end":"2026-06-16T10:28:48.241837Z","steps":["trace[1149098439] 'process raft request' (duration: 267.625984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:49.418152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.967954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-16T10:28:49.418217Z","caller":"traceutil/trace.go:171","msg":"trace[720920196] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5200; }","duration":"181.084916ms","start":"2026-06-16T10:28:49.237118Z","end":"2026-06-16T10:28:49.418203Z","steps":["trace[720920196] 'range keys from in-memory index tree' (duration: 180.720918ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:49.857935Z","caller":"traceutil/trace.go:171","msg":"trace[614169681] linearizableReadLoop","detail":"{readStateIndex:5399; appliedIndex:5398; }","duration":"254.290874ms","start":"2026-06-16T10:28:49.603598Z","end":"2026-06-16T10:28:49.857889Z","steps":["trace[614169681] 'read index received' (duration: 214.587592ms)","trace[614169681] 'applied index is now lower than readState.Index' (duration: 39.701692ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:28:49.858023Z","caller":"traceutil/trace.go:171","msg":"trace[1682198978] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"263.640682ms","start":"2026-06-16T10:28:49.594311Z","end":"2026-06-16T10:28:49.857952Z","steps":["trace[1682198978] 'process raft request' (duration: 223.848239ms)","trace[1682198978] 'compare' (duration: 39.472217ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:49.858226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.60595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-06-16T10:28:49.858264Z","caller":"traceutil/trace.go:171","msg":"trace[1239674171] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5202; }","duration":"254.701292ms","start":"2026-06-16T10:28:49.603552Z","end":"2026-06-16T10:28:49.858253Z","steps":["trace[1239674171] 'agreement among raft nodes before linearized reading' (duration: 254.504968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.474237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.497528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:28:50.47434Z","caller":"traceutil/trace.go:171","msg":"trace[2014272141] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"540.753292ms","start":"2026-06-16T10:28:49.933571Z","end":"2026-06-16T10:28:50.474324Z","steps":["trace[2014272141] 'process raft request' (duration: 424.094941ms)","trace[2014272141] 'compare' (duration: 115.897806ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:50.474407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.933548Z","time spent":"540.827083ms","remote":"127.0.0.1:59248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.504214Z","caller":"traceutil/trace.go:171","msg":"trace[464436573] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"559.378236ms","start":"2026-06-16T10:28:49.944804Z","end":"2026-06-16T10:28:50.504182Z","steps":["trace[464436573] 'process raft request' (duration: 559.03939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.504384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.944774Z","time spent":"559.509699ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11833,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.504408Z","caller":"traceutil/trace.go:171","msg":"trace[597288267] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"533.034835ms","start":"2026-06-16T10:28:49.97133Z","end":"2026-06-16T10:28:50.504365Z","steps":["trace[597288267] 'process raft request' (duration: 532.77398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.504589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.971309Z","time spent":"533.180648ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.504683Z","caller":"traceutil/trace.go:171","msg":"trace[371058113] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"530.541464ms","start":"2026-06-16T10:28:49.974078Z","end":"2026-06-16T10:28:50.50462Z","steps":["trace[371058113] 'process raft request' (duration: 530.140706ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:28:50.504858Z","caller":"traceutil/trace.go:171","msg":"trace[564642835] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"529.84792ms","start":"2026-06-16T10:28:49.974996Z","end":"2026-06-16T10:28:50.504844Z","steps":["trace[564642835] 'process raft request' (duration: 529.281788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.504955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.97407Z","time spent":"530.729408ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.505019Z","caller":"traceutil/trace.go:171","msg":"trace[1980816533] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"531.732178ms","start":"2026-06-16T10:28:49.973271Z","end":"2026-06-16T10:28:50.505003Z","steps":["trace[1980816533] 'process raft request' (duration: 530.889191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.505092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.973258Z","time spent":"531.806109ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:28:50.504921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:49.974989Z","time spent":"529.900112ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:28:50.601569Z","caller":"traceutil/trace.go:171","msg":"trace[1158726699] linearizableReadLoop","detail":"{readStateIndex:5410; appliedIndex:5403; }","duration":"353.521875ms","start":"2026-06-16T10:28:50.248016Z","end":"2026-06-16T10:28:50.601538Z","steps":["trace[1158726699] 'read index received' (duration: 109.6552ms)","trace[1158726699] 'applied index is now lower than readState.Index' (duration: 243.864665ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:28:50.601942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.883233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-16T10:28:50.601991Z","caller":"traceutil/trace.go:171","msg":"trace[704308470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5212; }","duration":"354.023455ms","start":"2026-06-16T10:28:50.247952Z","end":"2026-06-16T10:28:50.601976Z","steps":["trace[704308470] 'agreement among raft nodes before linearized reading' (duration: 353.792051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.602036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:28:50.247915Z","time spent":"354.107796ms","remote":"127.0.0.1:59426","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-06-16T10:28:50.602184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.402367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-16T10:28:50.602286Z","caller":"traceutil/trace.go:171","msg":"trace[486241132] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5212; }","duration":"127.53738ms","start":"2026-06-16T10:28:50.474721Z","end":"2026-06-16T10:28:50.602258Z","steps":["trace[486241132] 'agreement among raft nodes before linearized reading' (duration: 127.154382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:28:50.602184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.166432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-rl59p.18b988f0f78ba345\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-06-16T10:28:50.602393Z","caller":"traceutil/trace.go:171","msg":"trace[1275951951] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-rl59p.18b988f0f78ba345; range_end:; response_count:1; response_revision:5212; }","duration":"124.402237ms","start":"2026-06-16T10:28:50.477978Z","end":"2026-06-16T10:28:50.60238Z","steps":["trace[1275951951] 'agreement among raft nodes before linearized reading' (duration: 124.047149ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:00.820175Z","caller":"traceutil/trace.go:171","msg":"trace[799137031] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"130.365117ms","start":"2026-06-16T10:29:00.689773Z","end":"2026-06-16T10:29:00.820138Z","steps":["trace[799137031] 'process raft request' (duration: 130.137242ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:11.777632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2880} {"level":"info","ts":"2026-06-16T10:29:11.846846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2880,"took":"68.197821ms","hash":352966132,"current-db-size-bytes":52776960,"current-db-size":"53 MB","current-db-size-in-use-bytes":27742208,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-16T10:29:11.846902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":352966132,"revision":2880,"compact-revision":-1} {"level":"info","ts":"2026-06-16T10:29:39.040347Z","caller":"traceutil/trace.go:171","msg":"trace[672278026] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"127.920259ms","start":"2026-06-16T10:29:38.912374Z","end":"2026-06-16T10:29:39.040295Z","steps":["trace[672278026] 'process raft request' (duration: 90.802771ms)","trace[672278026] 'compare' (duration: 36.641839ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:29:39.270234Z","caller":"traceutil/trace.go:171","msg":"trace[444968886] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"126.686723ms","start":"2026-06-16T10:29:39.143523Z","end":"2026-06-16T10:29:39.27021Z","steps":["trace[444968886] 'process raft request' (duration: 126.627142ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:39.270261Z","caller":"traceutil/trace.go:171","msg":"trace[749189633] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"128.290214ms","start":"2026-06-16T10:29:39.141936Z","end":"2026-06-16T10:29:39.270227Z","steps":["trace[749189633] 'process raft request' (duration: 128.051619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.618798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745400496689354,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T10:29:47.784632Z","caller":"traceutil/trace.go:171","msg":"trace[635485039] linearizableReadLoop","detail":"{readStateIndex:5896; appliedIndex:5895; }","duration":"665.871837ms","start":"2026-06-16T10:29:47.118736Z","end":"2026-06-16T10:29:47.784608Z","steps":["trace[635485039] 'read index received' (duration: 665.767535ms)","trace[635485039] 'applied index is now lower than readState.Index' (duration: 103.192µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:29:47.784743Z","caller":"traceutil/trace.go:171","msg":"trace[600257446] transaction","detail":"{read_only:false; response_revision:5684; number_of_response:1; }","duration":"690.455125ms","start":"2026-06-16T10:29:47.094276Z","end":"2026-06-16T10:29:47.784731Z","steps":["trace[600257446] 'process raft request' (duration: 690.16034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.784897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:47.094259Z","time spent":"690.517617ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T10:29:47.78506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.204054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:29:47.785153Z","caller":"traceutil/trace.go:171","msg":"trace[1202932776] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:5684; }","duration":"666.464459ms","start":"2026-06-16T10:29:47.118669Z","end":"2026-06-16T10:29:47.785133Z","steps":["trace[1202932776] 'agreement among raft nodes before linearized reading' (duration: 666.199584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.785268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:47.118607Z","time spent":"666.646902ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"info","ts":"2026-06-16T10:29:47.956802Z","caller":"traceutil/trace.go:171","msg":"trace[416194474] linearizableReadLoop","detail":"{readStateIndex:5897; appliedIndex:5896; }","duration":"172.079067ms","start":"2026-06-16T10:29:47.784698Z","end":"2026-06-16T10:29:47.956777Z","steps":["trace[416194474] 'read index received' (duration: 171.781052ms)","trace[416194474] 'applied index is now lower than readState.Index' (duration: 296.865µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:29:47.956997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.661503ms","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-06-16T10:29:47.95703Z","caller":"traceutil/trace.go:171","msg":"trace[1752894633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5685; }","duration":"783.882507ms","start":"2026-06-16T10:29:47.173137Z","end":"2026-06-16T10:29:47.95702Z","steps":["trace[1752894633] 'agreement among raft nodes before linearized reading' (duration: 783.732184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.95707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:47.173109Z","time spent":"783.952559ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T10:29:47.95715Z","caller":"traceutil/trace.go:171","msg":"trace[1508368727] transaction","detail":"{read_only:false; response_revision:5685; number_of_response:1; }","duration":"185.299344ms","start":"2026-06-16T10:29:47.771816Z","end":"2026-06-16T10:29:47.957115Z","steps":["trace[1508368727] 'process raft request' (duration: 184.641821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.957155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.237142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T10:29:47.957183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.651634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:29:47.957257Z","caller":"traceutil/trace.go:171","msg":"trace[918153840] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5685; }","duration":"529.358194ms","start":"2026-06-16T10:29:47.427851Z","end":"2026-06-16T10:29:47.957209Z","steps":["trace[918153840] 'agreement among raft nodes before linearized reading' (duration: 529.240492ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:29:47.957297Z","caller":"traceutil/trace.go:171","msg":"trace[743754877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5685; }","duration":"438.843588ms","start":"2026-06-16T10:29:47.518435Z","end":"2026-06-16T10:29:47.957278Z","steps":["trace[743754877] 'agreement among raft nodes before linearized reading' (duration: 438.649914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.957348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.369615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T10:29:47.957358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:47.518411Z","time spent":"438.93214ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T10:29:47.957377Z","caller":"traceutil/trace.go:171","msg":"trace[693818091] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5685; }","duration":"529.439996ms","start":"2026-06-16T10:29:47.427928Z","end":"2026-06-16T10:29:47.957368Z","steps":["trace[693818091] 'agreement among raft nodes before linearized reading' (duration: 529.396355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.957404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:47.427904Z","time spent":"529.493497ms","remote":"127.0.0.1:59398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-06-16T10:29:47.957304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:47.427807Z","time spent":"529.487017ms","remote":"127.0.0.1:59398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-06-16T10:29:47.957407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.820955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T10:29:47.957454Z","caller":"traceutil/trace.go:171","msg":"trace[828697189] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5685; }","duration":"301.908527ms","start":"2026-06-16T10:29:47.65553Z","end":"2026-06-16T10:29:47.957439Z","steps":["trace[828697189] 'agreement among raft nodes before linearized reading' (duration: 301.845445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.957486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:47.655509Z","time spent":"301.968327ms","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:29:47.957729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.074732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:29:47.957872Z","caller":"traceutil/trace.go:171","msg":"trace[129670106] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5685; }","duration":"681.599671ms","start":"2026-06-16T10:29:47.276258Z","end":"2026-06-16T10:29:47.957857Z","steps":["trace[129670106] 'agreement among raft nodes before linearized reading' (duration: 681.054761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:29:47.957913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:29:47.276237Z","time spent":"681.659402ms","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-16T10:30:05.154782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.987964ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T10:30:05.155002Z","caller":"traceutil/trace.go:171","msg":"trace[1263787092] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5824; }","duration":"237.254479ms","start":"2026-06-16T10:30:04.917702Z","end":"2026-06-16T10:30:05.154957Z","steps":["trace[1263787092] 'range keys from in-memory index tree' (duration: 236.966493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:30:05.155688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.622154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T10:30:05.155991Z","caller":"traceutil/trace.go:171","msg":"trace[692706272] transaction","detail":"{read_only:false; response_revision:5826; number_of_response:1; }","duration":"281.639231ms","start":"2026-06-16T10:30:04.874325Z","end":"2026-06-16T10:30:05.155964Z","steps":["trace[692706272] 'process raft request' (duration: 281.548089ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:30:05.156171Z","caller":"traceutil/trace.go:171","msg":"trace[383987890] transaction","detail":"{read_only:false; response_revision:5825; number_of_response:1; }","duration":"357.465608ms","start":"2026-06-16T10:30:04.798682Z","end":"2026-06-16T10:30:05.156147Z","steps":["trace[383987890] 'process raft request' (duration: 177.190672ms)","trace[383987890] 'compare' (duration: 179.239927ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T10:30:05.156198Z","caller":"traceutil/trace.go:171","msg":"trace[1762729978] linearizableReadLoop","detail":"{readStateIndex:6040; appliedIndex:6039; }","duration":"323.122771ms","start":"2026-06-16T10:30:04.833041Z","end":"2026-06-16T10:30:05.156164Z","steps":["trace[1762729978] 'read index received' (duration: 142.982707ms)","trace[1762729978] 'applied index is now lower than readState.Index' (duration: 180.137744ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T10:30:05.157365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.294962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-16T10:30:05.157562Z","caller":"traceutil/trace.go:171","msg":"trace[1384623879] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:5826; }","duration":"324.470495ms","start":"2026-06-16T10:30:04.832989Z","end":"2026-06-16T10:30:05.157459Z","steps":["trace[1384623879] 'agreement among raft nodes before linearized reading' (duration: 323.566919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T10:30:05.157905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:30:04.832967Z","time spent":"324.888413ms","remote":"127.0.0.1:59194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-06-16T10:30:05.158091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T10:30:04.798563Z","time spent":"357.660903ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T10:32:38.47573Z","caller":"traceutil/trace.go:171","msg":"trace[457510877] transaction","detail":"{read_only:false; response_revision:6838; number_of_response:1; }","duration":"180.636176ms","start":"2026-06-16T10:32:38.295065Z","end":"2026-06-16T10:32:38.475701Z","steps":["trace[457510877] 'process raft request' (duration: 180.411042ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T10:34:11.792604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5364} {"level":"info","ts":"2026-06-16T10:34:11.865934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5364,"took":"72.13789ms","hash":2915728616,"current-db-size-bytes":52776960,"current-db-size":"53 MB","current-db-size-in-use-bytes":18563072,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-16T10:34:11.865999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2915728616,"revision":5364,"compact-revision":2880}