{"level":"warn","ts":"2026-08-25T19:04:59.154462Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-25T19:04:59.154591Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.136:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.136:2380","--initial-cluster=instance=https://162.253.55.136:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.136:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.136: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-08-25T19:04:59.154695Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-25T19:04:59.154715Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.136:2380"]} {"level":"info","ts":"2026-08-25T19:04:59.154751Z","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-08-25T19:04:59.155838Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"]} {"level":"info","ts":"2026-08-25T19:04:59.155939Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.136:2380"],"listen-peer-urls":["https://162.253.55.136:2380"],"advertise-client-urls":["https://162.253.55.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.136: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-08-25T19:04:59.160907Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.657308ms"} {"level":"info","ts":"2026-08-25T19:04:59.166649Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"308ce60d3e55f7ff","cluster-id":"dd7f8b36a12acab1"} {"level":"info","ts":"2026-08-25T19:04:59.166748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=()"} {"level":"info","ts":"2026-08-25T19:04:59.166786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became follower at term 0"} {"level":"info","ts":"2026-08-25T19:04:59.166802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 308ce60d3e55f7ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-25T19:04:59.166811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became follower at term 1"} {"level":"info","ts":"2026-08-25T19:04:59.166857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=(3498423955114817535)"} {"level":"warn","ts":"2026-08-25T19:04:59.184149Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-25T19:04:59.186032Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-25T19:04:59.18904Z","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-08-25T19:04:59.192941Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"308ce60d3e55f7ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-25T19:04:59.193827Z","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-08-25T19:04:59.194721Z","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-08-25T19:04:59.194822Z","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-08-25T19:04:59.194812Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"308ce60d3e55f7ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-25T19:04:59.19563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=(3498423955114817535)"} {"level":"info","ts":"2026-08-25T19:04:59.19581Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd7f8b36a12acab1","local-member-id":"308ce60d3e55f7ff","added-peer-id":"308ce60d3e55f7ff","added-peer-peer-urls":["https://162.253.55.136:2380"]} {"level":"info","ts":"2026-08-25T19:04:59.201874Z","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-08-25T19:04:59.202153Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"308ce60d3e55f7ff","initial-advertise-peer-urls":["https://162.253.55.136:2380"],"listen-peer-urls":["https://162.253.55.136:2380"],"advertise-client-urls":["https://162.253.55.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-25T19:04:59.20218Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-25T19:04:59.202218Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.136:2380"} {"level":"info","ts":"2026-08-25T19:04:59.202392Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.136:2380"} {"level":"info","ts":"2026-08-25T19:04:59.667507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff is starting a new election at term 1"} {"level":"info","ts":"2026-08-25T19:04:59.667576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became pre-candidate at term 1"} {"level":"info","ts":"2026-08-25T19:04:59.667608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff received MsgPreVoteResp from 308ce60d3e55f7ff at term 1"} {"level":"info","ts":"2026-08-25T19:04:59.667627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became candidate at term 2"} {"level":"info","ts":"2026-08-25T19:04:59.667638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff received MsgVoteResp from 308ce60d3e55f7ff at term 2"} {"level":"info","ts":"2026-08-25T19:04:59.667654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became leader at term 2"} {"level":"info","ts":"2026-08-25T19:04:59.667667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 308ce60d3e55f7ff elected leader 308ce60d3e55f7ff at term 2"} {"level":"info","ts":"2026-08-25T19:04:59.852895Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-25T19:04:59.853191Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"308ce60d3e55f7ff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.136:2379]}","request-path":"/0/members/308ce60d3e55f7ff/attributes","cluster-id":"dd7f8b36a12acab1","publish-timeout":"7s"} {"level":"info","ts":"2026-08-25T19:04:59.853208Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-25T19:04:59.856349Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-25T19:04:59.856438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-25T19:04:59.856956Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-25T19:04:59.856987Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-25T19:04:59.858737Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.136:2379"} {"level":"info","ts":"2026-08-25T19:05:00.067451Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd7f8b36a12acab1","local-member-id":"308ce60d3e55f7ff","cluster-version":"3.5"} {"level":"info","ts":"2026-08-25T19:05:00.067577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-25T19:05:00.067624Z","caller":"traceutil/trace.go:171","msg":"trace[1125702927] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"156.983632ms","start":"2026-08-25T19:04:59.910624Z","end":"2026-08-25T19:05:00.067608Z","steps":["trace[1125702927] 'read index received' (duration: 156.752527ms)","trace[1125702927] 'applied index is now lower than readState.Index' (duration: 228.145µs)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:00.067683Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-08-25T19:05:00.06776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.111095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.06781Z","caller":"traceutil/trace.go:171","msg":"trace[850276839] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"157.183916ms","start":"2026-08-25T19:04:59.910619Z","end":"2026-08-25T19:05:00.067803Z","steps":["trace[850276839] 'agreement among raft nodes before linearized reading' (duration: 157.054243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.067847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.099434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.067957Z","caller":"traceutil/trace.go:171","msg":"trace[72141374] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"157.241007ms","start":"2026-08-25T19:04:59.910693Z","end":"2026-08-25T19:05:00.067934Z","steps":["trace[72141374] 'agreement among raft nodes before linearized reading' (duration: 157.044073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.068513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.208181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.068533Z","caller":"traceutil/trace.go:171","msg":"trace[1701798928] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"103.237371ms","start":"2026-08-25T19:04:59.96529Z","end":"2026-08-25T19:05:00.068527Z","steps":["trace[1701798928] 'agreement among raft nodes before linearized reading' (duration: 103.20457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.068529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.659965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.068572Z","caller":"traceutil/trace.go:171","msg":"trace[1248273071] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"115.720767ms","start":"2026-08-25T19:04:59.952835Z","end":"2026-08-25T19:05:00.068556Z","steps":["trace[1248273071] 'agreement among raft nodes before linearized reading' (duration: 115.662906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.068791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.052929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.068818Z","caller":"traceutil/trace.go:171","msg":"trace[2052017633] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"103.104649ms","start":"2026-08-25T19:04:59.965706Z","end":"2026-08-25T19:05:00.06881Z","steps":["trace[2052017633] 'agreement among raft nodes before linearized reading' (duration: 103.070068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.318589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.882603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-25T19:05:00.318813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.845098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.318884Z","caller":"traceutil/trace.go:171","msg":"trace[1222969935] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"169.932881ms","start":"2026-08-25T19:05:00.148935Z","end":"2026-08-25T19:05:00.318868Z","steps":["trace[1222969935] 'count revisions from in-memory index tree' (duration: 169.794047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.319025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.843975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.319052Z","caller":"traceutil/trace.go:171","msg":"trace[211980799] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"126.907685ms","start":"2026-08-25T19:05:00.192136Z","end":"2026-08-25T19:05:00.319043Z","steps":["trace[211980799] 'range keys from in-memory index tree' (duration: 126.756793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.318818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.369659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-25T19:05:00.319154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.085868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.319172Z","caller":"traceutil/trace.go:171","msg":"trace[1277417109] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"117.116328ms","start":"2026-08-25T19:05:00.20205Z","end":"2026-08-25T19:05:00.319166Z","steps":["trace[1277417109] 'range keys from in-memory index tree' (duration: 117.031057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.319262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.015646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.318668Z","caller":"traceutil/trace.go:171","msg":"trace[63863773] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"220.963754ms","start":"2026-08-25T19:05:00.09769Z","end":"2026-08-25T19:05:00.318654Z","steps":["trace[63863773] 'range keys from in-memory index tree' (duration: 220.852352ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:00.319288Z","caller":"traceutil/trace.go:171","msg":"trace[23079371] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"117.054196ms","start":"2026-08-25T19:05:00.202224Z","end":"2026-08-25T19:05:00.319278Z","steps":["trace[23079371] 'count revisions from in-memory index tree' (duration: 116.978985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.319369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.941502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.319395Z","caller":"traceutil/trace.go:171","msg":"trace[388209436] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"106.994693ms","start":"2026-08-25T19:05:00.212394Z","end":"2026-08-25T19:05:00.319389Z","steps":["trace[388209436] 'count revisions from in-memory index tree' (duration: 106.878051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.319475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.8496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.319496Z","caller":"traceutil/trace.go:171","msg":"trace[379390532] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"106.929962ms","start":"2026-08-25T19:05:00.21256Z","end":"2026-08-25T19:05:00.31949Z","steps":["trace[379390532] 'range keys from in-memory index tree' (duration: 106.722817ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:00.319525Z","caller":"traceutil/trace.go:171","msg":"trace[662668781] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"242.808709ms","start":"2026-08-25T19:05:00.076354Z","end":"2026-08-25T19:05:00.319162Z","steps":["trace[662668781] 'range keys from in-memory index tree' (duration: 242.272557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.319625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.781845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.319704Z","caller":"traceutil/trace.go:171","msg":"trace[390873563] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"208.888187ms","start":"2026-08-25T19:05:00.110797Z","end":"2026-08-25T19:05:00.319685Z","steps":["trace[390873563] 'range keys from in-memory index tree' (duration: 208.724734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.319863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.861226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.319909Z","caller":"traceutil/trace.go:171","msg":"trace[784242382] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"208.913247ms","start":"2026-08-25T19:05:00.110976Z","end":"2026-08-25T19:05:00.319889Z","steps":["trace[784242382] 'count revisions from in-memory index tree' (duration: 208.825145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.318541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.630194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-25T19:05:00.320067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.701796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.320068Z","caller":"traceutil/trace.go:171","msg":"trace[929032410] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"171.176686ms","start":"2026-08-25T19:05:00.148871Z","end":"2026-08-25T19:05:00.320048Z","steps":["trace[929032410] 'range keys from in-memory index tree' (duration: 169.587923ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:00.320105Z","caller":"traceutil/trace.go:171","msg":"trace[1284168627] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"194.736856ms","start":"2026-08-25T19:05:00.125357Z","end":"2026-08-25T19:05:00.320094Z","steps":["trace[1284168627] 'count revisions from in-memory index tree' (duration: 194.666885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.318557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.686545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-25T19:05:00.320275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.53509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.320314Z","caller":"traceutil/trace.go:171","msg":"trace[674330751] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"184.587401ms","start":"2026-08-25T19:05:00.135707Z","end":"2026-08-25T19:05:00.320294Z","steps":["trace[674330751] 'range keys from in-memory index tree' (duration: 184.438389ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:00.320287Z","caller":"traceutil/trace.go:171","msg":"trace[500491983] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"235.406151ms","start":"2026-08-25T19:05:00.084864Z","end":"2026-08-25T19:05:00.32027Z","steps":["trace[500491983] 'range keys from in-memory index tree' (duration: 233.631664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.319134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.955737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-25T19:05:00.318591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.969881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.320502Z","caller":"traceutil/trace.go:171","msg":"trace[1724710724] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"128.348025ms","start":"2026-08-25T19:05:00.192138Z","end":"2026-08-25T19:05:00.320486Z","steps":["trace[1724710724] 'count revisions from in-memory index tree' (duration: 126.866595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.31857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.571572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.320698Z","caller":"traceutil/trace.go:171","msg":"trace[884681760] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"235.675876ms","start":"2026-08-25T19:05:00.084979Z","end":"2026-08-25T19:05:00.320655Z","steps":["trace[884681760] 'count revisions from in-memory index tree' (duration: 233.526791ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:00.320562Z","caller":"traceutil/trace.go:171","msg":"trace[1931033822] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"243.986953ms","start":"2026-08-25T19:05:00.076557Z","end":"2026-08-25T19:05:00.320544Z","steps":["trace[1931033822] 'count revisions from in-memory index tree' (duration: 241.832678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.31858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.012486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.320803Z","caller":"traceutil/trace.go:171","msg":"trace[1843131509] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"223.243933ms","start":"2026-08-25T19:05:00.097548Z","end":"2026-08-25T19:05:00.320792Z","steps":["trace[1843131509] 'count revisions from in-memory index tree' (duration: 220.978665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:00.320241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.501439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-25T19:05:00.320024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.781938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-25T19:05:00.320851Z","caller":"traceutil/trace.go:171","msg":"trace[442282775] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"185.105354ms","start":"2026-08-25T19:05:00.13573Z","end":"2026-08-25T19:05:00.320835Z","steps":["trace[442282775] 'count revisions from in-memory index tree' (duration: 184.415748ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:00.320883Z","caller":"traceutil/trace.go:171","msg":"trace[1497906651] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"195.647176ms","start":"2026-08-25T19:05:00.125221Z","end":"2026-08-25T19:05:00.320868Z","steps":["trace[1497906651] 'range keys from in-memory index tree' (duration: 194.748247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:34.51107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.571774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:05:34.511306Z","caller":"traceutil/trace.go:171","msg":"trace[1774965667] transaction","detail":"{read_only:false; number_of_response:1; response_revision:534; }","duration":"296.89001ms","start":"2026-08-25T19:05:34.214399Z","end":"2026-08-25T19:05:34.511289Z","steps":["trace[1774965667] 'process raft request' (duration: 296.840669ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.511526Z","caller":"traceutil/trace.go:171","msg":"trace[550478343] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"300.636493ms","start":"2026-08-25T19:05:34.210875Z","end":"2026-08-25T19:05:34.511512Z","steps":["trace[550478343] 'process raft request' (duration: 177.982195ms)","trace[550478343] 'compare' (duration: 121.40525ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:05:34.511607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.210861Z","time spent":"300.709055ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:34.511499Z","caller":"traceutil/trace.go:171","msg":"trace[1046986037] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"300.53175ms","start":"2026-08-25T19:05:34.210927Z","end":"2026-08-25T19:05:34.511459Z","steps":["trace[1046986037] 'process raft request' (duration: 300.256574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:34.511794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.210923Z","time spent":"300.794247ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-25T19:05:34.56112Z","caller":"traceutil/trace.go:171","msg":"trace[364396081] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"342.271495ms","start":"2026-08-25T19:05:34.218809Z","end":"2026-08-25T19:05:34.561081Z","steps":["trace[364396081] 'process raft request' (duration: 342.082941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:34.561332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.218791Z","time spent":"342.409668ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-25T19:05:34.561516Z","caller":"traceutil/trace.go:171","msg":"trace[1450056768] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"342.605213ms","start":"2026-08-25T19:05:34.21887Z","end":"2026-08-25T19:05:34.561475Z","steps":["trace[1450056768] 'process raft request' (duration: 342.151973ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.561608Z","caller":"traceutil/trace.go:171","msg":"trace[984139476] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"336.800994ms","start":"2026-08-25T19:05:34.224794Z","end":"2026-08-25T19:05:34.561595Z","steps":["trace[984139476] 'process raft request' (duration: 336.352224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:34.561638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.218867Z","time spent":"342.715005ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-25T19:05:34.561677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.224776Z","time spent":"336.871526ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-25T19:05:34.561814Z","caller":"traceutil/trace.go:171","msg":"trace[2125813150] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"340.356812ms","start":"2026-08-25T19:05:34.221446Z","end":"2026-08-25T19:05:34.561803Z","steps":["trace[2125813150] 'process raft request' (duration: 339.660567ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.561845Z","caller":"traceutil/trace.go:171","msg":"trace[96426149] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"336.927307ms","start":"2026-08-25T19:05:34.224908Z","end":"2026-08-25T19:05:34.561836Z","steps":["trace[96426149] 'process raft request' (duration: 336.272433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:34.561893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.22144Z","time spent":"340.429365ms","remote":"127.0.0.1:37424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-25T19:05:34.561904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.224904Z","time spent":"336.966318ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:34.561974Z","caller":"traceutil/trace.go:171","msg":"trace[1320901309] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"340.090647ms","start":"2026-08-25T19:05:34.221296Z","end":"2026-08-25T19:05:34.561386Z","steps":["trace[1320901309] 'process raft request' (duration: 339.76728ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.56196Z","caller":"traceutil/trace.go:171","msg":"trace[1689268747] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"323.410217ms","start":"2026-08-25T19:05:34.238472Z","end":"2026-08-25T19:05:34.561882Z","steps":["trace[1689268747] 'process raft request' (duration: 323.268064ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.562044Z","caller":"traceutil/trace.go:171","msg":"trace[1448101390] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"302.816172ms","start":"2026-08-25T19:05:34.259203Z","end":"2026-08-25T19:05:34.56202Z","steps":["trace[1448101390] 'process raft request' (duration: 302.618037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:34.562091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.259176Z","time spent":"302.894234ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-25T19:05:34.562154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.238443Z","time spent":"323.627013ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-25T19:05:34.562299Z","caller":"traceutil/trace.go:171","msg":"trace[1921020927] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"210.370633ms","start":"2026-08-25T19:05:34.351875Z","end":"2026-08-25T19:05:34.562246Z","steps":["trace[1921020927] 'process raft request' (duration: 210.22912ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.562342Z","caller":"traceutil/trace.go:171","msg":"trace[1821218389] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"326.616328ms","start":"2026-08-25T19:05:34.235253Z","end":"2026-08-25T19:05:34.561869Z","steps":["trace[1821218389] 'process raft request' (duration: 326.084196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:34.562674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.221276Z","time spent":"340.725581ms","remote":"127.0.0.1:37424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-25T19:05:34.562715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.235226Z","time spent":"327.331405ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-25T19:05:34.709657Z","caller":"traceutil/trace.go:171","msg":"trace[1487286962] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"189.471088ms","start":"2026-08-25T19:05:34.520158Z","end":"2026-08-25T19:05:34.709629Z","steps":["trace[1487286962] 'process raft request' (duration: 189.235353ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.710043Z","caller":"traceutil/trace.go:171","msg":"trace[834383517] transaction","detail":"{read_only:false; number_of_response:1; response_revision:546; }","duration":"189.272164ms","start":"2026-08-25T19:05:34.520716Z","end":"2026-08-25T19:05:34.709988Z","steps":["trace[834383517] 'process raft request' (duration: 188.839765ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.710097Z","caller":"traceutil/trace.go:171","msg":"trace[1197628419] transaction","detail":"{read_only:false; number_of_response:1; response_revision:546; }","duration":"187.100715ms","start":"2026-08-25T19:05:34.522976Z","end":"2026-08-25T19:05:34.710077Z","steps":["trace[1197628419] 'process raft request' (duration: 186.87436ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.709544Z","caller":"traceutil/trace.go:171","msg":"trace[1832018176] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"192.114647ms","start":"2026-08-25T19:05:34.517335Z","end":"2026-08-25T19:05:34.709449Z","steps":["trace[1832018176] 'process raft request' (duration: 106.99618ms)","trace[1832018176] 'compare' (duration: 84.79635ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:34.710429Z","caller":"traceutil/trace.go:171","msg":"trace[1446806699] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"119.398566ms","start":"2026-08-25T19:05:34.59101Z","end":"2026-08-25T19:05:34.710409Z","steps":["trace[1446806699] 'process raft request' (duration: 119.264893ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.710621Z","caller":"traceutil/trace.go:171","msg":"trace[1803707142] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"127.698541ms","start":"2026-08-25T19:05:34.582904Z","end":"2026-08-25T19:05:34.710602Z","steps":["trace[1803707142] 'process raft request' (duration: 127.203539ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.710651Z","caller":"traceutil/trace.go:171","msg":"trace[104826726] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"133.674633ms","start":"2026-08-25T19:05:34.576955Z","end":"2026-08-25T19:05:34.71063Z","steps":["trace[104826726] 'process raft request' (duration: 133.085089ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.71073Z","caller":"traceutil/trace.go:171","msg":"trace[1421809657] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"186.721618ms","start":"2026-08-25T19:05:34.523991Z","end":"2026-08-25T19:05:34.710713Z","steps":["trace[1421809657] 'process raft request' (duration: 185.94979ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:34.710761Z","caller":"traceutil/trace.go:171","msg":"trace[445940381] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"124.857218ms","start":"2026-08-25T19:05:34.585892Z","end":"2026-08-25T19:05:34.710749Z","steps":["trace[445940381] 'process raft request' (duration: 124.296215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.493263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.133251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:05:35.493368Z","caller":"traceutil/trace.go:171","msg":"trace[431354840] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"842.298487ms","start":"2026-08-25T19:05:34.651052Z","end":"2026-08-25T19:05:35.49335Z","steps":["trace[431354840] 'process raft request' (duration: 742.005322ms)","trace[431354840] 'compare' (duration: 100.007479ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:05:35.493421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.651016Z","time spent":"842.382579ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-25T19:05:35.540598Z","caller":"traceutil/trace.go:171","msg":"trace[1443899414] transaction","detail":"{read_only:false; number_of_response:0; response_revision:552; }","duration":"825.708041ms","start":"2026-08-25T19:05:34.714866Z","end":"2026-08-25T19:05:35.540574Z","steps":["trace[1443899414] 'process raft request' (duration: 825.517557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.540755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.714842Z","time spent":"825.831033ms","remote":"127.0.0.1:37424","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-25T19:05:35.54084Z","caller":"traceutil/trace.go:171","msg":"trace[1975383771] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"809.320147ms","start":"2026-08-25T19:05:34.731505Z","end":"2026-08-25T19:05:35.540825Z","steps":["trace[1975383771] 'process raft request' (duration: 809.133503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.541199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.731484Z","time spent":"809.659914ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:35.541429Z","caller":"traceutil/trace.go:171","msg":"trace[1495135425] transaction","detail":"{read_only:false; number_of_response:0; response_revision:552; }","duration":"824.013822ms","start":"2026-08-25T19:05:34.717406Z","end":"2026-08-25T19:05:35.54142Z","steps":["trace[1495135425] 'process raft request' (duration: 823.062521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.541501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.717387Z","time spent":"824.094715ms","remote":"127.0.0.1:37424","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-25T19:05:35.541953Z","caller":"traceutil/trace.go:171","msg":"trace[1062235261] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"820.169566ms","start":"2026-08-25T19:05:34.721758Z","end":"2026-08-25T19:05:35.541928Z","steps":["trace[1062235261] 'process raft request' (duration: 818.756625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.542025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.721743Z","time spent":"820.244949ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:35.543563Z","caller":"traceutil/trace.go:171","msg":"trace[1792586269] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"808.740104ms","start":"2026-08-25T19:05:34.734788Z","end":"2026-08-25T19:05:35.543528Z","steps":["trace[1792586269] 'process raft request' (duration: 806.017704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.543718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.734765Z","time spent":"808.864987ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:35.543798Z","caller":"traceutil/trace.go:171","msg":"trace[1634935572] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"821.712992ms","start":"2026-08-25T19:05:34.721878Z","end":"2026-08-25T19:05:35.543591Z","steps":["trace[1634935572] 'process raft request' (duration: 818.710405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.544642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.721869Z","time spent":"822.1269ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:35.545449Z","caller":"traceutil/trace.go:171","msg":"trace[1551518986] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:577; }","duration":"144.66624ms","start":"2026-08-25T19:05:35.400754Z","end":"2026-08-25T19:05:35.545421Z","steps":["trace[1551518986] 'read index received' (duration: 139.799931ms)","trace[1551518986] 'applied index is now lower than readState.Index' (duration: 4.865759ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:35.545617Z","caller":"traceutil/trace.go:171","msg":"trace[1175597703] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"784.937397ms","start":"2026-08-25T19:05:34.760658Z","end":"2026-08-25T19:05:35.545596Z","steps":["trace[1175597703] 'process raft request' (duration: 782.288289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.545984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.760625Z","time spent":"785.334526ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:35.545728Z","caller":"traceutil/trace.go:171","msg":"trace[1620377855] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"714.640119ms","start":"2026-08-25T19:05:34.831079Z","end":"2026-08-25T19:05:35.54572Z","steps":["trace[1620377855] 'process raft request' (duration: 714.310892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.546078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:34.831051Z","time spent":"715.010907ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:05:35.545772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.024257ms","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-08-25T19:05:35.546153Z","caller":"traceutil/trace.go:171","msg":"trace[1891308375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"145.451546ms","start":"2026-08-25T19:05:35.400693Z","end":"2026-08-25T19:05:35.546145Z","steps":["trace[1891308375] 'agreement among raft nodes before linearized reading' (duration: 145.051428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.555229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.163344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-25T19:05:35.555282Z","caller":"traceutil/trace.go:171","msg":"trace[609929632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"138.248406ms","start":"2026-08-25T19:05:35.41702Z","end":"2026-08-25T19:05:35.555268Z","steps":["trace[609929632] 'agreement among raft nodes before linearized reading' (duration: 138.145814ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:35.736293Z","caller":"traceutil/trace.go:171","msg":"trace[50781325] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"178.385477ms","start":"2026-08-25T19:05:35.557879Z","end":"2026-08-25T19:05:35.736264Z","steps":["trace[50781325] 'process raft request' (duration: 84.33605ms)","trace[50781325] 'compare' (duration: 93.830112ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:35.736317Z","caller":"traceutil/trace.go:171","msg":"trace[1559027386] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"178.361876ms","start":"2026-08-25T19:05:35.557932Z","end":"2026-08-25T19:05:35.736294Z","steps":["trace[1559027386] 'process raft request' (duration: 178.242334ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:35.736466Z","caller":"traceutil/trace.go:171","msg":"trace[1215536731] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"176.168168ms","start":"2026-08-25T19:05:35.560274Z","end":"2026-08-25T19:05:35.736442Z","steps":["trace[1215536731] 'process raft request' (duration: 175.956533ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:35.736747Z","caller":"traceutil/trace.go:171","msg":"trace[1771614143] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"172.878115ms","start":"2026-08-25T19:05:35.563856Z","end":"2026-08-25T19:05:35.736734Z","steps":["trace[1771614143] 'process raft request' (duration: 172.511327ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:35.736734Z","caller":"traceutil/trace.go:171","msg":"trace[1923151075] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"172.816023ms","start":"2026-08-25T19:05:35.563894Z","end":"2026-08-25T19:05:35.73671Z","steps":["trace[1923151075] 'process raft request' (duration: 172.719881ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:35.752306Z","caller":"traceutil/trace.go:171","msg":"trace[162400314] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:585; }","duration":"144.205039ms","start":"2026-08-25T19:05:35.608083Z","end":"2026-08-25T19:05:35.752288Z","steps":["trace[162400314] 'read index received' (duration: 34.216699ms)","trace[162400314] 'applied index is now lower than readState.Index' (duration: 109.98715ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:35.752398Z","caller":"traceutil/trace.go:171","msg":"trace[942812528] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"145.712152ms","start":"2026-08-25T19:05:35.606665Z","end":"2026-08-25T19:05:35.752377Z","steps":["trace[942812528] 'process raft request' (duration: 145.506547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:35.752664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.562517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:05:35.752713Z","caller":"traceutil/trace.go:171","msg":"trace[396268839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"144.641759ms","start":"2026-08-25T19:05:35.608058Z","end":"2026-08-25T19:05:35.752699Z","steps":["trace[396268839] 'agreement among raft nodes before linearized reading' (duration: 144.29783ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:35.886862Z","caller":"traceutil/trace.go:171","msg":"trace[2062294911] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"133.358157ms","start":"2026-08-25T19:05:35.753479Z","end":"2026-08-25T19:05:35.886838Z","steps":["trace[2062294911] 'read index received' (duration: 85.266661ms)","trace[2062294911] 'applied index is now lower than readState.Index' (duration: 48.089386ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:05:35.886998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.490441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:05:35.887068Z","caller":"traceutil/trace.go:171","msg":"trace[1827997400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"133.607124ms","start":"2026-08-25T19:05:35.753445Z","end":"2026-08-25T19:05:35.887052Z","steps":["trace[1827997400] 'agreement among raft nodes before linearized reading' (duration: 133.48399ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:35.887171Z","caller":"traceutil/trace.go:171","msg":"trace[1479238718] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"138.351539ms","start":"2026-08-25T19:05:35.748519Z","end":"2026-08-25T19:05:35.88687Z","steps":["trace[1479238718] 'process raft request' (duration: 89.936725ms)","trace[1479238718] 'compare' (duration: 48.258821ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:35.939787Z","caller":"traceutil/trace.go:171","msg":"trace[1860225801] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"186.21802ms","start":"2026-08-25T19:05:35.753548Z","end":"2026-08-25T19:05:35.939766Z","steps":["trace[1860225801] 'process raft request' (duration: 185.928144ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:35.940222Z","caller":"traceutil/trace.go:171","msg":"trace[332723516] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"134.505665ms","start":"2026-08-25T19:05:35.805679Z","end":"2026-08-25T19:05:35.940185Z","steps":["trace[332723516] 'process raft request' (duration: 134.021483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:36.24521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.63738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:05:36.245313Z","caller":"traceutil/trace.go:171","msg":"trace[1334958913] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"291.753226ms","start":"2026-08-25T19:05:35.953544Z","end":"2026-08-25T19:05:36.245297Z","steps":["trace[1334958913] 'process raft request' (duration: 124.919072ms)","trace[1334958913] 'compare' (duration: 166.481166ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:36.246466Z","caller":"traceutil/trace.go:171","msg":"trace[614956261] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"227.926641ms","start":"2026-08-25T19:05:36.018508Z","end":"2026-08-25T19:05:36.246435Z","steps":["trace[614956261] 'process raft request' (duration: 227.853849ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:36.246892Z","caller":"traceutil/trace.go:171","msg":"trace[1551969420] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"250.338447ms","start":"2026-08-25T19:05:35.996533Z","end":"2026-08-25T19:05:36.246871Z","steps":["trace[1551969420] 'process raft request' (duration: 249.618022ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:36.478996Z","caller":"traceutil/trace.go:171","msg":"trace[1407666200] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"201.381291ms","start":"2026-08-25T19:05:36.277589Z","end":"2026-08-25T19:05:36.47897Z","steps":["trace[1407666200] 'process raft request' (duration: 201.32674ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:36.479153Z","caller":"traceutil/trace.go:171","msg":"trace[1244732614] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"216.182319ms","start":"2026-08-25T19:05:36.262948Z","end":"2026-08-25T19:05:36.47913Z","steps":["trace[1244732614] 'process raft request' (duration: 144.869635ms)","trace[1244732614] 'compare' (duration: 70.940146ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:36.479243Z","caller":"traceutil/trace.go:171","msg":"trace[137505481] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"126.074519ms","start":"2026-08-25T19:05:36.353141Z","end":"2026-08-25T19:05:36.479216Z","steps":["trace[137505481] 'process raft request' (duration: 125.928546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:36.753504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.001781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:05:36.753609Z","caller":"traceutil/trace.go:171","msg":"trace[1317211283] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"238.900394ms","start":"2026-08-25T19:05:36.51469Z","end":"2026-08-25T19:05:36.753591Z","steps":["trace[1317211283] 'process raft request' (duration: 106.726979ms)","trace[1317211283] 'compare' (duration: 131.853888ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:36.768451Z","caller":"traceutil/trace.go:171","msg":"trace[778367110] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"170.198319ms","start":"2026-08-25T19:05:36.598236Z","end":"2026-08-25T19:05:36.768434Z","steps":["trace[778367110] 'process raft request' (duration: 170.076566ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:36.99749Z","caller":"traceutil/trace.go:171","msg":"trace[2028941127] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"190.416178ms","start":"2026-08-25T19:05:36.807043Z","end":"2026-08-25T19:05:36.997459Z","steps":["trace[2028941127] 'process raft request' (duration: 127.627134ms)","trace[2028941127] 'compare' (duration: 62.60982ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:37.011381Z","caller":"traceutil/trace.go:171","msg":"trace[786597861] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"102.417074ms","start":"2026-08-25T19:05:36.908932Z","end":"2026-08-25T19:05:37.011349Z","steps":["trace[786597861] 'process raft request' (duration: 102.371213ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:37.011422Z","caller":"traceutil/trace.go:171","msg":"trace[1921755561] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"151.964443ms","start":"2026-08-25T19:05:36.859416Z","end":"2026-08-25T19:05:37.01138Z","steps":["trace[1921755561] 'process raft request' (duration: 151.684877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:42.020996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.716355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-25T19:05:42.021109Z","caller":"traceutil/trace.go:171","msg":"trace[740540170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"261.861508ms","start":"2026-08-25T19:05:41.759221Z","end":"2026-08-25T19:05:42.021082Z","steps":["trace[740540170] 'range keys from in-memory index tree' (duration: 261.540821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:42.021462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.022794ms","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-08-25T19:05:42.02151Z","caller":"traceutil/trace.go:171","msg":"trace[1944152555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"263.098366ms","start":"2026-08-25T19:05:41.758399Z","end":"2026-08-25T19:05:42.021497Z","steps":["trace[1944152555] 'range keys from in-memory index tree' (duration: 262.905742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:42.021895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.459758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-25T19:05:42.021929Z","caller":"traceutil/trace.go:171","msg":"trace[1905038456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"256.521399ms","start":"2026-08-25T19:05:41.7654Z","end":"2026-08-25T19:05:42.021921Z","steps":["trace[1905038456] 'range keys from in-memory index tree' (duration: 256.381936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:42.022205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.18596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:05:42.022346Z","caller":"traceutil/trace.go:171","msg":"trace[702501417] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"185.045055ms","start":"2026-08-25T19:05:41.837281Z","end":"2026-08-25T19:05:42.022326Z","steps":["trace[702501417] 'process raft request' (duration: 25.655131ms)","trace[702501417] 'compare' (duration: 158.864552ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:05:42.400368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.679345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:05:42.400533Z","caller":"traceutil/trace.go:171","msg":"trace[1367642457] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"375.07333ms","start":"2026-08-25T19:05:42.025433Z","end":"2026-08-25T19:05:42.400506Z","steps":["trace[1367642457] 'process raft request' (duration: 141.121079ms)","trace[1367642457] 'compare' (duration: 233.444289ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:05:42.400622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:42.025413Z","time spent":"375.163371ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:42.47022Z","caller":"traceutil/trace.go:171","msg":"trace[2102926408] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"443.068478ms","start":"2026-08-25T19:05:42.027121Z","end":"2026-08-25T19:05:42.47019Z","steps":["trace[2102926408] 'process raft request' (duration: 442.853192ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:42.470348Z","caller":"traceutil/trace.go:171","msg":"trace[1351638581] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"437.827261ms","start":"2026-08-25T19:05:42.032487Z","end":"2026-08-25T19:05:42.470314Z","steps":["trace[1351638581] 'process raft request' (duration: 437.616765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:42.470479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:42.027104Z","time spent":"443.216161ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:05:42.470619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:42.032469Z","time spent":"438.052686ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:05:42.472159Z","caller":"traceutil/trace.go:171","msg":"trace[995085956] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"392.276374ms","start":"2026-08-25T19:05:42.079863Z","end":"2026-08-25T19:05:42.472139Z","steps":["trace[995085956] 'process raft request' (duration: 391.100788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:42.47226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:42.079828Z","time spent":"392.378666ms","remote":"127.0.0.1:36990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:05:43.072817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.727182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:05:43.072909Z","caller":"traceutil/trace.go:171","msg":"trace[1992370478] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"472.648938ms","start":"2026-08-25T19:05:42.600246Z","end":"2026-08-25T19:05:43.072895Z","steps":["trace[1992370478] 'read index received' (duration: 31.191µs)","trace[1992370478] 'applied index is now lower than readState.Index' (duration: 472.616727ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:43.072976Z","caller":"traceutil/trace.go:171","msg":"trace[1642245450] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"543.865828ms","start":"2026-08-25T19:05:42.529083Z","end":"2026-08-25T19:05:43.072949Z","steps":["trace[1642245450] 'process raft request' (duration: 65.935721ms)","trace[1642245450] 'compare' (duration: 477.61481ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:05:43.073041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.788701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fgtfn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-25T19:05:43.07307Z","caller":"traceutil/trace.go:171","msg":"trace[924867710] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fgtfn; range_end:; response_count:1; response_revision:631; }","duration":"472.850923ms","start":"2026-08-25T19:05:42.600212Z","end":"2026-08-25T19:05:43.073063Z","steps":["trace[924867710] 'agreement among raft nodes before linearized reading' (duration: 472.743171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:43.073075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:42.529056Z","time spent":"543.970839ms","remote":"127.0.0.1:37246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-25T19:05:43.073093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:05:42.600195Z","time spent":"472.891694ms","remote":"127.0.0.1:37102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-fgtfn\" "} {"level":"warn","ts":"2026-08-25T19:05:43.07325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.880252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.136\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-25T19:05:43.073326Z","caller":"traceutil/trace.go:171","msg":"trace[197746661] range","detail":"{range_begin:/registry/masterleases/162.253.55.136; range_end:; response_count:1; response_revision:631; }","duration":"173.997713ms","start":"2026-08-25T19:05:42.89931Z","end":"2026-08-25T19:05:43.073308Z","steps":["trace[197746661] 'agreement among raft nodes before linearized reading' (duration: 173.769458ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:43.331687Z","caller":"traceutil/trace.go:171","msg":"trace[1014670569] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"245.317879ms","start":"2026-08-25T19:05:43.086344Z","end":"2026-08-25T19:05:43.331662Z","steps":["trace[1014670569] 'read index received' (duration: 216.761801ms)","trace[1014670569] 'applied index is now lower than readState.Index' (duration: 28.554948ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:05:43.33179Z","caller":"traceutil/trace.go:171","msg":"trace[1155561696] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"248.722015ms","start":"2026-08-25T19:05:43.083008Z","end":"2026-08-25T19:05:43.33173Z","steps":["trace[1155561696] 'process raft request' (duration: 248.49497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:05:43.331875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.497813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:05:43.331989Z","caller":"traceutil/trace.go:171","msg":"trace[453967700] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:632; }","duration":"245.644137ms","start":"2026-08-25T19:05:43.086326Z","end":"2026-08-25T19:05:43.33197Z","steps":["trace[453967700] 'agreement among raft nodes before linearized reading' (duration: 245.437942ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:44.964808Z","caller":"traceutil/trace.go:171","msg":"trace[168182560] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"170.530361ms","start":"2026-08-25T19:05:44.794253Z","end":"2026-08-25T19:05:44.964784Z","steps":["trace[168182560] 'process raft request' (duration: 166.972572ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:44.967563Z","caller":"traceutil/trace.go:171","msg":"trace[1528422191] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"129.554766ms","start":"2026-08-25T19:05:44.837978Z","end":"2026-08-25T19:05:44.967533Z","steps":["trace[1528422191] 'process raft request' (duration: 129.437473ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:05:44.967636Z","caller":"traceutil/trace.go:171","msg":"trace[1539570651] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"170.950151ms","start":"2026-08-25T19:05:44.796662Z","end":"2026-08-25T19:05:44.967612Z","steps":["trace[1539570651] 'process raft request' (duration: 170.645794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:06:28.595045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.830791ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-25T19:06:28.595178Z","caller":"traceutil/trace.go:171","msg":"trace[1416813258] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"240.323055ms","start":"2026-08-25T19:06:28.354833Z","end":"2026-08-25T19:06:28.595156Z","steps":["trace[1416813258] 'read index received' (duration: 80.247438ms)","trace[1416813258] 'applied index is now lower than readState.Index' (duration: 160.073877ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:06:28.595264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.417737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:06:28.595293Z","caller":"traceutil/trace.go:171","msg":"trace[891367726] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1201; }","duration":"240.494729ms","start":"2026-08-25T19:06:28.354788Z","end":"2026-08-25T19:06:28.595283Z","steps":["trace[891367726] 'agreement among raft nodes before linearized reading' (duration: 240.418737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:06:49.29879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.27866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-25T19:06:49.298874Z","caller":"traceutil/trace.go:171","msg":"trace[977165040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1460; }","duration":"178.424494ms","start":"2026-08-25T19:06:49.120431Z","end":"2026-08-25T19:06:49.298856Z","steps":["trace[977165040] 'range keys from in-memory index tree' (duration: 178.065226ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:06:49.434053Z","caller":"traceutil/trace.go:171","msg":"trace[1501938123] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"130.430797ms","start":"2026-08-25T19:06:49.303596Z","end":"2026-08-25T19:06:49.434027Z","steps":["trace[1501938123] 'process raft request' (duration: 130.261043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:07:13.034418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.124597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.136\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-25T19:07:13.034418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.650351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-25T19:07:13.034492Z","caller":"traceutil/trace.go:171","msg":"trace[10604139] range","detail":"{range_begin:/registry/masterleases/162.253.55.136; range_end:; response_count:1; response_revision:1857; }","duration":"130.238559ms","start":"2026-08-25T19:07:12.90424Z","end":"2026-08-25T19:07:13.034478Z","steps":["trace[10604139] 'range keys from in-memory index tree' (duration: 129.997073ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:13.0345Z","caller":"traceutil/trace.go:171","msg":"trace[1381426884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1857; }","duration":"112.780425ms","start":"2026-08-25T19:07:12.921705Z","end":"2026-08-25T19:07:13.034485Z","steps":["trace[1381426884] 'range keys from in-memory index tree' (duration: 112.421446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:07:13.034533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.568635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-25T19:07:13.034633Z","caller":"traceutil/trace.go:171","msg":"trace[1981375615] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1857; }","duration":"167.713759ms","start":"2026-08-25T19:07:12.866899Z","end":"2026-08-25T19:07:13.034613Z","steps":["trace[1981375615] 'range keys from in-memory index tree' (duration: 167.286079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:07:13.034813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.288686ms","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-08-25T19:07:13.034949Z","caller":"traceutil/trace.go:171","msg":"trace[1970350466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1857; }","duration":"185.48759ms","start":"2026-08-25T19:07:12.849432Z","end":"2026-08-25T19:07:13.03492Z","steps":["trace[1970350466] 'range keys from in-memory index tree' (duration: 185.002079ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:13.212456Z","caller":"traceutil/trace.go:171","msg":"trace[1545396985] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"189.788701ms","start":"2026-08-25T19:07:13.022639Z","end":"2026-08-25T19:07:13.212428Z","steps":["trace[1545396985] 'process raft request' (duration: 189.566335ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:13.284688Z","caller":"traceutil/trace.go:171","msg":"trace[1895070345] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"246.175158ms","start":"2026-08-25T19:07:13.03849Z","end":"2026-08-25T19:07:13.284665Z","steps":["trace[1895070345] 'process raft request' (duration: 246.040585ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:13.284925Z","caller":"traceutil/trace.go:171","msg":"trace[680044557] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"244.776035ms","start":"2026-08-25T19:07:13.040121Z","end":"2026-08-25T19:07:13.284897Z","steps":["trace[680044557] 'process raft request' (duration: 244.501609ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:13.284893Z","caller":"traceutil/trace.go:171","msg":"trace[385387144] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"245.923032ms","start":"2026-08-25T19:07:13.038911Z","end":"2026-08-25T19:07:13.284834Z","steps":["trace[385387144] 'process raft request' (duration: 245.659756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:07:13.284872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.128216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9f3fcfc-4a68-477b-9a00-eecb99e205c0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-25T19:07:13.28505Z","caller":"traceutil/trace.go:171","msg":"trace[748348522] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9f3fcfc-4a68-477b-9a00-eecb99e205c0; range_end:; response_count:1; response_revision:1862; }","duration":"202.365031ms","start":"2026-08-25T19:07:13.082674Z","end":"2026-08-25T19:07:13.285039Z","steps":["trace[748348522] 'agreement among raft nodes before linearized reading' (duration: 202.057434ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:13.28496Z","caller":"traceutil/trace.go:171","msg":"trace[1716516641] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"246.602557ms","start":"2026-08-25T19:07:13.038347Z","end":"2026-08-25T19:07:13.284949Z","steps":["trace[1716516641] 'process raft request' (duration: 246.072475ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:13.284687Z","caller":"traceutil/trace.go:171","msg":"trace[1701129686] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1929; }","duration":"201.934742ms","start":"2026-08-25T19:07:13.082728Z","end":"2026-08-25T19:07:13.284663Z","steps":["trace[1701129686] 'read index received' (duration: 129.508232ms)","trace[1701129686] 'applied index is now lower than readState.Index' (duration: 72.42529ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:07:28.777653Z","caller":"traceutil/trace.go:171","msg":"trace[638464083] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"167.126765ms","start":"2026-08-25T19:07:28.610506Z","end":"2026-08-25T19:07:28.777633Z","steps":["trace[638464083] 'read index received' (duration: 167.008332ms)","trace[638464083] 'applied index is now lower than readState.Index' (duration: 117.763µs)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:07:28.77775Z","caller":"traceutil/trace.go:171","msg":"trace[1854927003] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"209.791507ms","start":"2026-08-25T19:07:28.567926Z","end":"2026-08-25T19:07:28.777717Z","steps":["trace[1854927003] 'process raft request' (duration: 209.538871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:07:28.777804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.278199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:07:28.777833Z","caller":"traceutil/trace.go:171","msg":"trace[1274013622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1986; }","duration":"167.35251ms","start":"2026-08-25T19:07:28.610472Z","end":"2026-08-25T19:07:28.777825Z","steps":["trace[1274013622] 'agreement among raft nodes before linearized reading' (duration: 167.254048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:07:28.778964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.694234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-25T19:07:28.779052Z","caller":"traceutil/trace.go:171","msg":"trace[536955161] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1987; }","duration":"135.816407ms","start":"2026-08-25T19:07:28.643212Z","end":"2026-08-25T19:07:28.779028Z","steps":["trace[536955161] 'agreement among raft nodes before linearized reading' (duration: 135.588301ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:46.48721Z","caller":"traceutil/trace.go:171","msg":"trace[199373897] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"115.402169ms","start":"2026-08-25T19:07:46.371494Z","end":"2026-08-25T19:07:46.486896Z","steps":["trace[199373897] 'process raft request' (duration: 114.250972ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:51.90839Z","caller":"traceutil/trace.go:171","msg":"trace[1263130277] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"116.443664ms","start":"2026-08-25T19:07:51.791905Z","end":"2026-08-25T19:07:51.908349Z","steps":["trace[1263130277] 'process raft request' (duration: 116.295541ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:53.159587Z","caller":"traceutil/trace.go:171","msg":"trace[1731472603] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2214; }","duration":"252.843213ms","start":"2026-08-25T19:07:52.906697Z","end":"2026-08-25T19:07:53.15954Z","steps":["trace[1731472603] 'read index received' (duration: 237.085687ms)","trace[1731472603] 'applied index is now lower than readState.Index' (duration: 15.755606ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:07:53.160048Z","caller":"traceutil/trace.go:171","msg":"trace[810493518] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"308.455392ms","start":"2026-08-25T19:07:52.851568Z","end":"2026-08-25T19:07:53.160023Z","steps":["trace[810493518] 'process raft request' (duration: 292.277604ms)","trace[810493518] 'compare' (duration: 15.490011ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:07:53.16026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:07:52.851548Z","time spent":"308.562444ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:07:53.160529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.814387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.136\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-25T19:07:53.160648Z","caller":"traceutil/trace.go:171","msg":"trace[1512663359] range","detail":"{range_begin:/registry/masterleases/162.253.55.136; range_end:; response_count:1; response_revision:2134; }","duration":"253.991661ms","start":"2026-08-25T19:07:52.906634Z","end":"2026-08-25T19:07:53.160625Z","steps":["trace[1512663359] 'agreement among raft nodes before linearized reading' (duration: 253.664724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:07:53.160815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.926137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-25T19:07:53.160969Z","caller":"traceutil/trace.go:171","msg":"trace[465839782] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2134; }","duration":"220.06734ms","start":"2026-08-25T19:07:52.94082Z","end":"2026-08-25T19:07:53.160888Z","steps":["trace[465839782] 'agreement among raft nodes before linearized reading' (duration: 219.455116ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:53.600774Z","caller":"traceutil/trace.go:171","msg":"trace[1013763383] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2217; }","duration":"132.201152ms","start":"2026-08-25T19:07:53.468545Z","end":"2026-08-25T19:07:53.600746Z","steps":["trace[1013763383] 'read index received' (duration: 33.671525ms)","trace[1013763383] 'applied index is now lower than readState.Index' (duration: 98.528287ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:07:53.600858Z","caller":"traceutil/trace.go:171","msg":"trace[772170822] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"260.582615ms","start":"2026-08-25T19:07:53.340217Z","end":"2026-08-25T19:07:53.6008Z","steps":["trace[772170822] 'process raft request' (duration: 162.017488ms)","trace[772170822] 'compare' (duration: 98.341442ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:07:53.602719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.145138ms","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-08-25T19:07:53.602838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.576302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-25T19:07:53.602936Z","caller":"traceutil/trace.go:171","msg":"trace[892126343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2136; }","duration":"132.737415ms","start":"2026-08-25T19:07:53.470185Z","end":"2026-08-25T19:07:53.602922Z","steps":["trace[892126343] 'agreement among raft nodes before linearized reading' (duration: 132.534161ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:07:53.602937Z","caller":"traceutil/trace.go:171","msg":"trace[855564390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2136; }","duration":"134.380483ms","start":"2026-08-25T19:07:53.468507Z","end":"2026-08-25T19:07:53.602887Z","steps":["trace[855564390] 'agreement among raft nodes before linearized reading' (duration: 132.338305ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:08:53.589589Z","caller":"traceutil/trace.go:171","msg":"trace[1647421878] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"228.32999ms","start":"2026-08-25T19:08:53.360613Z","end":"2026-08-25T19:08:53.588942Z","steps":["trace[1647421878] 'process raft request' (duration: 228.119036ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:09:25.846997Z","caller":"traceutil/trace.go:171","msg":"trace[1380313328] linearizableReadLoop","detail":"{readStateIndex:2837; appliedIndex:2836; }","duration":"108.985217ms","start":"2026-08-25T19:09:25.737992Z","end":"2026-08-25T19:09:25.846977Z","steps":["trace[1380313328] 'read index received' (duration: 108.828404ms)","trace[1380313328] 'applied index is now lower than readState.Index' (duration: 156.043µs)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:09:25.847156Z","caller":"traceutil/trace.go:171","msg":"trace[2059488147] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"138.529888ms","start":"2026-08-25T19:09:25.708583Z","end":"2026-08-25T19:09:25.847113Z","steps":["trace[2059488147] 'process raft request' (duration: 138.295003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:09:25.84732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.303574ms","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-08-25T19:09:25.847362Z","caller":"traceutil/trace.go:171","msg":"trace[1847525123] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2734; }","duration":"109.367765ms","start":"2026-08-25T19:09:25.737985Z","end":"2026-08-25T19:09:25.847353Z","steps":["trace[1847525123] 'agreement among raft nodes before linearized reading' (duration: 109.068039ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:09:31.171577Z","caller":"traceutil/trace.go:171","msg":"trace[1396226424] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"152.890978ms","start":"2026-08-25T19:09:31.01866Z","end":"2026-08-25T19:09:31.171551Z","steps":["trace[1396226424] 'process raft request' (duration: 152.760775ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:15.707207Z","caller":"traceutil/trace.go:171","msg":"trace[945561827] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"170.381515ms","start":"2026-08-25T19:13:15.5368Z","end":"2026-08-25T19:13:15.707182Z","steps":["trace[945561827] 'process raft request' (duration: 170.176159ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:15.707182Z","caller":"traceutil/trace.go:171","msg":"trace[401706518] linearizableReadLoop","detail":"{readStateIndex:4848; appliedIndex:4847; }","duration":"152.405688ms","start":"2026-08-25T19:13:15.554749Z","end":"2026-08-25T19:13:15.707155Z","steps":["trace[401706518] 'read index received' (duration: 152.150092ms)","trace[401706518] 'applied index is now lower than readState.Index' (duration: 254.586µs)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:13:15.707322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.549782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:13:15.707378Z","caller":"traceutil/trace.go:171","msg":"trace[265098486] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4672; }","duration":"152.672064ms","start":"2026-08-25T19:13:15.554691Z","end":"2026-08-25T19:13:15.707364Z","steps":["trace[265098486] 'agreement among raft nodes before linearized reading' (duration: 152.555372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:15.978099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.79202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7689bff-6xxx9.18cf22368fbe5cf5\" ","response":"range_response_count:1 size:889"} {"level":"info","ts":"2026-08-25T19:13:15.978218Z","caller":"traceutil/trace.go:171","msg":"trace[1812562485] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7689bff-6xxx9.18cf22368fbe5cf5; range_end:; response_count:1; response_revision:4672; }","duration":"141.952704ms","start":"2026-08-25T19:13:15.836241Z","end":"2026-08-25T19:13:15.978193Z","steps":["trace[1812562485] 'range keys from in-memory index tree' (duration: 141.632077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:15.978225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.679311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:13:15.978302Z","caller":"traceutil/trace.go:171","msg":"trace[200227337] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4672; }","duration":"179.801283ms","start":"2026-08-25T19:13:15.798469Z","end":"2026-08-25T19:13:15.97827Z","steps":["trace[200227337] 'count revisions from in-memory index tree' (duration: 179.579728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:15.978501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.399034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0504866a-6b2d-4c90-a700-f37d81da6708\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-25T19:13:15.978563Z","caller":"traceutil/trace.go:171","msg":"trace[1506237433] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0504866a-6b2d-4c90-a700-f37d81da6708; range_end:; response_count:1; response_revision:4672; }","duration":"142.467386ms","start":"2026-08-25T19:13:15.836079Z","end":"2026-08-25T19:13:15.978546Z","steps":["trace[1506237433] 'range keys from in-memory index tree' (duration: 142.23362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:18.373405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.086152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-08-25T19:13:18.373497Z","caller":"traceutil/trace.go:171","msg":"trace[639024505] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4720; }","duration":"112.253756ms","start":"2026-08-25T19:13:18.261222Z","end":"2026-08-25T19:13:18.373476Z","steps":["trace[639024505] 'range keys from in-memory index tree' (duration: 110.794603ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:19.855922Z","caller":"traceutil/trace.go:171","msg":"trace[136111993] linearizableReadLoop","detail":"{readStateIndex:4930; appliedIndex:4929; }","duration":"122.677236ms","start":"2026-08-25T19:13:19.733196Z","end":"2026-08-25T19:13:19.855874Z","steps":["trace[136111993] 'read index received' (duration: 122.386729ms)","trace[136111993] 'applied index is now lower than readState.Index' (duration: 289.017µs)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:13:19.855951Z","caller":"traceutil/trace.go:171","msg":"trace[1339578522] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"129.654129ms","start":"2026-08-25T19:13:19.726262Z","end":"2026-08-25T19:13:19.855916Z","steps":["trace[1339578522] 'process raft request' (duration: 129.431064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:19.856444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.22807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0504866a-6b2d-4c90-a700-f37d81da6708\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-08-25T19:13:19.856788Z","caller":"traceutil/trace.go:171","msg":"trace[69140176] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0504866a-6b2d-4c90-a700-f37d81da6708; range_end:; response_count:1; response_revision:4751; }","duration":"123.348953ms","start":"2026-08-25T19:13:19.733135Z","end":"2026-08-25T19:13:19.856484Z","steps":["trace[69140176] 'agreement among raft nodes before linearized reading' (duration: 122.916043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.244593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.882561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:13:29.244703Z","caller":"traceutil/trace.go:171","msg":"trace[508322060] linearizableReadLoop","detail":"{readStateIndex:5054; appliedIndex:5053; }","duration":"262.606029ms","start":"2026-08-25T19:13:28.98208Z","end":"2026-08-25T19:13:29.244686Z","steps":["trace[508322060] 'read index received' (duration: 16.461273ms)","trace[508322060] 'applied index is now lower than readState.Index' (duration: 246.143596ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:13:29.244755Z","caller":"traceutil/trace.go:171","msg":"trace[999876931] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"328.985229ms","start":"2026-08-25T19:13:28.915744Z","end":"2026-08-25T19:13:29.244729Z","steps":["trace[999876931] 'process raft request' (duration: 82.864554ms)","trace[999876931] 'compare' (duration: 245.775527ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:13:29.244882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.801024ms","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-08-25T19:13:29.244916Z","caller":"traceutil/trace.go:171","msg":"trace[894811463] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4870; }","duration":"262.876175ms","start":"2026-08-25T19:13:28.982032Z","end":"2026-08-25T19:13:29.244908Z","steps":["trace[894811463] 'agreement among raft nodes before linearized reading' (duration: 262.744152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.244949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:28.915723Z","time spent":"329.137033ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:13:29.245172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.145694ms","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-08-25T19:13:29.245218Z","caller":"traceutil/trace.go:171","msg":"trace[885773092] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4870; }","duration":"261.205356ms","start":"2026-08-25T19:13:28.984003Z","end":"2026-08-25T19:13:29.245208Z","steps":["trace[885773092] 'agreement among raft nodes before linearized reading' (duration: 261.104444ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:29.566179Z","caller":"traceutil/trace.go:171","msg":"trace[373779699] linearizableReadLoop","detail":"{readStateIndex:5055; appliedIndex:5054; }","duration":"272.811206ms","start":"2026-08-25T19:13:29.29334Z","end":"2026-08-25T19:13:29.566152Z","steps":["trace[373779699] 'read index received' (duration: 195.281915ms)","trace[373779699] 'applied index is now lower than readState.Index' (duration: 77.528381ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:13:29.56633Z","caller":"traceutil/trace.go:171","msg":"trace[1165913832] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"311.219728ms","start":"2026-08-25T19:13:29.25509Z","end":"2026-08-25T19:13:29.566309Z","steps":["trace[1165913832] 'process raft request' (duration: 233.521253ms)","trace[1165913832] 'compare' (duration: 77.175303ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:13:29.56678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.255066Z","time spent":"311.36049ms","remote":"127.0.0.1:37102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:13:29.566874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.472805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-08-25T19:13:29.566923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.583994ms","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-08-25T19:13:29.56694Z","caller":"traceutil/trace.go:171","msg":"trace[1313260045] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4871; }","duration":"110.583728ms","start":"2026-08-25T19:13:29.456339Z","end":"2026-08-25T19:13:29.566922Z","steps":["trace[1313260045] 'agreement among raft nodes before linearized reading' (duration: 110.433924ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:29.566968Z","caller":"traceutil/trace.go:171","msg":"trace[228034150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4871; }","duration":"273.676706ms","start":"2026-08-25T19:13:29.293279Z","end":"2026-08-25T19:13:29.566955Z","steps":["trace[228034150] 'agreement among raft nodes before linearized reading' (duration: 273.496842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.567113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.32513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-25T19:13:29.567135Z","caller":"traceutil/trace.go:171","msg":"trace[2146779190] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4871; }","duration":"103.384151ms","start":"2026-08-25T19:13:29.463744Z","end":"2026-08-25T19:13:29.567128Z","steps":["trace[2146779190] 'agreement among raft nodes before linearized reading' (duration: 103.33804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.567347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.495609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-08-25T19:13:29.567394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.750809ms","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-08-25T19:13:29.567417Z","caller":"traceutil/trace.go:171","msg":"trace[111407677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4871; }","duration":"221.836782ms","start":"2026-08-25T19:13:29.345573Z","end":"2026-08-25T19:13:29.56741Z","steps":["trace[111407677] 'agreement among raft nodes before linearized reading' (duration: 221.77505ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:29.567406Z","caller":"traceutil/trace.go:171","msg":"trace[1885515653] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4871; }","duration":"114.602091ms","start":"2026-08-25T19:13:29.452784Z","end":"2026-08-25T19:13:29.567386Z","steps":["trace[1885515653] 'agreement among raft nodes before linearized reading' (duration: 114.458068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.566874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.628008ms","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-08-25T19:13:29.5727Z","caller":"traceutil/trace.go:171","msg":"trace[1544046714] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4871; }","duration":"277.469964ms","start":"2026-08-25T19:13:29.295205Z","end":"2026-08-25T19:13:29.572675Z","steps":["trace[1544046714] 'agreement among raft nodes before linearized reading' (duration: 271.534336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.95183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.177617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:13:29.952176Z","caller":"traceutil/trace.go:171","msg":"trace[1278485123] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"378.096951ms","start":"2026-08-25T19:13:29.574054Z","end":"2026-08-25T19:13:29.952151Z","steps":["trace[1278485123] 'process raft request' (duration: 377.877955ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:29.952153Z","caller":"traceutil/trace.go:171","msg":"trace[495883090] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"378.106241ms","start":"2026-08-25T19:13:29.573914Z","end":"2026-08-25T19:13:29.95202Z","steps":["trace[495883090] 'process raft request' (duration: 100.636678ms)","trace[495883090] 'compare' (duration: 277.060814ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:13:29.952392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.574042Z","time spent":"378.198613ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:13:29.952531Z","caller":"traceutil/trace.go:171","msg":"trace[470184016] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"377.939977ms","start":"2026-08-25T19:13:29.574451Z","end":"2026-08-25T19:13:29.952391Z","steps":["trace[470184016] 'process raft request' (duration: 377.538637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.952835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.573889Z","time spent":"378.49851ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:13:29.953004Z","caller":"traceutil/trace.go:171","msg":"trace[1451253220] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5056; }","duration":"342.439272ms","start":"2026-08-25T19:13:29.610552Z","end":"2026-08-25T19:13:29.952991Z","steps":["trace[1451253220] 'read index received' (duration: 64.076097ms)","trace[1451253220] 'applied index is now lower than readState.Index' (duration: 278.362345ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:13:29.953014Z","caller":"traceutil/trace.go:171","msg":"trace[1296602359] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"370.961025ms","start":"2026-08-25T19:13:29.581989Z","end":"2026-08-25T19:13:29.95295Z","steps":["trace[1296602359] 'process raft request' (duration: 370.048173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.953111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.553845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:13:29.953138Z","caller":"traceutil/trace.go:171","msg":"trace[2036725575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4879; }","duration":"342.617056ms","start":"2026-08-25T19:13:29.610512Z","end":"2026-08-25T19:13:29.953129Z","steps":["trace[2036725575] 'agreement among raft nodes before linearized reading' (duration: 342.568885ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:29.953131Z","caller":"traceutil/trace.go:171","msg":"trace[1914196583] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"369.49051ms","start":"2026-08-25T19:13:29.583596Z","end":"2026-08-25T19:13:29.953087Z","steps":["trace[1914196583] 'process raft request' (duration: 369.358727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.953167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.610483Z","time spent":"342.677227ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-25T19:13:29.953493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.583588Z","time spent":"369.798157ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:13:29.953555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.99152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:13:29.953603Z","caller":"traceutil/trace.go:171","msg":"trace[855480306] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:4879; }","duration":"307.070012ms","start":"2026-08-25T19:13:29.646506Z","end":"2026-08-25T19:13:29.953576Z","steps":["trace[855480306] 'agreement among raft nodes before linearized reading' (duration: 307.01902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.953639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.646478Z","time spent":"307.152793ms","remote":"127.0.0.1:45520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-25T19:13:29.953796Z","caller":"traceutil/trace.go:171","msg":"trace[1649325101] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"370.568686ms","start":"2026-08-25T19:13:29.583185Z","end":"2026-08-25T19:13:29.953754Z","steps":["trace[1649325101] 'process raft request' (duration: 369.736787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.953864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.583176Z","time spent":"370.655597ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:13:29.954062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.465015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:13:29.954112Z","caller":"traceutil/trace.go:171","msg":"trace[59856716] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4879; }","duration":"194.557148ms","start":"2026-08-25T19:13:29.759545Z","end":"2026-08-25T19:13:29.954103Z","steps":["trace[59856716] 'agreement among raft nodes before linearized reading' (duration: 194.480536ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:13:29.954123Z","caller":"traceutil/trace.go:171","msg":"trace[363591131] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"371.873676ms","start":"2026-08-25T19:13:29.582219Z","end":"2026-08-25T19:13:29.954093Z","steps":["trace[363591131] 'process raft request' (duration: 370.666429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:13:29.954197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.582204Z","time spent":"371.956238ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:13:29.9529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.574443Z","time spent":"378.400538ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:13:29.954987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:13:29.581973Z","time spent":"372.956091ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:14:59.886966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2912} {"level":"info","ts":"2026-08-25T19:14:59.960786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2912,"took":"72.484366ms","hash":1832453193,"current-db-size-bytes":54657024,"current-db-size":"55 MB","current-db-size-in-use-bytes":30199808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-25T19:14:59.960856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1832453193,"revision":2912,"compact-revision":-1} {"level":"info","ts":"2026-08-25T19:16:53.08468Z","caller":"traceutil/trace.go:171","msg":"trace[488168019] transaction","detail":"{read_only:false; response_revision:6406; number_of_response:1; }","duration":"129.408062ms","start":"2026-08-25T19:16:52.955245Z","end":"2026-08-25T19:16:53.084654Z","steps":["trace[488168019] 'process raft request' (duration: 129.207278ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:16:53.084678Z","caller":"traceutil/trace.go:171","msg":"trace[1529817586] linearizableReadLoop","detail":"{readStateIndex:6638; appliedIndex:6637; }","duration":"110.125826ms","start":"2026-08-25T19:16:52.974524Z","end":"2026-08-25T19:16:53.08465Z","steps":["trace[1529817586] 'read index received' (duration: 109.982323ms)","trace[1529817586] 'applied index is now lower than readState.Index' (duration: 142.363µs)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:16:53.08504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.493484ms","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-08-25T19:16:53.08514Z","caller":"traceutil/trace.go:171","msg":"trace[1396104010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6406; }","duration":"110.672928ms","start":"2026-08-25T19:16:52.974453Z","end":"2026-08-25T19:16:53.085126Z","steps":["trace[1396104010] 'agreement among raft nodes before linearized reading' (duration: 110.28752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:16:53.097662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.133039ms","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-08-25T19:16:53.097697Z","caller":"traceutil/trace.go:171","msg":"trace[1584719039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6407; }","duration":"105.221751ms","start":"2026-08-25T19:16:52.992467Z","end":"2026-08-25T19:16:53.097689Z","steps":["trace[1584719039] 'agreement among raft nodes before linearized reading' (duration: 105.092738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:16:53.097821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.592408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-25T19:16:53.097935Z","caller":"traceutil/trace.go:171","msg":"trace[1142549778] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6407; }","duration":"111.733591ms","start":"2026-08-25T19:16:52.986174Z","end":"2026-08-25T19:16:53.097908Z","steps":["trace[1142549778] 'agreement among raft nodes before linearized reading' (duration: 111.467286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:23.875923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17870178018735709402,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-25T19:17:24.376427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17870178018735709402,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-25T19:17:24.857018Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.486275031s","expected-duration":"1s"} {"level":"info","ts":"2026-08-25T19:17:24.859806Z","caller":"traceutil/trace.go:171","msg":"trace[273453347] transaction","detail":"{read_only:false; response_revision:6620; number_of_response:1; }","duration":"1.489116083s","start":"2026-08-25T19:17:23.370673Z","end":"2026-08-25T19:17:24.85979Z","steps":["trace[273453347] 'process raft request' (duration: 1.487130569s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.859964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.370661Z","time spent":"1.489250106s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:17:24.860123Z","caller":"traceutil/trace.go:171","msg":"trace[2100800815] linearizableReadLoop","detail":"{readStateIndex:6863; appliedIndex:6860; }","duration":"1.484299388s","start":"2026-08-25T19:17:23.3758Z","end":"2026-08-25T19:17:24.860099Z","steps":["trace[2100800815] 'read index received' (duration: 1.482013358s)","trace[2100800815] 'applied index is now lower than readState.Index' (duration: 2.28511ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:17:24.86015Z","caller":"traceutil/trace.go:171","msg":"trace[1652170390] transaction","detail":"{read_only:false; response_revision:6621; number_of_response:1; }","duration":"1.170606325s","start":"2026-08-25T19:17:23.689524Z","end":"2026-08-25T19:17:24.86013Z","steps":["trace[1652170390] 'process raft request' (duration: 1.170211537s)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:17:24.860138Z","caller":"traceutil/trace.go:171","msg":"trace[918376269] transaction","detail":"{read_only:false; response_revision:6622; number_of_response:1; }","duration":"711.426998ms","start":"2026-08-25T19:17:24.148681Z","end":"2026-08-25T19:17:24.860108Z","steps":["trace[918376269] 'process raft request' (duration: 711.317265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.860288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.689504Z","time spent":"1.170702918s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:17:24.860328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:24.148654Z","time spent":"711.56429ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:17:24.860371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420531371s","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-08-25T19:17:24.860414Z","caller":"traceutil/trace.go:171","msg":"trace[349691757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6622; }","duration":"1.420605823s","start":"2026-08-25T19:17:23.439797Z","end":"2026-08-25T19:17:24.860403Z","steps":["trace[349691757] 'agreement among raft nodes before linearized reading' (duration: 1.4204487s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.860454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.439786Z","time spent":"1.420659544s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-25T19:17:24.860404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484584514s","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-08-25T19:17:24.860525Z","caller":"traceutil/trace.go:171","msg":"trace[1510739432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6622; }","duration":"1.484732927s","start":"2026-08-25T19:17:23.375776Z","end":"2026-08-25T19:17:24.860509Z","steps":["trace[1510739432] 'agreement among raft nodes before linearized reading' (duration: 1.48441134s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.860594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.375767Z","time spent":"1.484787998s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-25T19:17:24.860671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221950671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-25T19:17:24.860708Z","caller":"traceutil/trace.go:171","msg":"trace[605861391] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6622; }","duration":"1.222009563s","start":"2026-08-25T19:17:23.638687Z","end":"2026-08-25T19:17:24.860697Z","steps":["trace[605861391] 'agreement among raft nodes before linearized reading' (duration: 1.22187183s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.860735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.638673Z","time spent":"1.222057454s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-25T19:17:24.860704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.550774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-25T19:17:24.860762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231958358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-8584b6688f-ndj9k\" ","response":"range_response_count:1 size:9864"} {"level":"info","ts":"2026-08-25T19:17:24.860807Z","caller":"traceutil/trace.go:171","msg":"trace[15616010] range","detail":"{range_begin:/registry/pods/openstack/horizon-8584b6688f-ndj9k; range_end:; response_count:1; response_revision:6622; }","duration":"1.23202965s","start":"2026-08-25T19:17:23.628763Z","end":"2026-08-25T19:17:24.860793Z","steps":["trace[15616010] 'agreement among raft nodes before linearized reading' (duration: 1.231916538s)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:17:24.860778Z","caller":"traceutil/trace.go:171","msg":"trace[141159597] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:6622; }","duration":"930.738208ms","start":"2026-08-25T19:17:23.930027Z","end":"2026-08-25T19:17:24.860765Z","steps":["trace[141159597] 'agreement among raft nodes before linearized reading' (duration: 930.514483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.860878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251516914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-25T19:17:24.860926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.359406ms","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-08-25T19:17:24.860962Z","caller":"traceutil/trace.go:171","msg":"trace[576065910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6622; }","duration":"102.446498ms","start":"2026-08-25T19:17:24.758509Z","end":"2026-08-25T19:17:24.860955Z","steps":["trace[576065910] 'agreement among raft nodes before linearized reading' (duration: 102.07504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.861027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.753522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-25T19:17:24.861072Z","caller":"traceutil/trace.go:171","msg":"trace[583197207] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:6622; }","duration":"271.801003ms","start":"2026-08-25T19:17:24.589259Z","end":"2026-08-25T19:17:24.86106Z","steps":["trace[583197207] 'agreement among raft nodes before linearized reading' (duration: 271.689251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.861016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.36477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:17:24.86132Z","caller":"traceutil/trace.go:171","msg":"trace[1098198402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6622; }","duration":"1.251581756s","start":"2026-08-25T19:17:23.609319Z","end":"2026-08-25T19:17:24.860901Z","steps":["trace[1098198402] 'agreement among raft nodes before linearized reading' (duration: 1.251540685s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.860841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.628748Z","time spent":"1.232087691s","remote":"127.0.0.1:37102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":9887,"request content":"key:\"/registry/pods/openstack/horizon-8584b6688f-ndj9k\" "} {"level":"warn","ts":"2026-08-25T19:17:24.860813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081522197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:17:24.861545Z","caller":"traceutil/trace.go:171","msg":"trace[1186824864] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:6622; }","duration":"1.082289435s","start":"2026-08-25T19:17:23.779243Z","end":"2026-08-25T19:17:24.861532Z","steps":["trace[1186824864] 'agreement among raft nodes before linearized reading' (duration: 1.081543938s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.861592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.779222Z","time spent":"1.082353776s","remote":"127.0.0.1:38386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:17:24.86257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.609298Z","time spent":"1.253259332s","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-25T19:17:24.863978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16254853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:436532"} {"level":"info","ts":"2026-08-25T19:17:24.864027Z","caller":"traceutil/trace.go:171","msg":"trace[2140123556] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6622; }","duration":"1.162631831s","start":"2026-08-25T19:17:23.701384Z","end":"2026-08-25T19:17:24.864016Z","steps":["trace[2140123556] 'agreement among raft nodes before linearized reading' (duration: 1.159443582s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.864059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.701367Z","time spent":"1.162683003s","remote":"127.0.0.1:36990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":25,"response size":436555,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-08-25T19:17:24.864847Z","caller":"traceutil/trace.go:171","msg":"trace[1767814407] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:6622; }","duration":"816.545744ms","start":"2026-08-25T19:17:24.044606Z","end":"2026-08-25T19:17:24.861152Z","steps":["trace[1767814407] 'agreement among raft nodes before linearized reading' (duration: 816.34863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:24.865007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:24.044586Z","time spent":"820.389348ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:17:24.860902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:23.930007Z","time spent":"930.881711ms","remote":"127.0.0.1:37058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":4,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:17:29.409962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17870178018735709457,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-25T19:17:29.618142Z","caller":"traceutil/trace.go:171","msg":"trace[350253687] transaction","detail":"{read_only:false; response_revision:6642; number_of_response:1; }","duration":"713.565092ms","start":"2026-08-25T19:17:28.90455Z","end":"2026-08-25T19:17:29.618116Z","steps":["trace[350253687] 'process raft request' (duration: 713.445628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.618241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:28.904529Z","time spent":"713.661784ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:17:29.647777Z","caller":"traceutil/trace.go:171","msg":"trace[1733446390] linearizableReadLoop","detail":"{readStateIndex:6885; appliedIndex:6883; }","duration":"738.083116ms","start":"2026-08-25T19:17:28.909673Z","end":"2026-08-25T19:17:29.647756Z","steps":["trace[1733446390] 'read index received' (duration: 708.344618ms)","trace[1733446390] 'applied index is now lower than readState.Index' (duration: 29.737438ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:17:29.647879Z","caller":"traceutil/trace.go:171","msg":"trace[956836184] transaction","detail":"{read_only:false; response_revision:6643; number_of_response:1; }","duration":"739.478156ms","start":"2026-08-25T19:17:28.908393Z","end":"2026-08-25T19:17:29.647871Z","steps":["trace[956836184] 'process raft request' (duration: 739.210721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.648075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.263899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-25T19:17:29.648135Z","caller":"traceutil/trace.go:171","msg":"trace[624067594] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6643; }","duration":"732.331141ms","start":"2026-08-25T19:17:28.915787Z","end":"2026-08-25T19:17:29.648118Z","steps":["trace[624067594] 'agreement among raft nodes before linearized reading' (duration: 732.188638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.648162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:28.915755Z","time spent":"732.400881ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-25T19:17:29.648337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.669988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-25T19:17:29.648359Z","caller":"traceutil/trace.go:171","msg":"trace[791680839] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6643; }","duration":"738.716859ms","start":"2026-08-25T19:17:28.909636Z","end":"2026-08-25T19:17:29.648353Z","steps":["trace[791680839] 'agreement among raft nodes before linearized reading' (duration: 738.648807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.648376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:28.909625Z","time spent":"738.74638ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-25T19:17:29.6487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.470467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-25T19:17:29.648726Z","caller":"traceutil/trace.go:171","msg":"trace[736294421] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:6643; }","duration":"161.559308ms","start":"2026-08-25T19:17:29.487159Z","end":"2026-08-25T19:17:29.648719Z","steps":["trace[736294421] 'agreement among raft nodes before linearized reading' (duration: 161.518078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.648857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.984475ms","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-08-25T19:17:29.648877Z","caller":"traceutil/trace.go:171","msg":"trace[1291818668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6643; }","duration":"261.033337ms","start":"2026-08-25T19:17:29.387838Z","end":"2026-08-25T19:17:29.648872Z","steps":["trace[1291818668] 'agreement among raft nodes before linearized reading' (duration: 260.973014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.648985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.124228ms","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-08-25T19:17:29.649005Z","caller":"traceutil/trace.go:171","msg":"trace[929630183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6643; }","duration":"272.165479ms","start":"2026-08-25T19:17:29.376834Z","end":"2026-08-25T19:17:29.648999Z","steps":["trace[929630183] 'agreement among raft nodes before linearized reading' (duration: 272.112377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.649089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.739577ms","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-08-25T19:17:29.649108Z","caller":"traceutil/trace.go:171","msg":"trace[277309619] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:6643; }","duration":"472.759508ms","start":"2026-08-25T19:17:29.176344Z","end":"2026-08-25T19:17:29.649103Z","steps":["trace[277309619] 'agreement among raft nodes before linearized reading' (duration: 472.703377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.649125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:29.176296Z","time spent":"472.823679ms","remote":"127.0.0.1:37102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":45,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:17:29.64926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.548224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-haproxy-metrics\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-08-25T19:17:29.649283Z","caller":"traceutil/trace.go:171","msg":"trace[216180261] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-haproxy-metrics; range_end:; response_count:1; response_revision:6643; }","duration":"495.607435ms","start":"2026-08-25T19:17:29.153669Z","end":"2026-08-25T19:17:29.649277Z","steps":["trace[216180261] 'agreement among raft nodes before linearized reading' (duration: 495.537484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.649299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:29.15365Z","time spent":"495.644836ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":818,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-haproxy-metrics\" "} {"level":"warn","ts":"2026-08-25T19:17:29.650351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.937139ms","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-08-25T19:17:29.650382Z","caller":"traceutil/trace.go:171","msg":"trace[445516956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6643; }","duration":"730.050752ms","start":"2026-08-25T19:17:28.920323Z","end":"2026-08-25T19:17:29.650374Z","steps":["trace[445516956] 'agreement among raft nodes before linearized reading' (duration: 729.911239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.650404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:28.920316Z","time spent":"730.081773ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-25T19:17:29.650243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.429519ms","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-08-25T19:17:29.650535Z","caller":"traceutil/trace.go:171","msg":"trace[1559058834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6643; }","duration":"730.752527ms","start":"2026-08-25T19:17:28.919761Z","end":"2026-08-25T19:17:29.650514Z","steps":["trace[1559058834] 'agreement among raft nodes before linearized reading' (duration: 730.241346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.650596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:28.919753Z","time spent":"730.829398ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-25T19:17:29.650594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.274646ms","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-08-25T19:17:29.650646Z","caller":"traceutil/trace.go:171","msg":"trace[2002716223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6643; }","duration":"730.348838ms","start":"2026-08-25T19:17:28.920288Z","end":"2026-08-25T19:17:29.650636Z","steps":["trace[2002716223] 'agreement among raft nodes before linearized reading' (duration: 730.201314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:29.650679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:28.92028Z","time spent":"730.393188ms","remote":"127.0.0.1:37184","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-08-25T19:17:29.650817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:17:28.908378Z","time spent":"739.521537ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:17:31.933206Z","caller":"traceutil/trace.go:171","msg":"trace[1359452830] transaction","detail":"{read_only:false; response_revision:6657; number_of_response:1; }","duration":"158.151907ms","start":"2026-08-25T19:17:31.775018Z","end":"2026-08-25T19:17:31.93317Z","steps":["trace[1359452830] 'process raft request' (duration: 108.959684ms)","trace[1359452830] 'compare' (duration: 48.989989ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:17:31.933259Z","caller":"traceutil/trace.go:171","msg":"trace[1564948037] linearizableReadLoop","detail":"{readStateIndex:6903; appliedIndex:6897; }","duration":"151.867099ms","start":"2026-08-25T19:17:31.781368Z","end":"2026-08-25T19:17:31.933235Z","steps":["trace[1564948037] 'read index received' (duration: 75.300711ms)","trace[1564948037] 'applied index is now lower than readState.Index' (duration: 76.564928ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:17:31.933462Z","caller":"traceutil/trace.go:171","msg":"trace[902918784] transaction","detail":"{read_only:false; response_revision:6659; number_of_response:1; }","duration":"157.955842ms","start":"2026-08-25T19:17:31.775489Z","end":"2026-08-25T19:17:31.933445Z","steps":["trace[902918784] 'process raft request' (duration: 157.631655ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:17:31.933594Z","caller":"traceutil/trace.go:171","msg":"trace[2028319783] transaction","detail":"{read_only:false; response_revision:6658; number_of_response:1; }","duration":"158.426922ms","start":"2026-08-25T19:17:31.775157Z","end":"2026-08-25T19:17:31.933583Z","steps":["trace[2028319783] 'process raft request' (duration: 157.916291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:17:31.933692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.311319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:436532"} {"level":"info","ts":"2026-08-25T19:17:31.933735Z","caller":"traceutil/trace.go:171","msg":"trace[1017925760] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6661; }","duration":"152.386991ms","start":"2026-08-25T19:17:31.781338Z","end":"2026-08-25T19:17:31.933725Z","steps":["trace[1017925760] 'agreement among raft nodes before linearized reading' (duration: 151.982802ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:17:31.93382Z","caller":"traceutil/trace.go:171","msg":"trace[737901655] transaction","detail":"{read_only:false; response_revision:6660; number_of_response:1; }","duration":"157.458081ms","start":"2026-08-25T19:17:31.776353Z","end":"2026-08-25T19:17:31.933811Z","steps":["trace[737901655] 'process raft request' (duration: 156.806867ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:17:31.93395Z","caller":"traceutil/trace.go:171","msg":"trace[584266802] transaction","detail":"{read_only:false; response_revision:6661; number_of_response:1; }","duration":"157.146014ms","start":"2026-08-25T19:17:31.776789Z","end":"2026-08-25T19:17:31.933935Z","steps":["trace[584266802] 'process raft request' (duration: 156.408709ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:18:05.910523Z","caller":"traceutil/trace.go:171","msg":"trace[2032804722] transaction","detail":"{read_only:false; response_revision:6846; number_of_response:1; }","duration":"109.34713ms","start":"2026-08-25T19:18:05.801156Z","end":"2026-08-25T19:18:05.910504Z","steps":["trace[2032804722] 'process raft request' (duration: 109.254068ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:18:06.334731Z","caller":"traceutil/trace.go:171","msg":"trace[1533180920] transaction","detail":"{read_only:false; response_revision:6850; number_of_response:1; }","duration":"114.118436ms","start":"2026-08-25T19:18:06.220584Z","end":"2026-08-25T19:18:06.334703Z","steps":["trace[1533180920] 'process raft request' (duration: 40.375987ms)","trace[1533180920] 'compare' (duration: 73.566665ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:18:06.334835Z","caller":"traceutil/trace.go:171","msg":"trace[1108571592] linearizableReadLoop","detail":"{readStateIndex:7100; appliedIndex:7098; }","duration":"107.154612ms","start":"2026-08-25T19:18:06.227653Z","end":"2026-08-25T19:18:06.334808Z","steps":["trace[1108571592] 'read index received' (duration: 33.320922ms)","trace[1108571592] 'applied index is now lower than readState.Index' (duration: 73.81934ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:18:06.335089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.426888ms","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-08-25T19:18:06.335135Z","caller":"traceutil/trace.go:171","msg":"trace[1980680791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6851; }","duration":"107.51427ms","start":"2026-08-25T19:18:06.22761Z","end":"2026-08-25T19:18:06.335124Z","steps":["trace[1980680791] 'agreement among raft nodes before linearized reading' (duration: 107.331896ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:18:06.335225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.222124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-25T19:18:06.335306Z","caller":"traceutil/trace.go:171","msg":"trace[1568497501] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6851; }","duration":"107.347147ms","start":"2026-08-25T19:18:06.22794Z","end":"2026-08-25T19:18:06.335287Z","steps":["trace[1568497501] 'agreement among raft nodes before linearized reading' (duration: 107.065001ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:18:06.335116Z","caller":"traceutil/trace.go:171","msg":"trace[1958459075] transaction","detail":"{read_only:false; response_revision:6851; number_of_response:1; }","duration":"113.622824ms","start":"2026-08-25T19:18:06.221462Z","end":"2026-08-25T19:18:06.335085Z","steps":["trace[1958459075] 'process raft request' (duration: 113.192955ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:18:34.707122Z","caller":"traceutil/trace.go:171","msg":"trace[278544900] transaction","detail":"{read_only:false; response_revision:7002; number_of_response:1; }","duration":"169.90817ms","start":"2026-08-25T19:18:34.537188Z","end":"2026-08-25T19:18:34.707096Z","steps":["trace[278544900] 'process raft request' (duration: 120.881608ms)","trace[278544900] 'compare' (duration: 48.713396ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:19:04.953913Z","caller":"traceutil/trace.go:171","msg":"trace[1179789449] linearizableReadLoop","detail":"{readStateIndex:7410; appliedIndex:7409; }","duration":"360.890487ms","start":"2026-08-25T19:19:04.592997Z","end":"2026-08-25T19:19:04.953887Z","steps":["trace[1179789449] 'read index received' (duration: 360.727103ms)","trace[1179789449] 'applied index is now lower than readState.Index' (duration: 161.784µs)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:19:04.954049Z","caller":"traceutil/trace.go:171","msg":"trace[1630580450] transaction","detail":"{read_only:false; response_revision:7149; number_of_response:1; }","duration":"385.842942ms","start":"2026-08-25T19:19:04.568166Z","end":"2026-08-25T19:19:04.954008Z","steps":["trace[1630580450] 'process raft request' (duration: 385.542705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:04.954333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.228075ms","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-08-25T19:19:04.954357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.568153Z","time spent":"385.996915ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:19:04.954399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.055098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-25T19:19:04.954417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.200596ms","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-08-25T19:19:04.954458Z","caller":"traceutil/trace.go:171","msg":"trace[1049124692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7149; }","duration":"345.15272ms","start":"2026-08-25T19:19:04.609288Z","end":"2026-08-25T19:19:04.954441Z","steps":["trace[1049124692] 'agreement among raft nodes before linearized reading' (duration: 345.066788ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:04.954472Z","caller":"traceutil/trace.go:171","msg":"trace[1232944761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7149; }","duration":"106.319939ms","start":"2026-08-25T19:19:04.848138Z","end":"2026-08-25T19:19:04.954458Z","steps":["trace[1232944761] 'agreement among raft nodes before linearized reading' (duration: 106.142166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:04.954497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.609263Z","time spent":"345.228291ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-25T19:19:04.954384Z","caller":"traceutil/trace.go:171","msg":"trace[472313932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7149; }","duration":"361.428001ms","start":"2026-08-25T19:19:04.592942Z","end":"2026-08-25T19:19:04.95437Z","steps":["trace[472313932] 'agreement among raft nodes before linearized reading' (duration: 361.104602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:04.954361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.916464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-25T19:19:04.95456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.592922Z","time spent":"361.624044ms","remote":"127.0.0.1:37184","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":"info","ts":"2026-08-25T19:19:04.954658Z","caller":"traceutil/trace.go:171","msg":"trace[1732852154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7149; }","duration":"313.233211ms","start":"2026-08-25T19:19:04.641366Z","end":"2026-08-25T19:19:04.954599Z","steps":["trace[1732852154] 'agreement among raft nodes before linearized reading' (duration: 312.823221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:04.954766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.641334Z","time spent":"313.416055ms","remote":"127.0.0.1:37184","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-08-25T19:19:04.954248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.938228ms","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-08-25T19:19:04.954858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.963474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-25T19:19:04.954893Z","caller":"traceutil/trace.go:171","msg":"trace[1618661895] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:7149; }","duration":"361.639584ms","start":"2026-08-25T19:19:04.593231Z","end":"2026-08-25T19:19:04.954871Z","steps":["trace[1618661895] 'agreement among raft nodes before linearized reading' (duration: 360.874017ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:04.954936Z","caller":"traceutil/trace.go:171","msg":"trace[1454512582] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7149; }","duration":"262.039806ms","start":"2026-08-25T19:19:04.692879Z","end":"2026-08-25T19:19:04.954919Z","steps":["trace[1454512582] 'agreement among raft nodes before linearized reading' (duration: 261.541735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:04.955059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.593207Z","time spent":"361.785998ms","remote":"127.0.0.1:37066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-25T19:19:04.955798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.215232ms","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-08-25T19:19:04.955949Z","caller":"traceutil/trace.go:171","msg":"trace[523755225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7149; }","duration":"264.391217ms","start":"2026-08-25T19:19:04.691541Z","end":"2026-08-25T19:19:04.955932Z","steps":["trace[523755225] 'agreement among raft nodes before linearized reading' (duration: 263.503567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:07.61015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000410557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-25T19:19:07.610442Z","caller":"traceutil/trace.go:171","msg":"trace[1227096788] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000739884s","start":"2026-08-25T19:19:05.609642Z","end":"2026-08-25T19:19:07.610382Z","steps":["trace[1227096788] 'agreement among raft nodes before linearized reading' (duration: 2.000438498s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:07.610601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:05.609623Z","time spent":"2.000960999s","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-25T19:19:08.502473Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.538076721s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-25T19:19:08.594562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280521551s","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-08-25T19:19:08.59473Z","caller":"traceutil/trace.go:171","msg":"trace[92424625] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7151; }","duration":"1.280698765s","start":"2026-08-25T19:19:07.314006Z","end":"2026-08-25T19:19:08.594705Z","steps":["trace[92424625] 'range keys from in-memory index tree' (duration: 1.280503101s)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:08.595363Z","caller":"traceutil/trace.go:171","msg":"trace[2036865103] transaction","detail":"{read_only:false; response_revision:7153; number_of_response:1; }","duration":"3.635062024s","start":"2026-08-25T19:19:04.96028Z","end":"2026-08-25T19:19:08.595342Z","steps":["trace[2036865103] 'process raft request' (duration: 3.63487619s)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:08.595392Z","caller":"traceutil/trace.go:171","msg":"trace[1819464160] linearizableReadLoop","detail":"{readStateIndex:7414; appliedIndex:7410; }","duration":"3.634372189s","start":"2026-08-25T19:19:04.961002Z","end":"2026-08-25T19:19:08.595374Z","steps":["trace[1819464160] 'read index received' (duration: 3.459607ms)","trace[1819464160] 'applied index is now lower than readState.Index' (duration: 3.630911492s)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:19:08.595509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.96027Z","time spent":"3.635159246s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:19:08.595561Z","caller":"traceutil/trace.go:171","msg":"trace[701123995] transaction","detail":"{read_only:false; response_revision:7154; number_of_response:1; }","duration":"3.634097782s","start":"2026-08-25T19:19:04.96145Z","end":"2026-08-25T19:19:08.595548Z","steps":["trace[701123995] 'process raft request' (duration: 3.633839837s)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:08.595342Z","caller":"traceutil/trace.go:171","msg":"trace[692950391] transaction","detail":"{read_only:false; response_revision:7152; number_of_response:1; }","duration":"3.635062574s","start":"2026-08-25T19:19:04.960218Z","end":"2026-08-25T19:19:08.595281Z","steps":["trace[692950391] 'process raft request' (duration: 3.542437687s)","trace[692950391] 'compare' (duration: 91.38782ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:19:08.595644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.961439Z","time spent":"3.634155303s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:19:08.595831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.96021Z","time spent":"3.635495293s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:19:08.596046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.635034113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0504866a-6b2d-4c90-a700-f37d81da6708\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-08-25T19:19:08.5961Z","caller":"traceutil/trace.go:171","msg":"trace[1338581709] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0504866a-6b2d-4c90-a700-f37d81da6708; range_end:; response_count:1; response_revision:7154; }","duration":"3.635124086s","start":"2026-08-25T19:19:04.960957Z","end":"2026-08-25T19:19:08.596081Z","steps":["trace[1338581709] 'agreement among raft nodes before linearized reading' (duration: 3.634943611s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.59615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:04.960946Z","time spent":"3.635192367s","remote":"127.0.0.1:37058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-0504866a-6b2d-4c90-a700-f37d81da6708\" "} {"level":"info","ts":"2026-08-25T19:19:08.622367Z","caller":"traceutil/trace.go:171","msg":"trace[1978798165] transaction","detail":"{read_only:false; response_revision:7155; number_of_response:1; }","duration":"2.4400288s","start":"2026-08-25T19:19:06.182316Z","end":"2026-08-25T19:19:08.622345Z","steps":["trace[1978798165] 'process raft request' (duration: 2.439850766s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.622561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.182252Z","time spent":"2.440230214s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:19:08.622817Z","caller":"traceutil/trace.go:171","msg":"trace[166456921] transaction","detail":"{read_only:false; response_revision:7156; number_of_response:1; }","duration":"2.432728828s","start":"2026-08-25T19:19:06.190048Z","end":"2026-08-25T19:19:08.622776Z","steps":["trace[166456921] 'process raft request' (duration: 2.432262207s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.622978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.190034Z","time spent":"2.432855511s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:19:08.623308Z","caller":"traceutil/trace.go:171","msg":"trace[8601758] transaction","detail":"{read_only:false; response_revision:7157; number_of_response:1; }","duration":"1.176914591s","start":"2026-08-25T19:19:07.446379Z","end":"2026-08-25T19:19:08.623294Z","steps":["trace[8601758] 'process raft request' (duration: 1.176707546s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.623357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:07.446354Z","time spent":"1.176980162s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:19:08.65791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.941195634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-25T19:19:08.657957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690130815s","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-08-25T19:19:08.657982Z","caller":"traceutil/trace.go:171","msg":"trace[1398979730] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:7158; }","duration":"2.941311127s","start":"2026-08-25T19:19:05.716658Z","end":"2026-08-25T19:19:08.657969Z","steps":["trace[1398979730] 'agreement among raft nodes before linearized reading' (duration: 2.941214444s)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:08.658009Z","caller":"traceutil/trace.go:171","msg":"trace[1995693062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7158; }","duration":"1.690249088s","start":"2026-08-25T19:19:06.967746Z","end":"2026-08-25T19:19:08.657995Z","steps":["trace[1995693062] 'agreement among raft nodes before linearized reading' (duration: 1.690080084s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.657995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.933143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-25T19:19:08.658044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.967725Z","time spent":"1.690312769s","remote":"127.0.0.1:37184","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-08-25T19:19:08.658084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:05.716636Z","time spent":"2.941388879s","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-08-25T19:19:08.658086Z","caller":"traceutil/trace.go:171","msg":"trace[713776957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7158; }","duration":"751.066937ms","start":"2026-08-25T19:19:07.906998Z","end":"2026-08-25T19:19:08.658065Z","steps":["trace[713776957] 'agreement among raft nodes before linearized reading' (duration: 750.905423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.658134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927209137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-25T19:19:08.65814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:07.906977Z","time spent":"751.155228ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-25T19:19:08.658162Z","caller":"traceutil/trace.go:171","msg":"trace[444595708] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:7158; }","duration":"1.927273929s","start":"2026-08-25T19:19:06.730879Z","end":"2026-08-25T19:19:08.658153Z","steps":["trace[444595708] 'agreement among raft nodes before linearized reading' (duration: 1.927236098s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.658183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.730856Z","time spent":"1.92732286s","remote":"127.0.0.1:45394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:19:08.65821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69845733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-08-25T19:19:08.658255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.554529935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-25T19:19:08.658354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.346391712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-25T19:19:08.658411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047411445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:19:08.658409Z","caller":"traceutil/trace.go:171","msg":"trace[1321919051] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7158; }","duration":"1.554736599s","start":"2026-08-25T19:19:07.103637Z","end":"2026-08-25T19:19:08.658374Z","steps":["trace[1321919051] 'agreement among raft nodes before linearized reading' (duration: 1.554334601s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.657983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.271428948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:19:08.65842Z","caller":"traceutil/trace.go:171","msg":"trace[1716372147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7158; }","duration":"2.346493974s","start":"2026-08-25T19:19:06.31192Z","end":"2026-08-25T19:19:08.658414Z","steps":["trace[1716372147] 'agreement among raft nodes before linearized reading' (duration: 2.346394822s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.658337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68990412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-25T19:19:08.658473Z","caller":"traceutil/trace.go:171","msg":"trace[1804834028] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7158; }","duration":"1.698561402s","start":"2026-08-25T19:19:06.959706Z","end":"2026-08-25T19:19:08.658267Z","steps":["trace[1804834028] 'agreement among raft nodes before linearized reading' (duration: 1.698352388s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.65808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.249827604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:12297"} {"level":"info","ts":"2026-08-25T19:19:08.658521Z","caller":"traceutil/trace.go:171","msg":"trace[1874999327] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7158; }","duration":"1.690103675s","start":"2026-08-25T19:19:06.968404Z","end":"2026-08-25T19:19:08.658508Z","steps":["trace[1874999327] 'agreement among raft nodes before linearized reading' (duration: 1.689829889s)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:08.658543Z","caller":"traceutil/trace.go:171","msg":"trace[920863110] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:7158; }","duration":"3.250322035s","start":"2026-08-25T19:19:05.408214Z","end":"2026-08-25T19:19:08.658536Z","steps":["trace[920863110] 'agreement among raft nodes before linearized reading' (duration: 3.249769483s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.658547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.959683Z","time spent":"1.698850489s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-25T19:19:08.658567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.968393Z","time spent":"1.690164156s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-25T19:19:08.658584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.199291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:12 size:4389"} {"level":"info","ts":"2026-08-25T19:19:08.658426Z","caller":"traceutil/trace.go:171","msg":"trace[2069317586] transaction","detail":"{read_only:false; response_revision:7158; number_of_response:1; }","duration":"423.648306ms","start":"2026-08-25T19:19:08.234755Z","end":"2026-08-25T19:19:08.658403Z","steps":["trace[2069317586] 'process raft request' (duration: 422.889489ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:08.658616Z","caller":"traceutil/trace.go:171","msg":"trace[689699459] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:12; response_revision:7158; }","duration":"307.269933ms","start":"2026-08-25T19:19:08.351338Z","end":"2026-08-25T19:19:08.658608Z","steps":["trace[689699459] 'agreement among raft nodes before linearized reading' (duration: 307.110539ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:08.658463Z","caller":"traceutil/trace.go:171","msg":"trace[1884476889] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:7158; }","duration":"2.2719768s","start":"2026-08-25T19:19:06.386479Z","end":"2026-08-25T19:19:08.658456Z","steps":["trace[1884476889] 'agreement among raft nodes before linearized reading' (duration: 2.271371927s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.658663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:08.351316Z","time spent":"307.338245ms","remote":"127.0.0.1:37008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":12,"response size":4412,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"info","ts":"2026-08-25T19:19:08.658434Z","caller":"traceutil/trace.go:171","msg":"trace[561990551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7158; }","duration":"1.047461607s","start":"2026-08-25T19:19:07.610967Z","end":"2026-08-25T19:19:08.658428Z","steps":["trace[561990551] 'agreement among raft nodes before linearized reading' (duration: 1.047426816s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.658689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:07.610947Z","time spent":"1.047739532s","remote":"127.0.0.1:36848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-25T19:19:08.658564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:05.408195Z","time spent":"3.250363205s","remote":"127.0.0.1:37096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":12320,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-08-25T19:19:08.658482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:07.103619Z","time spent":"1.554852152s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-25T19:19:08.658675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.386469Z","time spent":"2.272200105s","remote":"127.0.0.1:58388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:19:08.658747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:08.234736Z","time spent":"423.908182ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:19:08.658496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.311896Z","time spent":"2.346593627s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-25T19:19:08.65899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.076220225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:19:08.659029Z","caller":"traceutil/trace.go:171","msg":"trace[1104675537] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:7158; }","duration":"2.07690214s","start":"2026-08-25T19:19:06.582119Z","end":"2026-08-25T19:19:08.659021Z","steps":["trace[1104675537] 'agreement among raft nodes before linearized reading' (duration: 2.076133863s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.659052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:06.5821Z","time spent":"2.076945981s","remote":"127.0.0.1:37224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:19:08.657918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.541087829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-25T19:19:08.659214Z","caller":"traceutil/trace.go:171","msg":"trace[1515881093] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:7158; }","duration":"3.54244877s","start":"2026-08-25T19:19:05.116757Z","end":"2026-08-25T19:19:08.659206Z","steps":["trace[1515881093] 'agreement among raft nodes before linearized reading' (duration: 3.541049919s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:08.659239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:05.116737Z","time spent":"3.54249463s","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":42,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:19:37.345234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17870178018735712355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-25T19:19:37.845691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17870178018735712355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-25T19:19:38.346129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17870178018735712355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-25T19:19:38.510461Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.671431405s","expected-duration":"1s"} {"level":"info","ts":"2026-08-25T19:19:38.510735Z","caller":"traceutil/trace.go:171","msg":"trace[1442700061] transaction","detail":"{read_only:false; response_revision:7302; number_of_response:1; }","duration":"1.671819854s","start":"2026-08-25T19:19:36.838893Z","end":"2026-08-25T19:19:38.510713Z","steps":["trace[1442700061] 'process raft request' (duration: 1.671700411s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.511094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:36.838864Z","time spent":"1.671966657s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:19:38.734639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.919533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-25T19:19:38.734927Z","caller":"traceutil/trace.go:171","msg":"trace[277304687] linearizableReadLoop","detail":"{readStateIndex:7571; appliedIndex:7569; }","duration":"1.890968542s","start":"2026-08-25T19:19:36.843919Z","end":"2026-08-25T19:19:38.734887Z","steps":["trace[277304687] 'read index received' (duration: 1.666742491s)","trace[277304687] 'applied index is now lower than readState.Index' (duration: 224.222971ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:19:38.835567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.991630077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-25T19:19:38.835651Z","caller":"traceutil/trace.go:171","msg":"trace[1764768143] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7303; }","duration":"1.991720248s","start":"2026-08-25T19:19:36.843897Z","end":"2026-08-25T19:19:38.835617Z","steps":["trace[1764768143] 'agreement among raft nodes before linearized reading' (duration: 1.891122605s)","trace[1764768143] 'range keys from in-memory index tree' (duration: 100.43914ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:19:38.835694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:36.843889Z","time spent":"1.99179622s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-25T19:19:38.836022Z","caller":"traceutil/trace.go:171","msg":"trace[126721855] transaction","detail":"{read_only:false; response_revision:7304; number_of_response:1; }","duration":"116.701792ms","start":"2026-08-25T19:19:38.719306Z","end":"2026-08-25T19:19:38.836008Z","steps":["trace[126721855] 'process raft request' (duration: 23.986684ms)","trace[126721855] 'compare' (duration: 92.086484ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:19:38.836229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316901111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-25T19:19:38.836189Z","caller":"traceutil/trace.go:171","msg":"trace[1207672336] linearizableReadLoop","detail":"{readStateIndex:7573; appliedIndex:7572; }","duration":"101.136386ms","start":"2026-08-25T19:19:38.734991Z","end":"2026-08-25T19:19:38.836127Z","steps":["trace[1207672336] 'read index received' (duration: 8.230584ms)","trace[1207672336] 'applied index is now lower than readState.Index' (duration: 92.904922ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:19:38.836271Z","caller":"traceutil/trace.go:171","msg":"trace[303779386] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7305; }","duration":"1.316943712s","start":"2026-08-25T19:19:37.519316Z","end":"2026-08-25T19:19:38.83626Z","steps":["trace[303779386] 'agreement among raft nodes before linearized reading' (duration: 1.316831489s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.836303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:37.519295Z","time spent":"1.317000822s","remote":"127.0.0.1:37066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-08-25T19:19:38.836546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.762867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-25T19:19:38.836531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.218568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:19:38.836586Z","caller":"traceutil/trace.go:171","msg":"trace[825743934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7305; }","duration":"929.839799ms","start":"2026-08-25T19:19:37.906737Z","end":"2026-08-25T19:19:38.836577Z","steps":["trace[825743934] 'agreement among raft nodes before linearized reading' (duration: 929.784247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.836613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:37.906713Z","time spent":"929.89463ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-25T19:19:38.836631Z","caller":"traceutil/trace.go:171","msg":"trace[57685339] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:7305; }","duration":"553.337141ms","start":"2026-08-25T19:19:38.283248Z","end":"2026-08-25T19:19:38.836585Z","steps":["trace[57685339] 'agreement among raft nodes before linearized reading' (duration: 553.223548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.836664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.720302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-25T19:19:38.836712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.28323Z","time spent":"553.445553ms","remote":"127.0.0.1:58530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-08-25T19:19:38.836767Z","caller":"traceutil/trace.go:171","msg":"trace[1892922825] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:7305; }","duration":"328.873704ms","start":"2026-08-25T19:19:38.50787Z","end":"2026-08-25T19:19:38.836744Z","steps":["trace[1892922825] 'agreement among raft nodes before linearized reading' (duration: 328.650639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.836827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.507855Z","time spent":"328.959847ms","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:19:38.836824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.938812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-25T19:19:38.836851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.853226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-25T19:19:38.836884Z","caller":"traceutil/trace.go:171","msg":"trace[355930758] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:7305; }","duration":"342.95773ms","start":"2026-08-25T19:19:38.493917Z","end":"2026-08-25T19:19:38.836875Z","steps":["trace[355930758] 'agreement among raft nodes before linearized reading' (duration: 342.898718ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:38.836886Z","caller":"traceutil/trace.go:171","msg":"trace[699845443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7305; }","duration":"227.056485ms","start":"2026-08-25T19:19:38.609815Z","end":"2026-08-25T19:19:38.836871Z","steps":["trace[699845443] 'agreement among raft nodes before linearized reading' (duration: 226.946222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.836919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.493895Z","time spent":"343.017141ms","remote":"127.0.0.1:37166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":9,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:19:38.836514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945798695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/default\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-08-25T19:19:38.837108Z","caller":"traceutil/trace.go:171","msg":"trace[968435118] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/default; range_end:; response_count:1; response_revision:7305; }","duration":"1.946429179s","start":"2026-08-25T19:19:36.890666Z","end":"2026-08-25T19:19:38.837096Z","steps":["trace[968435118] 'agreement among raft nodes before linearized reading' (duration: 1.945637121s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.837152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:36.89066Z","time spent":"1.94648171s","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/default\" "} {"level":"warn","ts":"2026-08-25T19:19:38.837228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.989694595s","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-08-25T19:19:38.837269Z","caller":"traceutil/trace.go:171","msg":"trace[999452566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7305; }","duration":"1.989743976s","start":"2026-08-25T19:19:36.847515Z","end":"2026-08-25T19:19:38.837259Z","steps":["trace[999452566] 'agreement among raft nodes before linearized reading' (duration: 1.989617353s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.837324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:36.847508Z","time spent":"1.989808197s","remote":"127.0.0.1:37184","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-08-25T19:19:38.837472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967347266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-25T19:19:38.837505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.990226346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036305"} {"level":"info","ts":"2026-08-25T19:19:38.837509Z","caller":"traceutil/trace.go:171","msg":"trace[1123573035] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:7305; }","duration":"1.967412348s","start":"2026-08-25T19:19:36.870086Z","end":"2026-08-25T19:19:38.837498Z","steps":["trace[1123573035] 'agreement among raft nodes before linearized reading' (duration: 1.967355337s)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:38.837542Z","caller":"traceutil/trace.go:171","msg":"trace[1674817830] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:7305; }","duration":"1.990265007s","start":"2026-08-25T19:19:36.847267Z","end":"2026-08-25T19:19:38.837532Z","steps":["trace[1674817830] 'agreement among raft nodes before linearized reading' (duration: 1.989882708s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.837545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:36.870072Z","time spent":"1.967465479s","remote":"127.0.0.1:37066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-08-25T19:19:38.837578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:36.847209Z","time spent":"1.990362069s","remote":"127.0.0.1:36990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":1036328,"request content":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" "} {"level":"warn","ts":"2026-08-25T19:19:38.837649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.991944754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-25T19:19:38.837698Z","caller":"traceutil/trace.go:171","msg":"trace[304311407] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7305; }","duration":"1.992005855s","start":"2026-08-25T19:19:36.845679Z","end":"2026-08-25T19:19:38.837685Z","steps":["trace[304311407] 'agreement among raft nodes before linearized reading' (duration: 1.991932383s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.837736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:36.845674Z","time spent":"1.992052966s","remote":"127.0.0.1:58426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-25T19:19:38.838368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.950243994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:11 size:7467"} {"level":"info","ts":"2026-08-25T19:19:38.838454Z","caller":"traceutil/trace.go:171","msg":"trace[1429938948] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:11; response_revision:7305; }","duration":"1.950352157s","start":"2026-08-25T19:19:36.888087Z","end":"2026-08-25T19:19:38.838439Z","steps":["trace[1429938948] 'agreement among raft nodes before linearized reading' (duration: 1.948489665s)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.838591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:36.888077Z","time spent":"1.95050013s","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":11,"response size":7490,"request content":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-08-25T19:19:38.873331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.49046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-25T19:19:38.873372Z","caller":"traceutil/trace.go:171","msg":"trace[1256678602] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7306; }","duration":"134.560912ms","start":"2026-08-25T19:19:38.738801Z","end":"2026-08-25T19:19:38.873361Z","steps":["trace[1256678602] 'agreement among raft nodes before linearized reading' (duration: 134.433268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.873379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.305145ms","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-08-25T19:19:38.873429Z","caller":"traceutil/trace.go:171","msg":"trace[578662770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7306; }","duration":"129.380476ms","start":"2026-08-25T19:19:38.744036Z","end":"2026-08-25T19:19:38.873416Z","steps":["trace[578662770] 'agreement among raft nodes before linearized reading' (duration: 129.188531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:38.8734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.38701ms","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-08-25T19:19:38.873442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.693459ms","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-08-25T19:19:38.873491Z","caller":"traceutil/trace.go:171","msg":"trace[1278730462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7306; }","duration":"136.785921ms","start":"2026-08-25T19:19:38.736694Z","end":"2026-08-25T19:19:38.87348Z","steps":["trace[1278730462] 'agreement among raft nodes before linearized reading' (duration: 136.638527ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:19:38.873489Z","caller":"traceutil/trace.go:171","msg":"trace[1533920502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7306; }","duration":"131.502273ms","start":"2026-08-25T19:19:38.741969Z","end":"2026-08-25T19:19:38.873471Z","steps":["trace[1533920502] 'agreement among raft nodes before linearized reading' (duration: 131.280738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.148305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.555527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-25T19:19:39.148459Z","caller":"traceutil/trace.go:171","msg":"trace[1455525609] transaction","detail":"{read_only:false; response_revision:7307; number_of_response:1; }","duration":"305.839342ms","start":"2026-08-25T19:19:38.842604Z","end":"2026-08-25T19:19:39.148444Z","steps":["trace[1455525609] 'process raft request' (duration: 160.08082ms)","trace[1455525609] 'compare' (duration: 145.464245ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:19:39.148551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.842592Z","time spent":"305.917423ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:19:39.148579Z","caller":"traceutil/trace.go:171","msg":"trace[1382371712] linearizableReadLoop","detail":"{readStateIndex:7577; appliedIndex:7575; }","duration":"275.389893ms","start":"2026-08-25T19:19:38.873159Z","end":"2026-08-25T19:19:39.148549Z","steps":["trace[1382371712] 'read index received' (duration: 129.641262ms)","trace[1382371712] 'applied index is now lower than readState.Index' (duration: 145.746741ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:19:39.148692Z","caller":"traceutil/trace.go:171","msg":"trace[429811214] transaction","detail":"{read_only:false; response_revision:7308; number_of_response:1; }","duration":"304.627395ms","start":"2026-08-25T19:19:38.844045Z","end":"2026-08-25T19:19:39.148673Z","steps":["trace[429811214] 'process raft request' (duration: 304.325348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.148784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.844035Z","time spent":"304.706937ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:19:39.148855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.326592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d2ffc9fc-33d0-463a-8c45-aa0c813f34f2\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-08-25T19:19:39.148888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.398674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/default\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-08-25T19:19:39.148902Z","caller":"traceutil/trace.go:171","msg":"trace[1095003647] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d2ffc9fc-33d0-463a-8c45-aa0c813f34f2; range_end:; response_count:1; response_revision:7308; }","duration":"306.411244ms","start":"2026-08-25T19:19:38.842477Z","end":"2026-08-25T19:19:39.148889Z","steps":["trace[1095003647] 'agreement among raft nodes before linearized reading' (duration: 306.19588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.14894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.842466Z","time spent":"306.465596ms","remote":"127.0.0.1:37058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumes/pvc-d2ffc9fc-33d0-463a-8c45-aa0c813f34f2\" "} {"level":"info","ts":"2026-08-25T19:19:39.14893Z","caller":"traceutil/trace.go:171","msg":"trace[1693897960] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/default; range_end:; response_count:1; response_revision:7308; }","duration":"306.442616ms","start":"2026-08-25T19:19:38.842463Z","end":"2026-08-25T19:19:39.148906Z","steps":["trace[1693897960] 'agreement among raft nodes before linearized reading' (duration: 306.324603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.14898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.842454Z","time spent":"306.520097ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/default\" "} {"level":"info","ts":"2026-08-25T19:19:39.239822Z","caller":"traceutil/trace.go:171","msg":"trace[1925974292] transaction","detail":"{read_only:false; response_revision:7309; number_of_response:1; }","duration":"362.72916ms","start":"2026-08-25T19:19:38.877076Z","end":"2026-08-25T19:19:39.239805Z","steps":["trace[1925974292] 'process raft request' (duration: 362.565906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.239936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.87706Z","time spent":"362.809732ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:19:39.240043Z","caller":"traceutil/trace.go:171","msg":"trace[436419642] transaction","detail":"{read_only:false; response_revision:7310; number_of_response:1; }","duration":"362.892244ms","start":"2026-08-25T19:19:38.877121Z","end":"2026-08-25T19:19:39.240013Z","steps":["trace[436419642] 'process raft request' (duration: 362.639688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.240186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.877103Z","time spent":"363.008057ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:19:39.240357Z","caller":"traceutil/trace.go:171","msg":"trace[749753692] transaction","detail":"{read_only:false; response_revision:7311; number_of_response:1; }","duration":"363.158721ms","start":"2026-08-25T19:19:38.877178Z","end":"2026-08-25T19:19:39.240336Z","steps":["trace[749753692] 'process raft request' (duration: 362.73676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.240461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.877172Z","time spent":"363.235072ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:19:39.240661Z","caller":"traceutil/trace.go:171","msg":"trace[1915447207] transaction","detail":"{read_only:false; response_revision:7312; number_of_response:1; }","duration":"363.417756ms","start":"2026-08-25T19:19:38.877217Z","end":"2026-08-25T19:19:39.240634Z","steps":["trace[1915447207] 'process raft request' (duration: 362.978116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.240792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.789491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 ","response":"range_response_count:11 size:5916"} {"level":"info","ts":"2026-08-25T19:19:39.24084Z","caller":"traceutil/trace.go:171","msg":"trace[1347578525] range","detail":"{range_begin:/registry/roles/kube-system/; range_end:/registry/roles/kube-system0; response_count:11; response_revision:7313; }","duration":"318.868722ms","start":"2026-08-25T19:19:38.92196Z","end":"2026-08-25T19:19:39.240829Z","steps":["trace[1347578525] 'agreement among raft nodes before linearized reading' (duration: 318.670928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.240873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.921941Z","time spent":"318.923244ms","remote":"127.0.0.1:37246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":11,"response size":5939,"request content":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-08-25T19:19:39.240945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.877207Z","time spent":"363.560499ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:19:39.24122Z","caller":"traceutil/trace.go:171","msg":"trace[653171388] transaction","detail":"{read_only:false; response_revision:7313; number_of_response:1; }","duration":"363.969609ms","start":"2026-08-25T19:19:38.877239Z","end":"2026-08-25T19:19:39.241208Z","steps":["trace[653171388] 'process raft request' (duration: 363.13065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.241288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.87723Z","time spent":"364.02388ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:19:39.24197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.569531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036305"} {"level":"info","ts":"2026-08-25T19:19:39.242045Z","caller":"traceutil/trace.go:171","msg":"trace[1427404896] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:7313; }","duration":"342.683014ms","start":"2026-08-25T19:19:38.899347Z","end":"2026-08-25T19:19:39.24203Z","steps":["trace[1427404896] 'agreement among raft nodes before linearized reading' (duration: 341.642041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.242081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.899328Z","time spent":"342.745405ms","remote":"127.0.0.1:36990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":1036328,"request content":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" "} {"level":"warn","ts":"2026-08-25T19:19:39.242159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.838387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/\" range_end:\"/registry/configmaps/local-path-storage0\" limit:500 ","response":"range_response_count:2 size:3217"} {"level":"info","ts":"2026-08-25T19:19:39.242315Z","caller":"traceutil/trace.go:171","msg":"trace[2058048064] range","detail":"{range_begin:/registry/configmaps/local-path-storage/; range_end:/registry/configmaps/local-path-storage0; response_count:2; response_revision:7313; }","duration":"317.137534ms","start":"2026-08-25T19:19:38.925145Z","end":"2026-08-25T19:19:39.242282Z","steps":["trace[2058048064] 'agreement among raft nodes before linearized reading' (duration: 316.669434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:19:39.242468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:19:38.925131Z","time spent":"317.287127ms","remote":"127.0.0.1:37004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":2,"response size":3240,"request content":"key:\"/registry/configmaps/local-path-storage/\" range_end:\"/registry/configmaps/local-path-storage0\" limit:500 "} {"level":"info","ts":"2026-08-25T19:19:59.895019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5632} {"level":"info","ts":"2026-08-25T19:19:59.978937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5632,"took":"82.407674ms","hash":4204485539,"current-db-size-bytes":54657024,"current-db-size":"55 MB","current-db-size-in-use-bytes":18452480,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-25T19:19:59.978997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4204485539,"revision":5632,"compact-revision":2912} {"level":"warn","ts":"2026-08-25T19:20:11.817866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17870178018735714241,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-25T19:20:11.985328Z","caller":"traceutil/trace.go:171","msg":"trace[790287609] linearizableReadLoop","detail":"{readStateIndex:7746; appliedIndex:7745; }","duration":"668.385651ms","start":"2026-08-25T19:20:11.316919Z","end":"2026-08-25T19:20:11.985304Z","steps":["trace[790287609] 'read index received' (duration: 668.193267ms)","trace[790287609] 'applied index is now lower than readState.Index' (duration: 191.634µs)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:20:11.985591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.972224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/keystone-keystone-db-init\" ","response":"range_response_count:1 size:752"} {"level":"info","ts":"2026-08-25T19:20:11.985632Z","caller":"traceutil/trace.go:171","msg":"trace[1648765890] range","detail":"{range_begin:/registry/rolebindings/openstack/keystone-keystone-db-init; range_end:; response_count:1; response_revision:7470; }","duration":"614.067976ms","start":"2026-08-25T19:20:11.371554Z","end":"2026-08-25T19:20:11.985622Z","steps":["trace[1648765890] 'agreement among raft nodes before linearized reading' (duration: 613.906592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:11.985648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.443773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/keystone-keystone-db-sync\" ","response":"range_response_count:1 size:752"} {"level":"warn","ts":"2026-08-25T19:20:11.985686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.577446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:20:11.985724Z","caller":"traceutil/trace.go:171","msg":"trace[1122906970] range","detail":"{range_begin:/registry/rolebindings/openstack/keystone-keystone-db-sync; range_end:; response_count:1; response_revision:7470; }","duration":"576.551885ms","start":"2026-08-25T19:20:11.409155Z","end":"2026-08-25T19:20:11.985707Z","steps":["trace[1122906970] 'agreement among raft nodes before linearized reading' (duration: 576.335449ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:20:11.985757Z","caller":"traceutil/trace.go:171","msg":"trace[74668730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7470; }","duration":"376.686048ms","start":"2026-08-25T19:20:11.609051Z","end":"2026-08-25T19:20:11.985737Z","steps":["trace[74668730] 'agreement among raft nodes before linearized reading' (duration: 376.568435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:11.985759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.769463ms","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-08-25T19:20:11.985775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.40914Z","time spent":"576.621457ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":775,"request content":"key:\"/registry/rolebindings/openstack/keystone-keystone-db-sync\" "} {"level":"info","ts":"2026-08-25T19:20:11.985803Z","caller":"traceutil/trace.go:171","msg":"trace[1419743769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7470; }","duration":"573.843625ms","start":"2026-08-25T19:20:11.411951Z","end":"2026-08-25T19:20:11.985795Z","steps":["trace[1419743769] 'agreement among raft nodes before linearized reading' (duration: 573.751613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:11.985805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.609028Z","time spent":"376.76668ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-25T19:20:11.985803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.126261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-25T19:20:11.985836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.411932Z","time spent":"573.898096ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-25T19:20:11.985791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.808592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-25T19:20:11.985883Z","caller":"traceutil/trace.go:171","msg":"trace[213753556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7470; }","duration":"578.236664ms","start":"2026-08-25T19:20:11.407625Z","end":"2026-08-25T19:20:11.985861Z","steps":["trace[213753556] 'agreement among raft nodes before linearized reading' (duration: 577.978218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:11.985927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.407621Z","time spent":"578.293885ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-25T19:20:11.985941Z","caller":"traceutil/trace.go:171","msg":"trace[1575646143] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7470; }","duration":"669.030637ms","start":"2026-08-25T19:20:11.316879Z","end":"2026-08-25T19:20:11.985909Z","steps":["trace[1575646143] 'agreement among raft nodes before linearized reading' (duration: 668.565265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:11.986021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.316861Z","time spent":"669.14433ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-25T19:20:11.985817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.603624ms","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-08-25T19:20:11.986142Z","caller":"traceutil/trace.go:171","msg":"trace[976018846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7470; }","duration":"579.959911ms","start":"2026-08-25T19:20:11.406163Z","end":"2026-08-25T19:20:11.986123Z","steps":["trace[976018846] 'agreement among raft nodes before linearized reading' (duration: 579.371618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:11.98567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.371529Z","time spent":"614.132067ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":775,"request content":"key:\"/registry/rolebindings/openstack/keystone-keystone-db-init\" "} {"level":"warn","ts":"2026-08-25T19:20:11.98621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.406159Z","time spent":"580.030993ms","remote":"127.0.0.1:37184","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-08-25T19:20:11.985409Z","caller":"traceutil/trace.go:171","msg":"trace[1524113272] transaction","detail":"{read_only:false; response_revision:7470; number_of_response:1; }","duration":"670.154972ms","start":"2026-08-25T19:20:11.315221Z","end":"2026-08-25T19:20:11.985376Z","steps":["trace[1524113272] 'process raft request' (duration: 669.946977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:11.986464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.315208Z","time spent":"671.176985ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-25T19:20:11.986547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.067279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-25T19:20:11.986856Z","caller":"traceutil/trace.go:171","msg":"trace[646475981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7470; }","duration":"582.401477ms","start":"2026-08-25T19:20:11.404433Z","end":"2026-08-25T19:20:11.986834Z","steps":["trace[646475981] 'agreement among raft nodes before linearized reading' (duration: 581.109589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:11.986916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:11.404414Z","time spent":"582.485378ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-25T19:20:17.342942Z","caller":"traceutil/trace.go:171","msg":"trace[58812847] linearizableReadLoop","detail":"{readStateIndex:7776; appliedIndex:7775; }","duration":"317.508927ms","start":"2026-08-25T19:20:17.025397Z","end":"2026-08-25T19:20:17.342906Z","steps":["trace[58812847] 'read index received' (duration: 282.529722ms)","trace[58812847] 'applied index is now lower than readState.Index' (duration: 34.977795ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:20:17.343088Z","caller":"traceutil/trace.go:171","msg":"trace[175679979] transaction","detail":"{read_only:false; response_revision:7499; number_of_response:1; }","duration":"388.906258ms","start":"2026-08-25T19:20:16.954167Z","end":"2026-08-25T19:20:17.343073Z","steps":["trace[175679979] 'process raft request' (duration: 353.822391ms)","trace[175679979] 'compare' (duration: 34.544575ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:20:17.343453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.604538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/horizon-db-init\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-08-25T19:20:17.343482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.533257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:20:17.343538Z","caller":"traceutil/trace.go:171","msg":"trace[1043828850] range","detail":"{range_begin:/registry/serviceaccounts/openstack/horizon-db-init; range_end:; response_count:1; response_revision:7499; }","duration":"312.747861ms","start":"2026-08-25T19:20:17.030764Z","end":"2026-08-25T19:20:17.343512Z","steps":["trace[1043828850] 'agreement among raft nodes before linearized reading' (duration: 312.541807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:17.343542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:16.954158Z","time spent":"388.964429ms","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-25T19:20:17.34356Z","caller":"traceutil/trace.go:171","msg":"trace[1429911161] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:7499; }","duration":"143.632939ms","start":"2026-08-25T19:20:17.199908Z","end":"2026-08-25T19:20:17.343541Z","steps":["trace[1429911161] 'agreement among raft nodes before linearized reading' (duration: 143.486866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:17.343585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:17.030738Z","time spent":"312.839604ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":556,"request content":"key:\"/registry/serviceaccounts/openstack/horizon-db-init\" "} {"level":"warn","ts":"2026-08-25T19:20:17.343743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.272315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/horizon\" ","response":"range_response_count:1 size:517"} {"level":"info","ts":"2026-08-25T19:20:17.344052Z","caller":"traceutil/trace.go:171","msg":"trace[1254270389] range","detail":"{range_begin:/registry/serviceaccounts/openstack/horizon; range_end:; response_count:1; response_revision:7499; }","duration":"318.648232ms","start":"2026-08-25T19:20:17.025352Z","end":"2026-08-25T19:20:17.344Z","steps":["trace[1254270389] 'agreement among raft nodes before linearized reading' (duration: 318.035659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:17.344195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-25T19:20:17.025328Z","time spent":"318.853477ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":540,"request content":"key:\"/registry/serviceaccounts/openstack/horizon\" "} {"level":"info","ts":"2026-08-25T19:20:18.219726Z","caller":"traceutil/trace.go:171","msg":"trace[337018622] transaction","detail":"{read_only:false; response_revision:7502; number_of_response:1; }","duration":"169.124511ms","start":"2026-08-25T19:20:18.050565Z","end":"2026-08-25T19:20:18.21969Z","steps":["trace[337018622] 'process raft request' (duration: 146.297989ms)","trace[337018622] 'compare' (duration: 22.354951ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:20:18.220235Z","caller":"traceutil/trace.go:171","msg":"trace[36953819] transaction","detail":"{read_only:false; response_revision:7504; number_of_response:1; }","duration":"168.61584ms","start":"2026-08-25T19:20:18.051603Z","end":"2026-08-25T19:20:18.220219Z","steps":["trace[36953819] 'process raft request' (duration: 168.521588ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:20:18.220304Z","caller":"traceutil/trace.go:171","msg":"trace[1918557551] linearizableReadLoop","detail":"{readStateIndex:7782; appliedIndex:7777; }","duration":"159.25316ms","start":"2026-08-25T19:20:18.061012Z","end":"2026-08-25T19:20:18.220265Z","steps":["trace[1918557551] 'read index received' (duration: 24.770286ms)","trace[1918557551] 'applied index is now lower than readState.Index' (duration: 134.482014ms)"],"step_count":2} {"level":"info","ts":"2026-08-25T19:20:18.220441Z","caller":"traceutil/trace.go:171","msg":"trace[616721001] transaction","detail":"{read_only:false; response_revision:7505; number_of_response:1; }","duration":"168.60504ms","start":"2026-08-25T19:20:18.051825Z","end":"2026-08-25T19:20:18.22043Z","steps":["trace[616721001] 'process raft request' (duration: 168.360585ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:20:18.220564Z","caller":"traceutil/trace.go:171","msg":"trace[1969493805] transaction","detail":"{read_only:false; response_revision:7503; number_of_response:1; }","duration":"169.924439ms","start":"2026-08-25T19:20:18.050597Z","end":"2026-08-25T19:20:18.220521Z","steps":["trace[1969493805] 'process raft request' (duration: 169.007689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:18.220779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.747631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-credential-rotate\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-25T19:20:18.220827Z","caller":"traceutil/trace.go:171","msg":"trace[1019707400] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-credential-rotate; range_end:; response_count:1; response_revision:7505; }","duration":"159.838153ms","start":"2026-08-25T19:20:18.060975Z","end":"2026-08-25T19:20:18.220813Z","steps":["trace[1019707400] 'agreement among raft nodes before linearized reading' (duration: 159.637579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:18.220903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.115969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-25T19:20:18.220953Z","caller":"traceutil/trace.go:171","msg":"trace[142773439] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:7505; }","duration":"127.198091ms","start":"2026-08-25T19:20:18.093743Z","end":"2026-08-25T19:20:18.220941Z","steps":["trace[142773439] 'agreement among raft nodes before linearized reading' (duration: 127.034728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:18.494259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.296853ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-25T19:20:18.494661Z","caller":"traceutil/trace.go:171","msg":"trace[549946599] linearizableReadLoop","detail":"{readStateIndex:7783; appliedIndex:7782; }","duration":"200.31321ms","start":"2026-08-25T19:20:18.294318Z","end":"2026-08-25T19:20:18.494631Z","steps":["trace[549946599] 'read index received' (duration: 31.533407ms)","trace[549946599] 'applied index is now lower than readState.Index' (duration: 168.776773ms)"],"step_count":2} {"level":"warn","ts":"2026-08-25T19:20:18.495467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.973015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-08-25T19:20:18.495493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.617577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-25T19:20:18.49556Z","caller":"traceutil/trace.go:171","msg":"trace[186238656] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:7505; }","duration":"139.080787ms","start":"2026-08-25T19:20:18.356449Z","end":"2026-08-25T19:20:18.49553Z","steps":["trace[186238656] 'agreement among raft nodes before linearized reading' (duration: 138.843852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-25T19:20:18.495511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.096572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-db-init\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-08-25T19:20:18.496229Z","caller":"traceutil/trace.go:171","msg":"trace[201712826] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-db-init; range_end:; response_count:1; response_revision:7505; }","duration":"149.85155ms","start":"2026-08-25T19:20:18.346353Z","end":"2026-08-25T19:20:18.496204Z","steps":["trace[201712826] 'agreement among raft nodes before linearized reading' (duration: 148.902588ms)"],"step_count":1} {"level":"info","ts":"2026-08-25T19:20:18.496283Z","caller":"traceutil/trace.go:171","msg":"trace[2011300130] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:7505; }","duration":"201.332582ms","start":"2026-08-25T19:20:18.294229Z","end":"2026-08-25T19:20:18.495562Z","steps":["trace[2011300130] 'agreement among raft nodes before linearized reading' (duration: 200.613176ms)"],"step_count":1}