{"level":"warn","ts":"2026-03-12T19:15:01.532942Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-12T19:15:01.533043Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-03-12T19:15:01.533115Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-12T19:15:01.53313Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-03-12T19:15:01.533149Z","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-03-12T19:15:01.533872Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-03-12T19:15:01.533956Z","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":16,"max-cpu-available":16,"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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-03-12T19:15:01.539517Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.305388ms"} {"level":"info","ts":"2026-03-12T19:15:01.555362Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-03-12T19:15:01.555482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-03-12T19:15:01.555506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-03-12T19:15:01.555522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-12T19:15:01.555533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-03-12T19:15:01.55558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-03-12T19:15:01.566081Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-12T19:15:01.568102Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-12T19:15:01.569425Z","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-03-12T19:15:01.571965Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-12T19:15:01.573059Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-12T19:15:01.575041Z","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-03-12T19:15:01.575106Z","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-03-12T19:15:01.575135Z","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-03-12T19:15:01.575521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-03-12T19:15:01.575641Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-03-12T19:15:01.577648Z","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-03-12T19:15:01.577942Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-12T19:15:01.577973Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-03-12T19:15:01.57801Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-12T19:15:01.578049Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-03-12T19:15:02.357658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-03-12T19:15:02.35774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-12T19:15:02.357782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-03-12T19:15:02.357813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-03-12T19:15:02.357825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-03-12T19:15:02.357842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-03-12T19:15:02.357858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-03-12T19:15:02.35913Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-03-12T19:15:02.359169Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-12T19:15:02.359322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-12T19:15:02.359323Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:15:02.359563Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-12T19:15:02.3596Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-12T19:15:02.360746Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:15:02.360988Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:15:02.361055Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:15:02.36127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-12T19:15:02.362728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"warn","ts":"2026-03-12T19:15:17.235753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388947325368148,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-12T19:15:17.351626Z","caller":"traceutil/trace.go:171","msg":"trace[1545545706] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:376; }","duration":"616.78875ms","start":"2026-03-12T19:15:16.734796Z","end":"2026-03-12T19:15:17.351585Z","steps":["trace[1545545706] 'read index received' (duration: 616.501404ms)","trace[1545545706] 'applied index is now lower than readState.Index' (duration: 286.126µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:15:17.351711Z","caller":"traceutil/trace.go:171","msg":"trace[1124164352] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"672.714559ms","start":"2026-03-12T19:15:16.678947Z","end":"2026-03-12T19:15:17.351661Z","steps":["trace[1124164352] 'process raft request' (duration: 672.450613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:17.351897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.055526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:15:17.352035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.860705ms","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-03-12T19:15:17.352206Z","caller":"traceutil/trace.go:171","msg":"trace[1560779607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:364; }","duration":"101.272274ms","start":"2026-03-12T19:15:17.250918Z","end":"2026-03-12T19:15:17.352191Z","steps":["trace[1560779607] 'agreement among raft nodes before linearized reading' (duration: 100.867225ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:17.352065Z","caller":"traceutil/trace.go:171","msg":"trace[1383339342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:364; }","duration":"617.277311ms","start":"2026-03-12T19:15:16.734762Z","end":"2026-03-12T19:15:17.35204Z","steps":["trace[1383339342] 'agreement among raft nodes before linearized reading' (duration: 617.004885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:17.352338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:16.734749Z","time spent":"617.573117ms","remote":"127.0.0.1:60212","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-03-12T19:15:17.352427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:16.678909Z","time spent":"672.896153ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:15:18.133529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.317132ms","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-03-12T19:15:18.133728Z","caller":"traceutil/trace.go:171","msg":"trace[626766306] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:368; }","duration":"437.547707ms","start":"2026-03-12T19:15:17.696155Z","end":"2026-03-12T19:15:18.133703Z","steps":["trace[626766306] 'range keys from in-memory index tree' (duration: 437.290501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:18.134582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.409394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:15:18.134805Z","caller":"traceutil/trace.go:171","msg":"trace[1803701874] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"415.128909ms","start":"2026-03-12T19:15:17.719656Z","end":"2026-03-12T19:15:18.134785Z","steps":["trace[1803701874] 'process raft request' (duration: 415.066578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:18.134872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:17.719615Z","time spent":"415.232052ms","remote":"127.0.0.1:60414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4402,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:15:18.134855Z","caller":"traceutil/trace.go:171","msg":"trace[1360101538] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"494.942648ms","start":"2026-03-12T19:15:17.639859Z","end":"2026-03-12T19:15:18.134802Z","steps":["trace[1360101538] 'read index received' (duration: 15.054373ms)","trace[1360101538] 'applied index is now lower than readState.Index' (duration: 479.881955ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:15:18.134812Z","caller":"traceutil/trace.go:171","msg":"trace[705554885] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"603.734299ms","start":"2026-03-12T19:15:17.531025Z","end":"2026-03-12T19:15:18.134759Z","steps":["trace[705554885] 'process raft request' (duration: 123.886595ms)","trace[705554885] 'compare' (duration: 479.260721ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:15:18.135129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.253665ms","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-03-12T19:15:18.135205Z","caller":"traceutil/trace.go:171","msg":"trace[1588708334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"495.391738ms","start":"2026-03-12T19:15:17.63978Z","end":"2026-03-12T19:15:18.135171Z","steps":["trace[1588708334] 'agreement among raft nodes before linearized reading' (duration: 495.234714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:18.13527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.024519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:15:18.135229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:17.53101Z","time spent":"604.107308ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:15:18.135296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:17.639749Z","time spent":"495.536751ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-12T19:15:18.135388Z","caller":"traceutil/trace.go:171","msg":"trace[673891499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"402.10325ms","start":"2026-03-12T19:15:17.733191Z","end":"2026-03-12T19:15:18.135294Z","steps":["trace[673891499] 'agreement among raft nodes before linearized reading' (duration: 402.015159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:18.135311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.714149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-12T19:15:18.135435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:17.733178Z","time spent":"402.248663ms","remote":"127.0.0.1:60208","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-03-12T19:15:18.135388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.762665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4416"} {"level":"info","ts":"2026-03-12T19:15:18.136204Z","caller":"traceutil/trace.go:171","msg":"trace[994718667] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:370; }","duration":"394.652045ms","start":"2026-03-12T19:15:17.741517Z","end":"2026-03-12T19:15:18.136169Z","steps":["trace[994718667] 'agreement among raft nodes before linearized reading' (duration: 393.685923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:18.13625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:17.74149Z","time spent":"394.745608ms","remote":"127.0.0.1:60414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4439,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-12T19:15:18.135511Z","caller":"traceutil/trace.go:171","msg":"trace[931336793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"469.993825ms","start":"2026-03-12T19:15:17.66545Z","end":"2026-03-12T19:15:18.135444Z","steps":["trace[931336793] 'agreement among raft nodes before linearized reading' (duration: 469.627347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:18.136309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:17.665439Z","time spent":"470.860865ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-12T19:15:32.550586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.753028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-12T19:15:32.550625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.660665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-03-12T19:15:32.550734Z","caller":"traceutil/trace.go:171","msg":"trace[1072006319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"332.943602ms","start":"2026-03-12T19:15:32.217758Z","end":"2026-03-12T19:15:32.550702Z","steps":["trace[1072006319] 'range keys from in-memory index tree' (duration: 332.630695ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:32.550769Z","caller":"traceutil/trace.go:171","msg":"trace[1135284852] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:465; }","duration":"309.86105ms","start":"2026-03-12T19:15:32.240876Z","end":"2026-03-12T19:15:32.550737Z","steps":["trace[1135284852] 'range keys from in-memory index tree' (duration: 309.548883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:32.550758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.179967ms","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-03-12T19:15:32.550833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:32.240858Z","time spent":"309.963402ms","remote":"127.0.0.1:60448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-03-12T19:15:32.550832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.962548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"warn","ts":"2026-03-12T19:15:32.550884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.130048ms","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-03-12T19:15:32.5509Z","caller":"traceutil/trace.go:171","msg":"trace[1513609663] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:465; }","duration":"253.04624ms","start":"2026-03-12T19:15:32.297833Z","end":"2026-03-12T19:15:32.55088Z","steps":["trace[1513609663] 'range keys from in-memory index tree' (duration: 252.509418ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:32.550898Z","caller":"traceutil/trace.go:171","msg":"trace[832999101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"338.397482ms","start":"2026-03-12T19:15:32.212469Z","end":"2026-03-12T19:15:32.550866Z","steps":["trace[832999101] 'range keys from in-memory index tree' (duration: 338.014424ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:32.550936Z","caller":"traceutil/trace.go:171","msg":"trace[88246073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"334.233671ms","start":"2026-03-12T19:15:32.216683Z","end":"2026-03-12T19:15:32.550917Z","steps":["trace[88246073] 'range keys from in-memory index tree' (duration: 333.978974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:32.550958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:32.212424Z","time spent":"338.522825ms","remote":"127.0.0.1:60552","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-03-12T19:15:32.550976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:32.216654Z","time spent":"334.314582ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-12T19:15:32.550794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:32.217746Z","time spent":"333.036764ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-12T19:15:32.551003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.721666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-12T19:15:32.551139Z","caller":"traceutil/trace.go:171","msg":"trace[10524863] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:465; }","duration":"309.919651ms","start":"2026-03-12T19:15:32.241189Z","end":"2026-03-12T19:15:32.551109Z","steps":["trace[10524863] 'range keys from in-memory index tree' (duration: 309.476821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:32.551208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:15:32.241174Z","time spent":"310.023313ms","remote":"127.0.0.1:60448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-03-12T19:15:40.647761Z","caller":"traceutil/trace.go:171","msg":"trace[1818186146] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"124.429339ms","start":"2026-03-12T19:15:40.523284Z","end":"2026-03-12T19:15:40.647714Z","steps":["trace[1818186146] 'process raft request' (duration: 124.236635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:40.866213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.070301ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-12T19:15:40.866413Z","caller":"traceutil/trace.go:171","msg":"trace[362845615] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"171.101034ms","start":"2026-03-12T19:15:40.695282Z","end":"2026-03-12T19:15:40.866383Z","steps":["trace[362845615] 'read index received' (duration: 752.656µs)","trace[362845615] 'applied index is now lower than readState.Index' (duration: 170.346138ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:15:40.866688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.41551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-12T19:15:40.866754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.953502ms","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-03-12T19:15:40.866793Z","caller":"traceutil/trace.go:171","msg":"trace[449019613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"171.596344ms","start":"2026-03-12T19:15:40.695175Z","end":"2026-03-12T19:15:40.866772Z","steps":["trace[449019613] 'agreement among raft nodes before linearized reading' (duration: 171.304218ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:40.866834Z","caller":"traceutil/trace.go:171","msg":"trace[1827342381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"167.052224ms","start":"2026-03-12T19:15:40.699761Z","end":"2026-03-12T19:15:40.866814Z","steps":["trace[1827342381] 'agreement among raft nodes before linearized reading' (duration: 166.814679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:40.866848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.986953ms","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-03-12T19:15:40.866935Z","caller":"traceutil/trace.go:171","msg":"trace[149047952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"167.147846ms","start":"2026-03-12T19:15:40.699766Z","end":"2026-03-12T19:15:40.866914Z","steps":["trace[149047952] 'agreement among raft nodes before linearized reading' (duration: 166.836719ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:41.758404Z","caller":"traceutil/trace.go:171","msg":"trace[1558507803] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"208.630025ms","start":"2026-03-12T19:15:41.549736Z","end":"2026-03-12T19:15:41.758366Z","steps":["trace[1558507803] 'process raft request' (duration: 208.38696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:42.295399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.524938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:15:42.296823Z","caller":"traceutil/trace.go:171","msg":"trace[561828889] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:517; }","duration":"125.024181ms","start":"2026-03-12T19:15:42.171755Z","end":"2026-03-12T19:15:42.296779Z","steps":["trace[561828889] 'range keys from in-memory index tree' (duration: 123.338775ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:54.811725Z","caller":"traceutil/trace.go:171","msg":"trace[968517479] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"122.813053ms","start":"2026-03-12T19:15:54.688889Z","end":"2026-03-12T19:15:54.811702Z","steps":["trace[968517479] 'process raft request' (duration: 122.750422ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:54.811801Z","caller":"traceutil/trace.go:171","msg":"trace[986514756] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"122.958796ms","start":"2026-03-12T19:15:54.688783Z","end":"2026-03-12T19:15:54.811742Z","steps":["trace[986514756] 'process raft request' (duration: 56.788019ms)","trace[986514756] 'compare' (duration: 64.98015ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:15:54.814197Z","caller":"traceutil/trace.go:171","msg":"trace[2086931827] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"123.585131ms","start":"2026-03-12T19:15:54.690593Z","end":"2026-03-12T19:15:54.814178Z","steps":["trace[2086931827] 'process raft request' (duration: 123.447408ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:54.814484Z","caller":"traceutil/trace.go:171","msg":"trace[1429102166] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:836; }","duration":"121.476364ms","start":"2026-03-12T19:15:54.692994Z","end":"2026-03-12T19:15:54.814471Z","steps":["trace[1429102166] 'read index received' (duration: 52.598156ms)","trace[1429102166] 'applied index is now lower than readState.Index' (duration: 68.877568ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:15:54.814525Z","caller":"traceutil/trace.go:171","msg":"trace[1648916606] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"121.219799ms","start":"2026-03-12T19:15:54.693257Z","end":"2026-03-12T19:15:54.814476Z","steps":["trace[1648916606] 'process raft request' (duration: 120.875431ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:15:54.814585Z","caller":"traceutil/trace.go:171","msg":"trace[642748400] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"119.793126ms","start":"2026-03-12T19:15:54.694781Z","end":"2026-03-12T19:15:54.814574Z","steps":["trace[642748400] 'process raft request' (duration: 119.581351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:15:54.814739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.71913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-f8csx\" ","response":"range_response_count:1 size:4872"} {"level":"info","ts":"2026-03-12T19:15:54.81477Z","caller":"traceutil/trace.go:171","msg":"trace[2127461845] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-f8csx; range_end:; response_count:1; response_revision:819; }","duration":"121.812951ms","start":"2026-03-12T19:15:54.692951Z","end":"2026-03-12T19:15:54.814764Z","steps":["trace[2127461845] 'agreement among raft nodes before linearized reading' (duration: 121.664018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:16:01.879195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.025782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:16:01.879464Z","caller":"traceutil/trace.go:171","msg":"trace[2107881996] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"199.492164ms","start":"2026-03-12T19:16:01.679934Z","end":"2026-03-12T19:16:01.879426Z","steps":["trace[2107881996] 'process raft request' (duration: 33.103015ms)","trace[2107881996] 'compare' (duration: 165.820256ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:16:01.879526Z","caller":"traceutil/trace.go:171","msg":"trace[832271034] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"198.45029ms","start":"2026-03-12T19:16:01.681051Z","end":"2026-03-12T19:16:01.879501Z","steps":["trace[832271034] 'process raft request' (duration: 198.327517ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:16:10.991954Z","caller":"traceutil/trace.go:171","msg":"trace[1864216265] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"122.079677ms","start":"2026-03-12T19:16:10.869843Z","end":"2026-03-12T19:16:10.991923Z","steps":["trace[1864216265] 'process raft request' (duration: 121.867262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:16:11.269477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.218199ms","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-03-12T19:16:11.269539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.699848ms","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-03-12T19:16:11.269597Z","caller":"traceutil/trace.go:171","msg":"trace[386182697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"195.397043ms","start":"2026-03-12T19:16:11.074163Z","end":"2026-03-12T19:16:11.26956Z","steps":["trace[386182697] 'range keys from in-memory index tree' (duration: 194.992864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:16:11.269561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.572963ms","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-03-12T19:16:11.269671Z","caller":"traceutil/trace.go:171","msg":"trace[875716763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"190.918873ms","start":"2026-03-12T19:16:11.078719Z","end":"2026-03-12T19:16:11.269638Z","steps":["trace[875716763] 'range keys from in-memory index tree' (duration: 190.479064ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:16:11.269731Z","caller":"traceutil/trace.go:171","msg":"trace[1079141203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"189.711967ms","start":"2026-03-12T19:16:11.079948Z","end":"2026-03-12T19:16:11.26966Z","steps":["trace[1079141203] 'range keys from in-memory index tree' (duration: 189.291927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:16:26.988161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.969828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-lggkn\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-03-12T19:16:26.988347Z","caller":"traceutil/trace.go:171","msg":"trace[1363675926] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-lggkn; range_end:; response_count:1; response_revision:1057; }","duration":"188.307454ms","start":"2026-03-12T19:16:26.799981Z","end":"2026-03-12T19:16:26.988289Z","steps":["trace[1363675926] 'range keys from in-memory index tree' (duration: 187.711192ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:16:35.717612Z","caller":"traceutil/trace.go:171","msg":"trace[1158350865] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"199.976056ms","start":"2026-03-12T19:16:35.517599Z","end":"2026-03-12T19:16:35.717575Z","steps":["trace[1158350865] 'process raft request' (duration: 196.684764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:01.575965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.772756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e4e3556-0b23-42c7-8e74-a18ff7acd12c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-12T19:17:01.576135Z","caller":"traceutil/trace.go:171","msg":"trace[1986855427] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0e4e3556-0b23-42c7-8e74-a18ff7acd12c; range_end:; response_count:1; response_revision:1573; }","duration":"573.016401ms","start":"2026-03-12T19:17:01.00308Z","end":"2026-03-12T19:17:01.576096Z","steps":["trace[1986855427] 'range keys from in-memory index tree' (duration: 572.178633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:01.576208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:01.00305Z","time spent":"573.139395ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e4e3556-0b23-42c7-8e74-a18ff7acd12c\" "} {"level":"info","ts":"2026-03-12T19:17:01.619112Z","caller":"traceutil/trace.go:171","msg":"trace[653536044] linearizableReadLoop","detail":"{readStateIndex:1637; appliedIndex:1636; }","duration":"179.741109ms","start":"2026-03-12T19:17:01.439334Z","end":"2026-03-12T19:17:01.619075Z","steps":["trace[653536044] 'read index received' (duration: 179.550484ms)","trace[653536044] 'applied index is now lower than readState.Index' (duration: 188.885µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:01.619273Z","caller":"traceutil/trace.go:171","msg":"trace[718671229] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"195.693661ms","start":"2026-03-12T19:17:01.423523Z","end":"2026-03-12T19:17:01.619217Z","steps":["trace[718671229] 'process raft request' (duration: 195.325633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:01.619524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.167727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e4e3556-0b23-42c7-8e74-a18ff7acd12c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-12T19:17:01.619631Z","caller":"traceutil/trace.go:171","msg":"trace[1421881688] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0e4e3556-0b23-42c7-8e74-a18ff7acd12c; range_end:; response_count:1; response_revision:1574; }","duration":"180.334951ms","start":"2026-03-12T19:17:01.439269Z","end":"2026-03-12T19:17:01.619604Z","steps":["trace[1421881688] 'agreement among raft nodes before linearized reading' (duration: 180.010354ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:01.774793Z","caller":"traceutil/trace.go:171","msg":"trace[1493601270] linearizableReadLoop","detail":"{readStateIndex:1638; appliedIndex:1637; }","duration":"143.297784ms","start":"2026-03-12T19:17:01.631462Z","end":"2026-03-12T19:17:01.77476Z","steps":["trace[1493601270] 'read index received' (duration: 55.599877ms)","trace[1493601270] 'applied index is now lower than readState.Index' (duration: 87.696716ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:01.77492Z","caller":"traceutil/trace.go:171","msg":"trace[228771644] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"190.085686ms","start":"2026-03-12T19:17:01.58479Z","end":"2026-03-12T19:17:01.774876Z","steps":["trace[228771644] 'process raft request' (duration: 102.338049ms)","trace[228771644] 'compare' (duration: 87.482731ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:17:01.77505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.560779ms","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-03-12T19:17:01.77514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.66533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-12T19:17:01.775157Z","caller":"traceutil/trace.go:171","msg":"trace[501511560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1575; }","duration":"143.711844ms","start":"2026-03-12T19:17:01.631423Z","end":"2026-03-12T19:17:01.775135Z","steps":["trace[501511560] 'agreement among raft nodes before linearized reading' (duration: 143.474248ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:01.7752Z","caller":"traceutil/trace.go:171","msg":"trace[1708212954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1575; }","duration":"142.760532ms","start":"2026-03-12T19:17:01.632426Z","end":"2026-03-12T19:17:01.775186Z","steps":["trace[1708212954] 'agreement among raft nodes before linearized reading' (duration: 142.544736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:01.775425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.957316ms","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-03-12T19:17:01.775475Z","caller":"traceutil/trace.go:171","msg":"trace[472196506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1575; }","duration":"128.028837ms","start":"2026-03-12T19:17:01.647435Z","end":"2026-03-12T19:17:01.775464Z","steps":["trace[472196506] 'agreement among raft nodes before linearized reading' (duration: 127.906164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:02.437761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.54806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:17:02.437925Z","caller":"traceutil/trace.go:171","msg":"trace[926194742] linearizableReadLoop","detail":"{readStateIndex:1643; appliedIndex:1642; }","duration":"571.339235ms","start":"2026-03-12T19:17:01.866562Z","end":"2026-03-12T19:17:02.437901Z","steps":["trace[926194742] 'read index received' (duration: 18.391756ms)","trace[926194742] 'applied index is now lower than readState.Index' (duration: 552.945209ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:02.438049Z","caller":"traceutil/trace.go:171","msg":"trace[808395048] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"573.684197ms","start":"2026-03-12T19:17:01.864352Z","end":"2026-03-12T19:17:02.438036Z","steps":["trace[808395048] 'process raft request' (duration: 20.661647ms)","trace[808395048] 'compare' (duration: 551.941416ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:17:02.438124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:01.864318Z","time spent":"573.769919ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:17:02.438424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.831216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-12T19:17:02.438528Z","caller":"traceutil/trace.go:171","msg":"trace[1561050163] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1580; }","duration":"571.961858ms","start":"2026-03-12T19:17:01.866542Z","end":"2026-03-12T19:17:02.438504Z","steps":["trace[1561050163] 'agreement among raft nodes before linearized reading' (duration: 571.706302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:02.438587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:01.866534Z","time spent":"572.03361ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-12T19:17:11.85264Z","caller":"traceutil/trace.go:171","msg":"trace[1689276410] linearizableReadLoop","detail":"{readStateIndex:1730; appliedIndex:1729; }","duration":"117.862912ms","start":"2026-03-12T19:17:11.734747Z","end":"2026-03-12T19:17:11.85261Z","steps":["trace[1689276410] 'read index received' (duration: 117.588356ms)","trace[1689276410] 'applied index is now lower than readState.Index' (duration: 273.066µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:11.852831Z","caller":"traceutil/trace.go:171","msg":"trace[1025525667] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"195.998058ms","start":"2026-03-12T19:17:11.65676Z","end":"2026-03-12T19:17:11.852758Z","steps":["trace[1025525667] 'process raft request' (duration: 195.64983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:11.852902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.125948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:17:11.853019Z","caller":"traceutil/trace.go:171","msg":"trace[1164356902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1664; }","duration":"118.324042ms","start":"2026-03-12T19:17:11.734679Z","end":"2026-03-12T19:17:11.853003Z","steps":["trace[1164356902] 'agreement among raft nodes before linearized reading' (duration: 118.101787ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:19.637699Z","caller":"traceutil/trace.go:171","msg":"trace[953220969] linearizableReadLoop","detail":"{readStateIndex:1777; appliedIndex:1776; }","duration":"186.128161ms","start":"2026-03-12T19:17:19.451543Z","end":"2026-03-12T19:17:19.637671Z","steps":["trace[953220969] 'read index received' (duration: 185.862596ms)","trace[953220969] 'applied index is now lower than readState.Index' (duration: 264.295µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:19.637788Z","caller":"traceutil/trace.go:171","msg":"trace[131332235] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"725.936969ms","start":"2026-03-12T19:17:18.911812Z","end":"2026-03-12T19:17:19.637749Z","steps":["trace[131332235] 'process raft request' (duration: 725.682113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:19.637989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:18.911784Z","time spent":"726.101253ms","remote":"127.0.0.1:60552","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-03-12T19:17:19.637986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.424727ms","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-03-12T19:17:19.638099Z","caller":"traceutil/trace.go:171","msg":"trace[1289215907] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1710; }","duration":"186.582321ms","start":"2026-03-12T19:17:19.451475Z","end":"2026-03-12T19:17:19.638057Z","steps":["trace[1289215907] 'agreement among raft nodes before linearized reading' (duration: 186.380396ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:46.948076Z","caller":"traceutil/trace.go:171","msg":"trace[587880930] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"248.972831ms","start":"2026-03-12T19:17:46.699062Z","end":"2026-03-12T19:17:46.948034Z","steps":["trace[587880930] 'read index received' (duration: 248.825768ms)","trace[587880930] 'applied index is now lower than readState.Index' (duration: 145.263µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:46.948237Z","caller":"traceutil/trace.go:171","msg":"trace[377542708] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"264.167905ms","start":"2026-03-12T19:17:46.684021Z","end":"2026-03-12T19:17:46.948189Z","steps":["trace[377542708] 'process raft request' (duration: 263.785237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:46.948311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.808459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:17:46.948359Z","caller":"traceutil/trace.go:171","msg":"trace[794459958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"209.901421ms","start":"2026-03-12T19:17:46.738443Z","end":"2026-03-12T19:17:46.948344Z","steps":["trace[794459958] 'agreement among raft nodes before linearized reading' (duration: 209.797659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:46.948474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.362418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-03-12T19:17:46.94847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.37816ms","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-03-12T19:17:46.948534Z","caller":"traceutil/trace.go:171","msg":"trace[352081249] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"109.509341ms","start":"2026-03-12T19:17:46.83901Z","end":"2026-03-12T19:17:46.948519Z","steps":["trace[352081249] 'agreement among raft nodes before linearized reading' (duration: 109.247565ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:46.948587Z","caller":"traceutil/trace.go:171","msg":"trace[421477854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1856; }","duration":"249.565714ms","start":"2026-03-12T19:17:46.698989Z","end":"2026-03-12T19:17:46.948555Z","steps":["trace[421477854] 'agreement among raft nodes before linearized reading' (duration: 249.228727ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:47.136427Z","caller":"traceutil/trace.go:171","msg":"trace[1060191113] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"182.868836ms","start":"2026-03-12T19:17:46.953526Z","end":"2026-03-12T19:17:47.136395Z","steps":["trace[1060191113] 'process raft request' (duration: 178.857898ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:53.114481Z","caller":"traceutil/trace.go:171","msg":"trace[1821147768] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"118.619282ms","start":"2026-03-12T19:17:52.995832Z","end":"2026-03-12T19:17:53.114451Z","steps":["trace[1821147768] 'read index received' (duration: 118.332005ms)","trace[1821147768] 'applied index is now lower than readState.Index' (duration: 285.527µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:53.114649Z","caller":"traceutil/trace.go:171","msg":"trace[1756087231] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"131.71845ms","start":"2026-03-12T19:17:52.982874Z","end":"2026-03-12T19:17:53.114592Z","steps":["trace[1756087231] 'process raft request' (duration: 131.412063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:53.114844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.988349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-12T19:17:53.114924Z","caller":"traceutil/trace.go:171","msg":"trace[1498961700] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1884; }","duration":"119.108672ms","start":"2026-03-12T19:17:52.995792Z","end":"2026-03-12T19:17:53.114901Z","steps":["trace[1498961700] 'agreement among raft nodes before linearized reading' (duration: 118.767975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:53.425511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.714009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:17:53.425655Z","caller":"traceutil/trace.go:171","msg":"trace[580993106] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"244.40952ms","start":"2026-03-12T19:17:53.181222Z","end":"2026-03-12T19:17:53.425631Z","steps":["trace[580993106] 'read index received' (duration: 68.270312ms)","trace[580993106] 'applied index is now lower than readState.Index' (duration: 176.137088ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:53.425807Z","caller":"traceutil/trace.go:171","msg":"trace[859498782] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"309.910282ms","start":"2026-03-12T19:17:53.115855Z","end":"2026-03-12T19:17:53.425766Z","steps":["trace[859498782] 'process raft request' (duration: 133.784184ms)","trace[859498782] 'compare' (duration: 175.353061ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:17:53.425844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.614944ms","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-03-12T19:17:53.425903Z","caller":"traceutil/trace.go:171","msg":"trace[761351374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1885; }","duration":"244.700067ms","start":"2026-03-12T19:17:53.181185Z","end":"2026-03-12T19:17:53.425885Z","steps":["trace[761351374] 'agreement among raft nodes before linearized reading' (duration: 244.512113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:53.425998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:53.115829Z","time spent":"310.058766ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:17:53.651837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.949824ms","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-03-12T19:17:53.651966Z","caller":"traceutil/trace.go:171","msg":"trace[1775494444] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1886; }","duration":"112.152689ms","start":"2026-03-12T19:17:53.539786Z","end":"2026-03-12T19:17:53.651938Z","steps":["trace[1775494444] 'count revisions from in-memory index tree' (duration: 111.803781ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:54.489667Z","caller":"traceutil/trace.go:171","msg":"trace[1735582325] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"346.843676ms","start":"2026-03-12T19:17:54.142793Z","end":"2026-03-12T19:17:54.489637Z","steps":["trace[1735582325] 'process raft request' (duration: 346.645952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:54.489891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:54.142761Z","time spent":"347.018129ms","remote":"127.0.0.1:60552","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-03-12T19:17:54.742795Z","caller":"traceutil/trace.go:171","msg":"trace[674639200] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1963; }","duration":"559.726062ms","start":"2026-03-12T19:17:54.183034Z","end":"2026-03-12T19:17:54.74276Z","steps":["trace[674639200] 'read index received' (duration: 306.470967ms)","trace[674639200] 'applied index is now lower than readState.Index' (duration: 253.253005ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:54.742859Z","caller":"traceutil/trace.go:171","msg":"trace[1007662752] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"599.363315ms","start":"2026-03-12T19:17:54.143459Z","end":"2026-03-12T19:17:54.742822Z","steps":["trace[1007662752] 'process raft request' (duration: 500.10307ms)","trace[1007662752] 'compare' (duration: 98.922647ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:17:54.743048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:54.143443Z","time spent":"599.496257ms","remote":"127.0.0.1:60552","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-03-12T19:17:54.743138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.08828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-03-12T19:17:54.743142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.738222ms","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-03-12T19:17:54.743297Z","caller":"traceutil/trace.go:171","msg":"trace[1671487134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1890; }","duration":"554.948506ms","start":"2026-03-12T19:17:54.188326Z","end":"2026-03-12T19:17:54.743275Z","steps":["trace[1671487134] 'agreement among raft nodes before linearized reading' (duration: 554.62954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:54.743361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:54.188307Z","time spent":"555.037468ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-12T19:17:54.743191Z","caller":"traceutil/trace.go:171","msg":"trace[1288095001] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1890; }","duration":"560.207082ms","start":"2026-03-12T19:17:54.182965Z","end":"2026-03-12T19:17:54.743173Z","steps":["trace[1288095001] 'agreement among raft nodes before linearized reading' (duration: 559.977527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:54.743652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:54.182937Z","time spent":"560.659353ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-12T19:17:54.957449Z","caller":"traceutil/trace.go:171","msg":"trace[1551282265] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"209.440121ms","start":"2026-03-12T19:17:54.747968Z","end":"2026-03-12T19:17:54.957408Z","steps":["trace[1551282265] 'process raft request' (duration: 121.590768ms)","trace[1551282265] 'compare' (duration: 87.339152ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:17:54.957677Z","caller":"traceutil/trace.go:171","msg":"trace[991810295] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"208.661694ms","start":"2026-03-12T19:17:54.748975Z","end":"2026-03-12T19:17:54.957637Z","steps":["trace[991810295] 'process raft request' (duration: 208.314287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:55.56541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.938517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-12T19:17:55.565501Z","caller":"traceutil/trace.go:171","msg":"trace[1970630147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1893; }","duration":"446.093431ms","start":"2026-03-12T19:17:55.119388Z","end":"2026-03-12T19:17:55.565482Z","steps":["trace[1970630147] 'range keys from in-memory index tree' (duration: 445.743523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:55.565543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:55.119364Z","time spent":"446.169112ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-12T19:17:55.565821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.733751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-12T19:17:55.565918Z","caller":"traceutil/trace.go:171","msg":"trace[1278830462] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1893; }","duration":"434.887954ms","start":"2026-03-12T19:17:55.131006Z","end":"2026-03-12T19:17:55.565894Z","steps":["trace[1278830462] 'range keys from in-memory index tree' (duration: 434.548996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:55.565964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:55.130987Z","time spent":"434.966145ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-12T19:17:55.970991Z","caller":"traceutil/trace.go:171","msg":"trace[175109686] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"399.404803ms","start":"2026-03-12T19:17:55.571557Z","end":"2026-03-12T19:17:55.970962Z","steps":["trace[175109686] 'process raft request' (duration: 396.9657ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:55.971159Z","caller":"traceutil/trace.go:171","msg":"trace[1813105214] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1968; }","duration":"394.854022ms","start":"2026-03-12T19:17:55.576286Z","end":"2026-03-12T19:17:55.97114Z","steps":["trace[1813105214] 'read index received' (duration: 65.560333ms)","trace[1813105214] 'applied index is now lower than readState.Index' (duration: 329.292339ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:17:55.971189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:55.571534Z","time spent":"399.544546ms","remote":"127.0.0.1:60552","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-03-12T19:17:55.971131Z","caller":"traceutil/trace.go:171","msg":"trace[52210581] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"399.187318ms","start":"2026-03-12T19:17:55.571915Z","end":"2026-03-12T19:17:55.971102Z","steps":["trace[52210581] 'process raft request' (duration: 398.975814ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:55.971578Z","caller":"traceutil/trace.go:171","msg":"trace[8213519] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"328.373108ms","start":"2026-03-12T19:17:55.643189Z","end":"2026-03-12T19:17:55.971562Z","steps":["trace[8213519] 'process raft request' (duration: 327.882528ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:17:55.971639Z","caller":"traceutil/trace.go:171","msg":"trace[1768812300] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"132.16631ms","start":"2026-03-12T19:17:55.839458Z","end":"2026-03-12T19:17:55.971624Z","steps":["trace[1768812300] 'process raft request' (duration: 132.002366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:55.971702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:55.643173Z","time spent":"328.437581ms","remote":"127.0.0.1:60256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:17:55.97167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:55.5719Z","time spent":"399.674489ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:17:55.971769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.066029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:17:55.971897Z","caller":"traceutil/trace.go:171","msg":"trace[400665022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"237.278164ms","start":"2026-03-12T19:17:55.734593Z","end":"2026-03-12T19:17:55.971871Z","steps":["trace[400665022] 'agreement among raft nodes before linearized reading' (duration: 236.992948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:55.971927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.591928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:17:55.972306Z","caller":"traceutil/trace.go:171","msg":"trace[1121958114] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1897; }","duration":"396.007667ms","start":"2026-03-12T19:17:55.576265Z","end":"2026-03-12T19:17:55.972273Z","steps":["trace[1121958114] 'agreement among raft nodes before linearized reading' (duration: 395.261731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:17:55.972009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.633139ms","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-03-12T19:17:55.972385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:17:55.57625Z","time spent":"396.11904ms","remote":"127.0.0.1:60770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-03-12T19:17:55.97247Z","caller":"traceutil/trace.go:171","msg":"trace[1932260498] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1897; }","duration":"212.1386ms","start":"2026-03-12T19:17:55.760288Z","end":"2026-03-12T19:17:55.972427Z","steps":["trace[1932260498] 'agreement among raft nodes before linearized reading' (duration: 211.471186ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:18:48.954475Z","caller":"traceutil/trace.go:171","msg":"trace[218563459] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"177.968979ms","start":"2026-03-12T19:18:48.776468Z","end":"2026-03-12T19:18:48.954437Z","steps":["trace[218563459] 'process raft request' (duration: 177.740414ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:18:49.650335Z","caller":"traceutil/trace.go:171","msg":"trace[1429079540] linearizableReadLoop","detail":"{readStateIndex:2420; appliedIndex:2419; }","duration":"402.972538ms","start":"2026-03-12T19:18:49.247328Z","end":"2026-03-12T19:18:49.650301Z","steps":["trace[1429079540] 'read index received' (duration: 402.844294ms)","trace[1429079540] 'applied index is now lower than readState.Index' (duration: 126.754µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:18:49.650466Z","caller":"traceutil/trace.go:171","msg":"trace[1989855303] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"403.95266ms","start":"2026-03-12T19:18:49.246428Z","end":"2026-03-12T19:18:49.65038Z","steps":["trace[1989855303] 'process raft request' (duration: 403.643922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:18:49.650619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.156617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-12T19:18:49.65067Z","caller":"traceutil/trace.go:171","msg":"trace[1261356649] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2328; }","duration":"122.291371ms","start":"2026-03-12T19:18:49.528361Z","end":"2026-03-12T19:18:49.650652Z","steps":["trace[1261356649] 'agreement among raft nodes before linearized reading' (duration: 122.121207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:18:49.650781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:18:49.246412Z","time spent":"404.181874ms","remote":"127.0.0.1:60552","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-03-12T19:18:49.650771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.388997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-03-12T19:18:49.650897Z","caller":"traceutil/trace.go:171","msg":"trace[287702569] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2328; }","duration":"403.5512ms","start":"2026-03-12T19:18:49.247305Z","end":"2026-03-12T19:18:49.650856Z","steps":["trace[287702569] 'agreement among raft nodes before linearized reading' (duration: 403.170222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:18:49.65097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:18:49.247296Z","time spent":"403.659062ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-12T19:18:55.175629Z","caller":"traceutil/trace.go:171","msg":"trace[224288109] transaction","detail":"{read_only:false; response_revision:2364; number_of_response:1; }","duration":"127.330312ms","start":"2026-03-12T19:18:55.048254Z","end":"2026-03-12T19:18:55.175584Z","steps":["trace[224288109] 'process raft request' (duration: 76.923059ms)","trace[224288109] 'compare' (duration: 49.965274ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:18:59.168653Z","caller":"traceutil/trace.go:171","msg":"trace[1971922568] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"154.89136ms","start":"2026-03-12T19:18:59.013717Z","end":"2026-03-12T19:18:59.168608Z","steps":["trace[1971922568] 'process raft request' (duration: 154.657225ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:16.527417Z","caller":"traceutil/trace.go:171","msg":"trace[389057426] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"128.415595ms","start":"2026-03-12T19:19:16.398961Z","end":"2026-03-12T19:19:16.527377Z","steps":["trace[389057426] 'process raft request' (duration: 54.158985ms)","trace[389057426] 'compare' (duration: 73.971304ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:19:16.52756Z","caller":"traceutil/trace.go:171","msg":"trace[1968618694] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"122.090747ms","start":"2026-03-12T19:19:16.405432Z","end":"2026-03-12T19:19:16.527523Z","steps":["trace[1968618694] 'process raft request' (duration: 121.853061ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:22.57278Z","caller":"traceutil/trace.go:171","msg":"trace[741114270] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"271.769521ms","start":"2026-03-12T19:19:22.300975Z","end":"2026-03-12T19:19:22.572745Z","steps":["trace[741114270] 'process raft request' (duration: 271.593097ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:22.675789Z","caller":"traceutil/trace.go:171","msg":"trace[203037741] linearizableReadLoop","detail":"{readStateIndex:2611; appliedIndex:2608; }","duration":"121.31643ms","start":"2026-03-12T19:19:22.554446Z","end":"2026-03-12T19:19:22.675763Z","steps":["trace[203037741] 'read index received' (duration: 18.247474ms)","trace[203037741] 'applied index is now lower than readState.Index' (duration: 103.067356ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:19:22.67585Z","caller":"traceutil/trace.go:171","msg":"trace[1923728860] transaction","detail":"{read_only:false; response_revision:2511; number_of_response:1; }","duration":"295.25777ms","start":"2026-03-12T19:19:22.380563Z","end":"2026-03-12T19:19:22.67582Z","steps":["trace[1923728860] 'process raft request' (duration: 256.740999ms)","trace[1923728860] 'compare' (duration: 38.215933ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:19:22.676042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.570545ms","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-03-12T19:19:22.676082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.521614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-12T19:19:22.676111Z","caller":"traceutil/trace.go:171","msg":"trace[953834683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2512; }","duration":"121.714688ms","start":"2026-03-12T19:19:22.554379Z","end":"2026-03-12T19:19:22.676093Z","steps":["trace[953834683] 'agreement among raft nodes before linearized reading' (duration: 121.526104ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:22.676135Z","caller":"traceutil/trace.go:171","msg":"trace[481284797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2512; }","duration":"121.600046ms","start":"2026-03-12T19:19:22.55452Z","end":"2026-03-12T19:19:22.67612Z","steps":["trace[481284797] 'agreement among raft nodes before linearized reading' (duration: 121.409381ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:22.676012Z","caller":"traceutil/trace.go:171","msg":"trace[169858938] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"230.000678ms","start":"2026-03-12T19:19:22.445955Z","end":"2026-03-12T19:19:22.675956Z","steps":["trace[169858938] 'process raft request' (duration: 229.745492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:19:23.404987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.369469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-12T19:19:23.405055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.218128ms","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-03-12T19:19:23.40516Z","caller":"traceutil/trace.go:171","msg":"trace[359391780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2514; }","duration":"117.401443ms","start":"2026-03-12T19:19:23.287732Z","end":"2026-03-12T19:19:23.405134Z","steps":["trace[359391780] 'range keys from in-memory index tree' (duration: 116.877101ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:23.405118Z","caller":"traceutil/trace.go:171","msg":"trace[1742202455] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2514; }","duration":"159.585564ms","start":"2026-03-12T19:19:23.2455Z","end":"2026-03-12T19:19:23.405086Z","steps":["trace[1742202455] 'count revisions from in-memory index tree' (duration: 159.261487ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:23.671222Z","caller":"traceutil/trace.go:171","msg":"trace[462209521] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"259.06657ms","start":"2026-03-12T19:19:23.412106Z","end":"2026-03-12T19:19:23.671173Z","steps":["trace[462209521] 'process raft request' (duration: 259.009319ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:23.671322Z","caller":"traceutil/trace.go:171","msg":"trace[55980959] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"259.175753ms","start":"2026-03-12T19:19:23.412105Z","end":"2026-03-12T19:19:23.67128Z","steps":["trace[55980959] 'process raft request' (duration: 258.805315ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:23.905878Z","caller":"traceutil/trace.go:171","msg":"trace[709825439] linearizableReadLoop","detail":"{readStateIndex:2616; appliedIndex:2613; }","duration":"347.187606ms","start":"2026-03-12T19:19:23.558659Z","end":"2026-03-12T19:19:23.905846Z","steps":["trace[709825439] 'read index received' (duration: 112.272679ms)","trace[709825439] 'applied index is now lower than readState.Index' (duration: 234.913357ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:19:23.905957Z","caller":"traceutil/trace.go:171","msg":"trace[509482372] transaction","detail":"{read_only:false; response_revision:2517; number_of_response:1; }","duration":"382.633379ms","start":"2026-03-12T19:19:23.523277Z","end":"2026-03-12T19:19:23.90591Z","steps":["trace[509482372] 'process raft request' (duration: 380.606784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:19:23.906101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.224741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:19:23.906103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.442741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.189c2e3188758b75\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-03-12T19:19:23.906154Z","caller":"traceutil/trace.go:171","msg":"trace[1671170639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2517; }","duration":"171.358343ms","start":"2026-03-12T19:19:23.734781Z","end":"2026-03-12T19:19:23.90614Z","steps":["trace[1671170639] 'agreement among raft nodes before linearized reading' (duration: 171.258941ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:19:23.906159Z","caller":"traceutil/trace.go:171","msg":"trace[611889494] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.189c2e3188758b75; range_end:; response_count:1; response_revision:2517; }","duration":"347.532563ms","start":"2026-03-12T19:19:23.558613Z","end":"2026-03-12T19:19:23.906146Z","steps":["trace[611889494] 'agreement among raft nodes before linearized reading' (duration: 347.36425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:19:23.906158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:19:23.523203Z","time spent":"382.841743ms","remote":"127.0.0.1:60552","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-03-12T19:19:23.906195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:19:23.558601Z","time spent":"347.584044ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":788,"request content":"key:\"/registry/events/openstack/valkey-node-0.189c2e3188758b75\" "} {"level":"info","ts":"2026-03-12T19:22:01.48032Z","caller":"traceutil/trace.go:171","msg":"trace[617142267] transaction","detail":"{read_only:false; response_revision:3466; number_of_response:1; }","duration":"300.2852ms","start":"2026-03-12T19:22:01.180008Z","end":"2026-03-12T19:22:01.480293Z","steps":["trace[617142267] 'process raft request' (duration: 300.093905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:22:01.480506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:22:01.179986Z","time spent":"300.429612ms","remote":"127.0.0.1:60552","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-03-12T19:22:01.638218Z","caller":"traceutil/trace.go:171","msg":"trace[522148884] linearizableReadLoop","detail":"{readStateIndex:3605; appliedIndex:3603; }","duration":"229.30395ms","start":"2026-03-12T19:22:01.408885Z","end":"2026-03-12T19:22:01.638189Z","steps":["trace[522148884] 'read index received' (duration: 71.218425ms)","trace[522148884] 'applied index is now lower than readState.Index' (duration: 158.084315ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:22:01.638286Z","caller":"traceutil/trace.go:171","msg":"trace[321122274] transaction","detail":"{read_only:false; response_revision:3467; number_of_response:1; }","duration":"355.815805ms","start":"2026-03-12T19:22:01.282408Z","end":"2026-03-12T19:22:01.638224Z","steps":["trace[321122274] 'process raft request' (duration: 355.509668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:22:01.638548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.626847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twdkv\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-12T19:22:01.638679Z","caller":"traceutil/trace.go:171","msg":"trace[420126279] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twdkv; range_end:; response_count:1; response_revision:3467; }","duration":"229.809452ms","start":"2026-03-12T19:22:01.408843Z","end":"2026-03-12T19:22:01.638652Z","steps":["trace[420126279] 'agreement among raft nodes before linearized reading' (duration: 229.450114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:22:01.638848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:22:01.282386Z","time spent":"356.198603ms","remote":"127.0.0.1:60552","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-03-12T19:22:01.639684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.937439ms","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-03-12T19:22:01.639715Z","caller":"traceutil/trace.go:171","msg":"trace[48057465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3468; }","duration":"114.03016ms","start":"2026-03-12T19:22:01.52568Z","end":"2026-03-12T19:22:01.63971Z","steps":["trace[48057465] 'agreement among raft nodes before linearized reading' (duration: 113.960489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:22:01.639736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.973356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-12T19:22:01.639833Z","caller":"traceutil/trace.go:171","msg":"trace[2117924883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3468; }","duration":"134.13709ms","start":"2026-03-12T19:22:01.505674Z","end":"2026-03-12T19:22:01.639811Z","steps":["trace[2117924883] 'agreement among raft nodes before linearized reading' (duration: 133.869244ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:09.872412Z","caller":"traceutil/trace.go:171","msg":"trace[1343987004] transaction","detail":"{read_only:false; response_revision:3905; number_of_response:1; }","duration":"129.829276ms","start":"2026-03-12T19:23:09.74255Z","end":"2026-03-12T19:23:09.872379Z","steps":["trace[1343987004] 'process raft request' (duration: 129.645522ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:16.991162Z","caller":"traceutil/trace.go:171","msg":"trace[1651127720] linearizableReadLoop","detail":"{readStateIndex:4478; appliedIndex:4477; }","duration":"101.609625ms","start":"2026-03-12T19:23:16.889515Z","end":"2026-03-12T19:23:16.991125Z","steps":["trace[1651127720] 'read index received' (duration: 100.802766ms)","trace[1651127720] 'applied index is now lower than readState.Index' (duration: 805.699µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:16.991293Z","caller":"traceutil/trace.go:171","msg":"trace[446364905] transaction","detail":"{read_only:false; response_revision:4303; number_of_response:1; }","duration":"104.289895ms","start":"2026-03-12T19:23:16.886943Z","end":"2026-03-12T19:23:16.991233Z","steps":["trace[446364905] 'process raft request' (duration: 102.616188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:16.991582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.024234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-03-12T19:23:16.99165Z","caller":"traceutil/trace.go:171","msg":"trace[998254853] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4303; }","duration":"102.146157ms","start":"2026-03-12T19:23:16.889487Z","end":"2026-03-12T19:23:16.991633Z","steps":["trace[998254853] 'agreement among raft nodes before linearized reading' (duration: 101.81411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:17.389278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.159335ms","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-03-12T19:23:17.389374Z","caller":"traceutil/trace.go:171","msg":"trace[1997186615] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4314; }","duration":"118.269817ms","start":"2026-03-12T19:23:17.271082Z","end":"2026-03-12T19:23:17.389351Z","steps":["trace[1997186615] 'range keys from in-memory index tree' (duration: 117.953031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:17.389418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.187725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-03-12T19:23:17.389534Z","caller":"traceutil/trace.go:171","msg":"trace[97583356] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4314; }","duration":"285.362818ms","start":"2026-03-12T19:23:17.104144Z","end":"2026-03-12T19:23:17.389507Z","steps":["trace[97583356] 'range keys from in-memory index tree' (duration: 284.973909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:17.389502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.652319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:23:17.389674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.568606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-03-12T19:23:17.38972Z","caller":"traceutil/trace.go:171","msg":"trace[1527622419] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4314; }","duration":"127.644268ms","start":"2026-03-12T19:23:17.262066Z","end":"2026-03-12T19:23:17.38971Z","steps":["trace[1527622419] 'range keys from in-memory index tree' (duration: 127.422253ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:17.389689Z","caller":"traceutil/trace.go:171","msg":"trace[1464773979] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:0; response_revision:4314; }","duration":"266.896714ms","start":"2026-03-12T19:23:17.122717Z","end":"2026-03-12T19:23:17.389613Z","steps":["trace[1464773979] 'range keys from in-memory index tree' (duration: 266.523876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:17.38971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.796638ms","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-03-12T19:23:17.389826Z","caller":"traceutil/trace.go:171","msg":"trace[1697098176] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4314; }","duration":"228.016264ms","start":"2026-03-12T19:23:17.161777Z","end":"2026-03-12T19:23:17.389794Z","steps":["trace[1697098176] 'count revisions from in-memory index tree' (duration: 227.673766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:17.389631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.434482ms","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-03-12T19:23:17.389982Z","caller":"traceutil/trace.go:171","msg":"trace[2104139005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4314; }","duration":"219.843541ms","start":"2026-03-12T19:23:17.170103Z","end":"2026-03-12T19:23:17.389947Z","steps":["trace[2104139005] 'range keys from in-memory index tree' (duration: 219.236137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:23.625441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.966047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-03-12T19:23:23.625422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.029058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-03-12T19:23:23.625545Z","caller":"traceutil/trace.go:171","msg":"trace[1512504894] linearizableReadLoop","detail":"{readStateIndex:4642; appliedIndex:4641; }","duration":"229.899206ms","start":"2026-03-12T19:23:23.395629Z","end":"2026-03-12T19:23:23.625528Z","steps":["trace[1512504894] 'read index received' (duration: 16.651663ms)","trace[1512504894] 'applied index is now lower than readState.Index' (duration: 213.246263ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:23.625583Z","caller":"traceutil/trace.go:171","msg":"trace[1565463395] transaction","detail":"{read_only:false; response_revision:4463; number_of_response:1; }","duration":"253.672418ms","start":"2026-03-12T19:23:23.371866Z","end":"2026-03-12T19:23:23.625539Z","steps":["trace[1565463395] 'process raft request' (duration: 40.514717ms)","trace[1565463395] 'compare' (duration: 212.844054ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:23.625636Z","caller":"traceutil/trace.go:171","msg":"trace[480994980] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4462; }","duration":"263.192351ms","start":"2026-03-12T19:23:23.362331Z","end":"2026-03-12T19:23:23.625523Z","steps":["trace[480994980] 'range keys from in-memory index tree' (duration: 262.814524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:23.625674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.04647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:23:23.625701Z","caller":"traceutil/trace.go:171","msg":"trace[1326306641] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4463; }","duration":"230.102551ms","start":"2026-03-12T19:23:23.395589Z","end":"2026-03-12T19:23:23.625691Z","steps":["trace[1326306641] 'agreement among raft nodes before linearized reading' (duration: 230.029639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:23.625943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.694072ms","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-03-12T19:23:23.62597Z","caller":"traceutil/trace.go:171","msg":"trace[1279481374] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4463; }","duration":"200.721874ms","start":"2026-03-12T19:23:23.425242Z","end":"2026-03-12T19:23:23.625964Z","steps":["trace[1279481374] 'agreement among raft nodes before linearized reading' (duration: 200.625452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:23.626031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.697977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:23:23.626084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.096986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:23:23.626082Z","caller":"traceutil/trace.go:171","msg":"trace[1424911563] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4463; }","duration":"160.774638ms","start":"2026-03-12T19:23:23.465295Z","end":"2026-03-12T19:23:23.626069Z","steps":["trace[1424911563] 'agreement among raft nodes before linearized reading' (duration: 160.691026ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:23.626105Z","caller":"traceutil/trace.go:171","msg":"trace[555169974] range","detail":"{range_begin:/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache; range_end:; response_count:0; response_revision:4463; }","duration":"199.132137ms","start":"2026-03-12T19:23:23.426967Z","end":"2026-03-12T19:23:23.626099Z","steps":["trace[555169974] 'agreement among raft nodes before linearized reading' (duration: 199.091236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:23.62627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.664569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-12T19:23:23.626325Z","caller":"traceutil/trace.go:171","msg":"trace[379088534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4463; }","duration":"199.758931ms","start":"2026-03-12T19:23:23.426554Z","end":"2026-03-12T19:23:23.626313Z","steps":["trace[379088534] 'agreement among raft nodes before linearized reading' (duration: 199.563377ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:23.744671Z","caller":"traceutil/trace.go:171","msg":"trace[1852489680] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"104.056959ms","start":"2026-03-12T19:23:23.640581Z","end":"2026-03-12T19:23:23.744637Z","steps":["trace[1852489680] 'process raft request' (duration: 103.905766ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:23.744863Z","caller":"traceutil/trace.go:171","msg":"trace[172435931] transaction","detail":"{read_only:false; response_revision:4465; number_of_response:1; }","duration":"107.352063ms","start":"2026-03-12T19:23:23.637472Z","end":"2026-03-12T19:23:23.744824Z","steps":["trace[172435931] 'process raft request' (duration: 103.421906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:23.745085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.213111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/loki\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:23:23.745216Z","caller":"traceutil/trace.go:171","msg":"trace[211635930] range","detail":"{range_begin:/registry/configmaps/monitoring/loki; range_end:; response_count:0; response_revision:4466; }","duration":"107.361434ms","start":"2026-03-12T19:23:23.637817Z","end":"2026-03-12T19:23:23.745179Z","steps":["trace[211635930] 'agreement among raft nodes before linearized reading' (duration: 107.120917ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:23.745213Z","caller":"traceutil/trace.go:171","msg":"trace[2129011116] linearizableReadLoop","detail":"{readStateIndex:4644; appliedIndex:4642; }","duration":"106.735179ms","start":"2026-03-12T19:23:23.637834Z","end":"2026-03-12T19:23:23.74457Z","steps":["trace[2129011116] 'read index received' (duration: 37.537731ms)","trace[2129011116] 'applied index is now lower than readState.Index' (duration: 69.195808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:23:23.74543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.631205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-12T19:23:23.745491Z","caller":"traceutil/trace.go:171","msg":"trace[61209445] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4466; }","duration":"105.706166ms","start":"2026-03-12T19:23:23.63976Z","end":"2026-03-12T19:23:23.745466Z","steps":["trace[61209445] 'agreement among raft nodes before linearized reading' (duration: 105.577223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:23.74594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.856419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75449"} {"level":"info","ts":"2026-03-12T19:23:23.74606Z","caller":"traceutil/trace.go:171","msg":"trace[1281949589] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4466; }","duration":"106.004003ms","start":"2026-03-12T19:23:23.640029Z","end":"2026-03-12T19:23:23.746033Z","steps":["trace[1281949589] 'agreement among raft nodes before linearized reading' (duration: 105.575993ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:24.001909Z","caller":"traceutil/trace.go:171","msg":"trace[1575498927] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4467; }","duration":"182.799262ms","start":"2026-03-12T19:23:23.819085Z","end":"2026-03-12T19:23:24.001884Z","steps":["trace[1575498927] 'process raft request' (duration: 182.706009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:26.588461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.931942ms","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-03-12T19:23:26.588566Z","caller":"traceutil/trace.go:171","msg":"trace[1355465971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4590; }","duration":"484.108756ms","start":"2026-03-12T19:23:26.104433Z","end":"2026-03-12T19:23:26.588542Z","steps":["trace[1355465971] 'range keys from in-memory index tree' (duration: 483.496762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:26.588616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:26.104404Z","time spent":"484.201218ms","remote":"127.0.0.1:60552","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-03-12T19:23:26.58883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.858223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:23:26.589011Z","caller":"traceutil/trace.go:171","msg":"trace[92530138] transaction","detail":"{read_only:false; response_revision:4591; number_of_response:1; }","duration":"483.780279ms","start":"2026-03-12T19:23:26.105215Z","end":"2026-03-12T19:23:26.588995Z","steps":["trace[92530138] 'process raft request' (duration: 334.326623ms)","trace[92530138] 'compare' (duration: 148.671398ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:23:26.58912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:26.105192Z","time spent":"483.852791ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:23:26.589262Z","caller":"traceutil/trace.go:171","msg":"trace[1017255308] linearizableReadLoop","detail":"{readStateIndex:4773; appliedIndex:4772; }","duration":"473.734894ms","start":"2026-03-12T19:23:26.115498Z","end":"2026-03-12T19:23:26.589233Z","steps":["trace[1017255308] 'read index received' (duration: 324.025152ms)","trace[1017255308] 'applied index is now lower than readState.Index' (duration: 149.708112ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:23:26.589428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.850937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d1e5469-29b4-4522-a38a-68e251049517\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-03-12T19:23:26.589425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.934078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twdkv\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-12T19:23:26.58937Z","caller":"traceutil/trace.go:171","msg":"trace[490446172] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"260.635514ms","start":"2026-03-12T19:23:26.328382Z","end":"2026-03-12T19:23:26.589017Z","steps":["trace[490446172] 'process raft request' (duration: 260.567323ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:26.589457Z","caller":"traceutil/trace.go:171","msg":"trace[1533726439] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0d1e5469-29b4-4522-a38a-68e251049517; range_end:; response_count:1; response_revision:4592; }","duration":"259.893068ms","start":"2026-03-12T19:23:26.329554Z","end":"2026-03-12T19:23:26.589447Z","steps":["trace[1533726439] 'agreement among raft nodes before linearized reading' (duration: 259.812086ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:26.589462Z","caller":"traceutil/trace.go:171","msg":"trace[854057350] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twdkv; range_end:; response_count:1; response_revision:4592; }","duration":"473.972079ms","start":"2026-03-12T19:23:26.11548Z","end":"2026-03-12T19:23:26.589452Z","steps":["trace[854057350] 'agreement among raft nodes before linearized reading' (duration: 473.836396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:26.589492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:26.115443Z","time spent":"474.041821ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-twdkv\" "} {"level":"warn","ts":"2026-03-12T19:23:26.589581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.898434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d1e5469-29b4-4522-a38a-68e251049517\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-03-12T19:23:26.589635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.264607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-12T19:23:26.589691Z","caller":"traceutil/trace.go:171","msg":"trace[220301962] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0d1e5469-29b4-4522-a38a-68e251049517; range_end:; response_count:1; response_revision:4592; }","duration":"422.062768ms","start":"2026-03-12T19:23:26.167597Z","end":"2026-03-12T19:23:26.58966Z","steps":["trace[220301962] 'agreement among raft nodes before linearized reading' (duration: 421.772621ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:26.589731Z","caller":"traceutil/trace.go:171","msg":"trace[716090085] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4592; }","duration":"298.40274ms","start":"2026-03-12T19:23:26.291304Z","end":"2026-03-12T19:23:26.589707Z","steps":["trace[716090085] 'agreement among raft nodes before linearized reading' (duration: 298.079142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:26.589793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:26.167576Z","time spent":"422.200341ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d1e5469-29b4-4522-a38a-68e251049517\" "} {"level":"info","ts":"2026-03-12T19:23:28.624152Z","caller":"traceutil/trace.go:171","msg":"trace[375162464] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"257.646937ms","start":"2026-03-12T19:23:28.366448Z","end":"2026-03-12T19:23:28.624095Z","steps":["trace[375162464] 'process raft request' (duration: 257.046824ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:29.114151Z","caller":"traceutil/trace.go:171","msg":"trace[695209078] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4626; }","duration":"156.539914ms","start":"2026-03-12T19:23:28.957583Z","end":"2026-03-12T19:23:29.114123Z","steps":["trace[695209078] 'process raft request' (duration: 156.38144ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:29.388366Z","caller":"traceutil/trace.go:171","msg":"trace[2001089061] linearizableReadLoop","detail":"{readStateIndex:4811; appliedIndex:4810; }","duration":"174.365273ms","start":"2026-03-12T19:23:29.213892Z","end":"2026-03-12T19:23:29.388257Z","steps":["trace[2001089061] 'read index received' (duration: 174.031886ms)","trace[2001089061] 'applied index is now lower than readState.Index' (duration: 332.097µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:29.388393Z","caller":"traceutil/trace.go:171","msg":"trace[920651923] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"264.926661ms","start":"2026-03-12T19:23:29.12342Z","end":"2026-03-12T19:23:29.388346Z","steps":["trace[920651923] 'process raft request' (duration: 264.552233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:29.388672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.765092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-12T19:23:29.388738Z","caller":"traceutil/trace.go:171","msg":"trace[2130088052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4627; }","duration":"174.891335ms","start":"2026-03-12T19:23:29.213829Z","end":"2026-03-12T19:23:29.38872Z","steps":["trace[2130088052] 'agreement among raft nodes before linearized reading' (duration: 174.64091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:29.389021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.949704ms","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-03-12T19:23:29.389143Z","caller":"traceutil/trace.go:171","msg":"trace[784571615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4627; }","duration":"174.097657ms","start":"2026-03-12T19:23:29.215022Z","end":"2026-03-12T19:23:29.38912Z","steps":["trace[784571615] 'agreement among raft nodes before linearized reading' (duration: 173.708628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:29.548222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.516657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:23:29.548487Z","caller":"traceutil/trace.go:171","msg":"trace[1326489896] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"155.329546ms","start":"2026-03-12T19:23:29.393104Z","end":"2026-03-12T19:23:29.548433Z","steps":["trace[1326489896] 'process raft request' (duration: 43.475132ms)","trace[1326489896] 'compare' (duration: 111.332813ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:29.548531Z","caller":"traceutil/trace.go:171","msg":"trace[415321030] linearizableReadLoop","detail":"{readStateIndex:4814; appliedIndex:4811; }","duration":"153.074376ms","start":"2026-03-12T19:23:29.395428Z","end":"2026-03-12T19:23:29.548502Z","steps":["trace[415321030] 'read index received' (duration: 41.172561ms)","trace[415321030] 'applied index is now lower than readState.Index' (duration: 111.899765ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:29.548646Z","caller":"traceutil/trace.go:171","msg":"trace[1057358625] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"153.816593ms","start":"2026-03-12T19:23:29.394782Z","end":"2026-03-12T19:23:29.548599Z","steps":["trace[1057358625] 'process raft request' (duration: 153.658059ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:29.548668Z","caller":"traceutil/trace.go:171","msg":"trace[1713742085] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"154.258373ms","start":"2026-03-12T19:23:29.394359Z","end":"2026-03-12T19:23:29.548617Z","steps":["trace[1713742085] 'process raft request' (duration: 154.012868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:29.548923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.492986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16076"} {"level":"info","ts":"2026-03-12T19:23:29.548979Z","caller":"traceutil/trace.go:171","msg":"trace[1865966551] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4630; }","duration":"153.570097ms","start":"2026-03-12T19:23:29.395391Z","end":"2026-03-12T19:23:29.548961Z","steps":["trace[1865966551] 'agreement among raft nodes before linearized reading' (duration: 153.337522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:29.549736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.319634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:23:29.549782Z","caller":"traceutil/trace.go:171","msg":"trace[1283407916] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:4630; }","duration":"141.437037ms","start":"2026-03-12T19:23:29.408332Z","end":"2026-03-12T19:23:29.549769Z","steps":["trace[1283407916] 'agreement among raft nodes before linearized reading' (duration: 141.313984ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:31.246224Z","caller":"traceutil/trace.go:171","msg":"trace[1241775157] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"156.37578ms","start":"2026-03-12T19:23:31.089813Z","end":"2026-03-12T19:23:31.246189Z","steps":["trace[1241775157] 'process raft request' (duration: 156.182226ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.14072Z","caller":"traceutil/trace.go:171","msg":"trace[375060470] linearizableReadLoop","detail":"{readStateIndex:4998; appliedIndex:4997; }","duration":"111.213459ms","start":"2026-03-12T19:23:38.029471Z","end":"2026-03-12T19:23:38.140684Z","steps":["trace[375060470] 'read index received' (duration: 110.8129ms)","trace[375060470] 'applied index is now lower than readState.Index' (duration: 399.329µs)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:23:38.141001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.493915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-03-12T19:23:38.141051Z","caller":"traceutil/trace.go:171","msg":"trace[1122073536] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4808; }","duration":"111.625388ms","start":"2026-03-12T19:23:38.029408Z","end":"2026-03-12T19:23:38.141033Z","steps":["trace[1122073536] 'agreement among raft nodes before linearized reading' (duration: 111.407853ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.141293Z","caller":"traceutil/trace.go:171","msg":"trace[1512268337] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"175.169562ms","start":"2026-03-12T19:23:37.96609Z","end":"2026-03-12T19:23:38.141259Z","steps":["trace[1512268337] 'process raft request' (duration: 174.282791ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.307463Z","caller":"traceutil/trace.go:171","msg":"trace[421539004] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"229.106438ms","start":"2026-03-12T19:23:38.078334Z","end":"2026-03-12T19:23:38.307441Z","steps":["trace[421539004] 'process raft request' (duration: 150.288744ms)","trace[421539004] 'compare' (duration: 78.446306ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:38.307687Z","caller":"traceutil/trace.go:171","msg":"trace[1055647898] linearizableReadLoop","detail":"{readStateIndex:5002; appliedIndex:4998; }","duration":"162.412505ms","start":"2026-03-12T19:23:38.145246Z","end":"2026-03-12T19:23:38.307659Z","steps":["trace[1055647898] 'read index received' (duration: 83.375696ms)","trace[1055647898] 'applied index is now lower than readState.Index' (duration: 79.035459ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:38.307726Z","caller":"traceutil/trace.go:171","msg":"trace[2128807221] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"201.401029ms","start":"2026-03-12T19:23:38.106281Z","end":"2026-03-12T19:23:38.307682Z","steps":["trace[2128807221] 'process raft request' (duration: 201.162453ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.307862Z","caller":"traceutil/trace.go:171","msg":"trace[1944119530] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"164.250167ms","start":"2026-03-12T19:23:38.14354Z","end":"2026-03-12T19:23:38.30779Z","steps":["trace[1944119530] 'process raft request' (duration: 164.049253ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.308Z","caller":"traceutil/trace.go:171","msg":"trace[922291343] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"220.918326ms","start":"2026-03-12T19:23:38.087037Z","end":"2026-03-12T19:23:38.307955Z","steps":["trace[922291343] 'process raft request' (duration: 220.319352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:38.308048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.505088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1462"} {"level":"info","ts":"2026-03-12T19:23:38.3081Z","caller":"traceutil/trace.go:171","msg":"trace[1335795550] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4812; }","duration":"162.584999ms","start":"2026-03-12T19:23:38.145497Z","end":"2026-03-12T19:23:38.308082Z","steps":["trace[1335795550] 'agreement among raft nodes before linearized reading' (duration: 162.355934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:38.308108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.410165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1478"} {"level":"warn","ts":"2026-03-12T19:23:38.308103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.829685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:1270"} {"level":"info","ts":"2026-03-12T19:23:38.308159Z","caller":"traceutil/trace.go:171","msg":"trace[947568027] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4812; }","duration":"162.550779ms","start":"2026-03-12T19:23:38.14559Z","end":"2026-03-12T19:23:38.308141Z","steps":["trace[947568027] 'agreement among raft nodes before linearized reading' (duration: 162.322104ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.308197Z","caller":"traceutil/trace.go:171","msg":"trace[858075771] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4812; }","duration":"162.983318ms","start":"2026-03-12T19:23:38.145194Z","end":"2026-03-12T19:23:38.308177Z","steps":["trace[858075771] 'agreement among raft nodes before linearized reading' (duration: 162.63635ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.522758Z","caller":"traceutil/trace.go:171","msg":"trace[1687475962] linearizableReadLoop","detail":"{readStateIndex:5008; appliedIndex:5002; }","duration":"195.422664ms","start":"2026-03-12T19:23:38.327309Z","end":"2026-03-12T19:23:38.522732Z","steps":["trace[1687475962] 'read index received' (duration: 72.040393ms)","trace[1687475962] 'applied index is now lower than readState.Index' (duration: 123.381291ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:38.522879Z","caller":"traceutil/trace.go:171","msg":"trace[584637640] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"208.62564ms","start":"2026-03-12T19:23:38.314242Z","end":"2026-03-12T19:23:38.522868Z","steps":["trace[584637640] 'process raft request' (duration: 198.859812ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.523207Z","caller":"traceutil/trace.go:171","msg":"trace[891282689] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"208.60647ms","start":"2026-03-12T19:23:38.31459Z","end":"2026-03-12T19:23:38.523196Z","steps":["trace[891282689] 'process raft request' (duration: 207.956765ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.523456Z","caller":"traceutil/trace.go:171","msg":"trace[1391108438] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"208.435945ms","start":"2026-03-12T19:23:38.315013Z","end":"2026-03-12T19:23:38.523448Z","steps":["trace[1391108438] 'process raft request' (duration: 207.579436ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.523633Z","caller":"traceutil/trace.go:171","msg":"trace[915565503] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"207.179107ms","start":"2026-03-12T19:23:38.316447Z","end":"2026-03-12T19:23:38.523626Z","steps":["trace[915565503] 'process raft request' (duration: 206.199566ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.523702Z","caller":"traceutil/trace.go:171","msg":"trace[1669257566] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"207.014304ms","start":"2026-03-12T19:23:38.316682Z","end":"2026-03-12T19:23:38.523696Z","steps":["trace[1669257566] 'process raft request' (duration: 206.016772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:38.523997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.654061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-brqr8\" ","response":"range_response_count:1 size:7818"} {"level":"warn","ts":"2026-03-12T19:23:38.524068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.367647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.189c2e6bae1859fd\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-03-12T19:23:38.524081Z","caller":"traceutil/trace.go:171","msg":"trace[670312903] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-brqr8; range_end:; response_count:1; response_revision:4818; }","duration":"196.884937ms","start":"2026-03-12T19:23:38.327171Z","end":"2026-03-12T19:23:38.524056Z","steps":["trace[670312903] 'agreement among raft nodes before linearized reading' (duration: 196.56777ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.524095Z","caller":"traceutil/trace.go:171","msg":"trace[877883504] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-alertmanager.189c2e6bae1859fd; range_end:; response_count:1; response_revision:4818; }","duration":"117.450089ms","start":"2026-03-12T19:23:38.406635Z","end":"2026-03-12T19:23:38.524085Z","steps":["trace[877883504] 'agreement among raft nodes before linearized reading' (duration: 117.340337ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.685899Z","caller":"traceutil/trace.go:171","msg":"trace[968950048] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"114.767829ms","start":"2026-03-12T19:23:38.571106Z","end":"2026-03-12T19:23:38.685874Z","steps":["trace[968950048] 'process raft request' (duration: 114.650766ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.685933Z","caller":"traceutil/trace.go:171","msg":"trace[1238371322] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"111.968146ms","start":"2026-03-12T19:23:38.573934Z","end":"2026-03-12T19:23:38.685902Z","steps":["trace[1238371322] 'process raft request' (duration: 111.869814ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.686207Z","caller":"traceutil/trace.go:171","msg":"trace[796639597] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"115.26563ms","start":"2026-03-12T19:23:38.57093Z","end":"2026-03-12T19:23:38.686195Z","steps":["trace[796639597] 'process raft request' (duration: 114.625666ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:38.686578Z","caller":"traceutil/trace.go:171","msg":"trace[1503666802] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"112.5818ms","start":"2026-03-12T19:23:38.573982Z","end":"2026-03-12T19:23:38.686564Z","steps":["trace[1503666802] 'process raft request' (duration: 111.879155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:47.145582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.413011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:23:47.145711Z","caller":"traceutil/trace.go:171","msg":"trace[1315272527] linearizableReadLoop","detail":"{readStateIndex:5147; appliedIndex:5146; }","duration":"241.66305ms","start":"2026-03-12T19:23:46.904029Z","end":"2026-03-12T19:23:47.145692Z","steps":["trace[1315272527] 'read index received' (duration: 61.272µs)","trace[1315272527] 'applied index is now lower than readState.Index' (duration: 241.600498ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:47.14572Z","caller":"traceutil/trace.go:171","msg":"trace[1658382696] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"291.344582ms","start":"2026-03-12T19:23:46.854358Z","end":"2026-03-12T19:23:47.145702Z","steps":["trace[1658382696] 'process raft request' (duration: 17.704746ms)","trace[1658382696] 'compare' (duration: 273.231147ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:23:47.145845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.816613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05e7e18d-82bc-4b1f-86e0-cc0e48de6481\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-12T19:23:47.14587Z","caller":"traceutil/trace.go:171","msg":"trace[138089512] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-05e7e18d-82bc-4b1f-86e0-cc0e48de6481; range_end:; response_count:1; response_revision:4955; }","duration":"241.896164ms","start":"2026-03-12T19:23:46.903965Z","end":"2026-03-12T19:23:47.145861Z","steps":["trace[138089512] 'agreement among raft nodes before linearized reading' (duration: 241.770682ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:47.342534Z","caller":"traceutil/trace.go:171","msg":"trace[1381216697] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"276.368607ms","start":"2026-03-12T19:23:47.066129Z","end":"2026-03-12T19:23:47.342498Z","steps":["trace[1381216697] 'process raft request' (duration: 276.017769ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:47.594993Z","caller":"traceutil/trace.go:171","msg":"trace[442181201] linearizableReadLoop","detail":"{readStateIndex:5149; appliedIndex:5148; }","duration":"129.751694ms","start":"2026-03-12T19:23:47.465214Z","end":"2026-03-12T19:23:47.594966Z","steps":["trace[442181201] 'read index received' (duration: 129.425127ms)","trace[442181201] 'applied index is now lower than readState.Index' (duration: 325.327µs)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:23:47.595304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.02805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-12T19:23:47.595416Z","caller":"traceutil/trace.go:171","msg":"trace[1296128730] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4957; }","duration":"130.242534ms","start":"2026-03-12T19:23:47.465152Z","end":"2026-03-12T19:23:47.595394Z","steps":["trace[1296128730] 'agreement among raft nodes before linearized reading' (duration: 129.929768ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:47.595729Z","caller":"traceutil/trace.go:171","msg":"trace[1605888736] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"167.269193ms","start":"2026-03-12T19:23:47.428425Z","end":"2026-03-12T19:23:47.595694Z","steps":["trace[1605888736] 'process raft request' (duration: 166.360913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:47.918746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.01402ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:23:47.918884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.039107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:23:47.918903Z","caller":"traceutil/trace.go:171","msg":"trace[682501034] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4958; }","duration":"222.193014ms","start":"2026-03-12T19:23:47.696692Z","end":"2026-03-12T19:23:47.918885Z","steps":["trace[682501034] 'range keys from in-memory index tree' (duration: 221.99779ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:47.919161Z","caller":"traceutil/trace.go:171","msg":"trace[12432815] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"314.315615ms","start":"2026-03-12T19:23:47.604765Z","end":"2026-03-12T19:23:47.919081Z","steps":["trace[12432815] 'process raft request' (duration: 84.989502ms)","trace[12432815] 'compare' (duration: 228.885954ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:23:47.919219Z","caller":"traceutil/trace.go:171","msg":"trace[568373787] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"314.123041ms","start":"2026-03-12T19:23:47.605078Z","end":"2026-03-12T19:23:47.919201Z","steps":["trace[568373787] 'process raft request' (duration: 313.940457ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:47.919284Z","caller":"traceutil/trace.go:171","msg":"trace[1703608765] linearizableReadLoop","detail":"{readStateIndex:5156; appliedIndex:5150; }","duration":"224.858693ms","start":"2026-03-12T19:23:47.69441Z","end":"2026-03-12T19:23:47.919235Z","steps":["trace[1703608765] 'read index received' (duration: 55.382µs)","trace[1703608765] 'applied index is now lower than readState.Index' (duration: 224.768631ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:23:47.919348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:47.605073Z","time spent":"314.226664ms","remote":"127.0.0.1:60562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:23:47.919388Z","caller":"traceutil/trace.go:171","msg":"trace[618220040] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"314.124882ms","start":"2026-03-12T19:23:47.605251Z","end":"2026-03-12T19:23:47.919376Z","steps":["trace[618220040] 'process raft request' (duration: 313.812475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:47.919395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:47.604752Z","time spent":"314.540471ms","remote":"127.0.0.1:60402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1082,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:23:47.91945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:47.605244Z","time spent":"314.167093ms","remote":"127.0.0.1:60402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:23:47.919173Z","caller":"traceutil/trace.go:171","msg":"trace[1900761959] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"314.113782ms","start":"2026-03-12T19:23:47.605026Z","end":"2026-03-12T19:23:47.919139Z","steps":["trace[1900761959] 'process raft request' (duration: 313.931258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:47.919572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:47.605016Z","time spent":"314.48503ms","remote":"127.0.0.1:60562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1463,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:23:47.919608Z","caller":"traceutil/trace.go:171","msg":"trace[778370180] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"311.36684ms","start":"2026-03-12T19:23:47.608199Z","end":"2026-03-12T19:23:47.919566Z","steps":["trace[778370180] 'process raft request' (duration: 310.967301ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:23:47.919585Z","caller":"traceutil/trace.go:171","msg":"trace[727370544] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"311.872331ms","start":"2026-03-12T19:23:47.607691Z","end":"2026-03-12T19:23:47.919564Z","steps":["trace[727370544] 'process raft request' (duration: 311.420101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:47.919728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.161937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:23:47.919782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:47.607664Z","time spent":"312.076875ms","remote":"127.0.0.1:60402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:23:47.919807Z","caller":"traceutil/trace.go:171","msg":"trace[1089541450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4964; }","duration":"186.279429ms","start":"2026-03-12T19:23:47.733508Z","end":"2026-03-12T19:23:47.919787Z","steps":["trace[1089541450] 'agreement among raft nodes before linearized reading' (duration: 186.128926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:47.919811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:23:47.608173Z","time spent":"311.536213ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:23:47.919654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.254122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-12T19:23:47.920001Z","caller":"traceutil/trace.go:171","msg":"trace[1234871438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4964; }","duration":"225.64262ms","start":"2026-03-12T19:23:47.694343Z","end":"2026-03-12T19:23:47.919985Z","steps":["trace[1234871438] 'agreement among raft nodes before linearized reading' (duration: 225.207631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:47.920529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.474928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-12T19:23:47.920599Z","caller":"traceutil/trace.go:171","msg":"trace[708439507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4964; }","duration":"167.58882ms","start":"2026-03-12T19:23:47.752994Z","end":"2026-03-12T19:23:47.920583Z","steps":["trace[708439507] 'agreement among raft nodes before linearized reading' (duration: 166.845494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:48.891903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.763919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:23:48.892017Z","caller":"traceutil/trace.go:171","msg":"trace[1556204146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4974; }","duration":"156.900482ms","start":"2026-03-12T19:23:48.735094Z","end":"2026-03-12T19:23:48.891994Z","steps":["trace[1556204146] 'range keys from in-memory index tree' (duration: 156.457093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:23:48.892211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.116879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-12T19:23:48.892322Z","caller":"traceutil/trace.go:171","msg":"trace[546416621] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4974; }","duration":"229.282733ms","start":"2026-03-12T19:23:48.663011Z","end":"2026-03-12T19:23:48.892294Z","steps":["trace[546416621] 'count revisions from in-memory index tree' (duration: 228.891613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.174716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.261966ms","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-03-12T19:24:04.174825Z","caller":"traceutil/trace.go:171","msg":"trace[1675464787] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5154; }","duration":"579.43516ms","start":"2026-03-12T19:24:03.595368Z","end":"2026-03-12T19:24:04.174803Z","steps":["trace[1675464787] 'range keys from in-memory index tree' (duration: 579.031861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.174801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.881675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-03-12T19:24:04.174843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.804115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:24:04.174905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.648457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:24:04.174988Z","caller":"traceutil/trace.go:171","msg":"trace[796601595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5154; }","duration":"439.978958ms","start":"2026-03-12T19:24:03.734942Z","end":"2026-03-12T19:24:04.174921Z","steps":["trace[796601595] 'range keys from in-memory index tree' (duration: 439.60281ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.175013Z","caller":"traceutil/trace.go:171","msg":"trace[919348856] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5154; }","duration":"693.77222ms","start":"2026-03-12T19:24:03.481202Z","end":"2026-03-12T19:24:04.174975Z","steps":["trace[919348856] 'count revisions from in-memory index tree' (duration: 693.562706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.175052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:24:03.73491Z","time spent":"440.131012ms","remote":"127.0.0.1:60208","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-03-12T19:24:04.17508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:24:03.481186Z","time spent":"693.879293ms","remote":"127.0.0.1:58778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-03-12T19:24:04.175109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.908906ms","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-03-12T19:24:04.174764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.604188ms","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-03-12T19:24:04.175219Z","caller":"traceutil/trace.go:171","msg":"trace[630953100] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5154; }","duration":"540.060779ms","start":"2026-03-12T19:24:03.635127Z","end":"2026-03-12T19:24:04.175188Z","steps":["trace[630953100] 'range keys from in-memory index tree' (duration: 539.729481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.17485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.177424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-12T19:24:04.175346Z","caller":"traceutil/trace.go:171","msg":"trace[70690308] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5154; }","duration":"661.162019ms","start":"2026-03-12T19:24:03.514067Z","end":"2026-03-12T19:24:04.175229Z","steps":["trace[70690308] 'count revisions from in-memory index tree' (duration: 660.507825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.175401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:24:03.514045Z","time spent":"661.336574ms","remote":"127.0.0.1:54264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-03-12T19:24:04.175386Z","caller":"traceutil/trace.go:171","msg":"trace[1268436187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5154; }","duration":"113.757077ms","start":"2026-03-12T19:24:04.061584Z","end":"2026-03-12T19:24:04.175341Z","steps":["trace[1268436187] 'range keys from in-memory index tree' (duration: 112.994129ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.174906Z","caller":"traceutil/trace.go:171","msg":"trace[1905987560] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5154; }","duration":"473.023869ms","start":"2026-03-12T19:24:03.701856Z","end":"2026-03-12T19:24:04.17488Z","steps":["trace[1905987560] 'range keys from in-memory index tree' (duration: 472.793313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.174887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:24:03.59534Z","time spent":"579.535432ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-03-12T19:24:04.175573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:24:03.701845Z","time spent":"473.716264ms","remote":"127.0.0.1:60296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-03-12T19:24:04.175575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.486072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129485"} {"level":"warn","ts":"2026-03-12T19:24:04.175296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:24:03.635108Z","time spent":"540.176511ms","remote":"127.0.0.1:60390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-03-12T19:24:04.175619Z","caller":"traceutil/trace.go:171","msg":"trace[1109158895] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5154; }","duration":"470.578134ms","start":"2026-03-12T19:24:03.70503Z","end":"2026-03-12T19:24:04.175608Z","steps":["trace[1109158895] 'range keys from in-memory index tree' (duration: 470.131464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.175658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:24:03.705003Z","time spent":"470.647444ms","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129508,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-03-12T19:24:04.301703Z","caller":"traceutil/trace.go:171","msg":"trace[1756946821] linearizableReadLoop","detail":"{readStateIndex:5353; appliedIndex:5352; }","duration":"118.898532ms","start":"2026-03-12T19:24:04.182772Z","end":"2026-03-12T19:24:04.30167Z","steps":["trace[1756946821] 'read index received' (duration: 118.687127ms)","trace[1756946821] 'applied index is now lower than readState.Index' (duration: 210.255µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:24:04.30187Z","caller":"traceutil/trace.go:171","msg":"trace[857046587] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"120.031117ms","start":"2026-03-12T19:24:04.181805Z","end":"2026-03-12T19:24:04.301836Z","steps":["trace[857046587] 'process raft request' (duration: 119.660899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.302431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.634159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:48 size:70061"} {"level":"info","ts":"2026-03-12T19:24:04.302484Z","caller":"traceutil/trace.go:171","msg":"trace[1893472292] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:48; response_revision:5155; }","duration":"119.71965ms","start":"2026-03-12T19:24:04.182753Z","end":"2026-03-12T19:24:04.302472Z","steps":["trace[1893472292] 'agreement among raft nodes before linearized reading' (duration: 119.068606ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.411999Z","caller":"traceutil/trace.go:171","msg":"trace[2063114359] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"229.045136ms","start":"2026-03-12T19:24:04.182924Z","end":"2026-03-12T19:24:04.411969Z","steps":["trace[2063114359] 'process raft request' (duration: 228.76221ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.412168Z","caller":"traceutil/trace.go:171","msg":"trace[1231406490] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5353; }","duration":"110.33748ms","start":"2026-03-12T19:24:04.301794Z","end":"2026-03-12T19:24:04.412132Z","steps":["trace[1231406490] 'read index received' (duration: 109.942481ms)","trace[1231406490] 'applied index is now lower than readState.Index' (duration: 393.959µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:24:04.412198Z","caller":"traceutil/trace.go:171","msg":"trace[1175470750] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"229.186049ms","start":"2026-03-12T19:24:04.182915Z","end":"2026-03-12T19:24:04.412101Z","steps":["trace[1175470750] 'process raft request' (duration: 228.942844ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.412346Z","caller":"traceutil/trace.go:171","msg":"trace[1376766016] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"224.651098ms","start":"2026-03-12T19:24:04.187681Z","end":"2026-03-12T19:24:04.412332Z","steps":["trace[1376766016] 'process raft request' (duration: 224.350932ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.412477Z","caller":"traceutil/trace.go:171","msg":"trace[1663223167] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"229.107558ms","start":"2026-03-12T19:24:04.18335Z","end":"2026-03-12T19:24:04.412458Z","steps":["trace[1663223167] 'process raft request' (duration: 228.575796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.413227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.336406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:48 size:70061"} {"level":"info","ts":"2026-03-12T19:24:04.413405Z","caller":"traceutil/trace.go:171","msg":"trace[1097557036] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:48; response_revision:5159; }","duration":"230.463469ms","start":"2026-03-12T19:24:04.182832Z","end":"2026-03-12T19:24:04.413295Z","steps":["trace[1097557036] 'agreement among raft nodes before linearized reading' (duration: 229.735093ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.592443Z","caller":"traceutil/trace.go:171","msg":"trace[1704444400] linearizableReadLoop","detail":"{readStateIndex:5358; appliedIndex:5357; }","duration":"180.172774ms","start":"2026-03-12T19:24:04.412243Z","end":"2026-03-12T19:24:04.592415Z","steps":["trace[1704444400] 'read index received' (duration: 180.126433ms)","trace[1704444400] 'applied index is now lower than readState.Index' (duration: 44.721µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:24:04.592494Z","caller":"traceutil/trace.go:171","msg":"trace[1557234732] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"284.122531ms","start":"2026-03-12T19:24:04.30833Z","end":"2026-03-12T19:24:04.592452Z","steps":["trace[1557234732] 'process raft request' (duration: 283.899436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.592718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.776035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-03-12T19:24:04.592759Z","caller":"traceutil/trace.go:171","msg":"trace[554793926] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5160; }","duration":"284.883777ms","start":"2026-03-12T19:24:04.307867Z","end":"2026-03-12T19:24:04.592751Z","steps":["trace[554793926] 'agreement among raft nodes before linearized reading' (duration: 284.647912ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.62541Z","caller":"traceutil/trace.go:171","msg":"trace[21825322] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"210.299318ms","start":"2026-03-12T19:24:04.415096Z","end":"2026-03-12T19:24:04.625395Z","steps":["trace[21825322] 'process raft request' (duration: 209.584552ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.625637Z","caller":"traceutil/trace.go:171","msg":"trace[407871328] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"210.449141ms","start":"2026-03-12T19:24:04.415171Z","end":"2026-03-12T19:24:04.62562Z","steps":["trace[407871328] 'process raft request' (duration: 209.674733ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.625853Z","caller":"traceutil/trace.go:171","msg":"trace[359794288] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"207.473604ms","start":"2026-03-12T19:24:04.418368Z","end":"2026-03-12T19:24:04.625842Z","steps":["trace[359794288] 'process raft request' (duration: 206.566563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:04.626181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.621333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129485"} {"level":"info","ts":"2026-03-12T19:24:04.62626Z","caller":"traceutil/trace.go:171","msg":"trace[1697406035] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5166; }","duration":"205.755376ms","start":"2026-03-12T19:24:04.420486Z","end":"2026-03-12T19:24:04.626241Z","steps":["trace[1697406035] 'agreement among raft nodes before linearized reading' (duration: 205.446679ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.626658Z","caller":"traceutil/trace.go:171","msg":"trace[1625521575] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"208.118368ms","start":"2026-03-12T19:24:04.418529Z","end":"2026-03-12T19:24:04.626647Z","steps":["trace[1625521575] 'process raft request' (duration: 206.476481ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.626849Z","caller":"traceutil/trace.go:171","msg":"trace[1181522019] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"207.484924ms","start":"2026-03-12T19:24:04.41935Z","end":"2026-03-12T19:24:04.626835Z","steps":["trace[1181522019] 'process raft request' (duration: 205.724394ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.626973Z","caller":"traceutil/trace.go:171","msg":"trace[1060391137] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"207.465524ms","start":"2026-03-12T19:24:04.419496Z","end":"2026-03-12T19:24:04.626961Z","steps":["trace[1060391137] 'process raft request' (duration: 205.625272ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:04.745511Z","caller":"traceutil/trace.go:171","msg":"trace[1114619499] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"106.376882ms","start":"2026-03-12T19:24:04.639096Z","end":"2026-03-12T19:24:04.745473Z","steps":["trace[1114619499] 'process raft request' (duration: 105.977543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:05.106503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.353751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-12T19:24:05.106537Z","caller":"traceutil/trace.go:171","msg":"trace[571264925] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"113.48832ms","start":"2026-03-12T19:24:04.992997Z","end":"2026-03-12T19:24:05.106486Z","steps":["trace[571264925] 'process raft request' (duration: 59.523702ms)","trace[571264925] 'compare' (duration: 53.766513ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:24:05.106601Z","caller":"traceutil/trace.go:171","msg":"trace[264632401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5168; }","duration":"127.539015ms","start":"2026-03-12T19:24:04.979039Z","end":"2026-03-12T19:24:05.106578Z","steps":["trace[264632401] 'agreement among raft nodes before linearized reading' (duration: 54.97795ms)","trace[264632401] 'range keys from in-memory index tree' (duration: 72.282439ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:24:05.106611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.697934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twdkv\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-12T19:24:05.106683Z","caller":"traceutil/trace.go:171","msg":"trace[374969655] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twdkv; range_end:; response_count:1; response_revision:5169; }","duration":"125.806527ms","start":"2026-03-12T19:24:04.980861Z","end":"2026-03-12T19:24:05.106667Z","steps":["trace[374969655] 'agreement among raft nodes before linearized reading' (duration: 125.574501ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:05.799134Z","caller":"traceutil/trace.go:171","msg":"trace[2083791799] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"114.804879ms","start":"2026-03-12T19:24:05.684289Z","end":"2026-03-12T19:24:05.799094Z","steps":["trace[2083791799] 'process raft request' (duration: 73.770722ms)","trace[2083791799] 'compare' (duration: 40.607188ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:24:48.733037Z","caller":"traceutil/trace.go:171","msg":"trace[1654298315] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"181.660498ms","start":"2026-03-12T19:24:48.551341Z","end":"2026-03-12T19:24:48.733001Z","steps":["trace[1654298315] 'process raft request' (duration: 181.497184ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:48.733412Z","caller":"traceutil/trace.go:171","msg":"trace[1445808366] linearizableReadLoop","detail":"{readStateIndex:5727; appliedIndex:5727; }","duration":"170.763757ms","start":"2026-03-12T19:24:48.562614Z","end":"2026-03-12T19:24:48.733378Z","steps":["trace[1445808366] 'read index received' (duration: 170.753987ms)","trace[1445808366] 'applied index is now lower than readState.Index' (duration: 7.4µs)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:24:48.734663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.030724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210803"} {"level":"info","ts":"2026-03-12T19:24:48.734772Z","caller":"traceutil/trace.go:171","msg":"trace[1117334168] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5518; }","duration":"172.208238ms","start":"2026-03-12T19:24:48.562542Z","end":"2026-03-12T19:24:48.73475Z","steps":["trace[1117334168] 'agreement among raft nodes before linearized reading' (duration: 170.990802ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:48.919974Z","caller":"traceutil/trace.go:171","msg":"trace[2001683700] linearizableReadLoop","detail":"{readStateIndex:5732; appliedIndex:5731; }","duration":"119.064323ms","start":"2026-03-12T19:24:48.800876Z","end":"2026-03-12T19:24:48.919941Z","steps":["trace[2001683700] 'read index received' (duration: 19.45005ms)","trace[2001683700] 'applied index is now lower than readState.Index' (duration: 99.612573ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:24:48.920301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.39235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-03-12T19:24:48.920366Z","caller":"traceutil/trace.go:171","msg":"trace[416955679] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5523; }","duration":"119.507583ms","start":"2026-03-12T19:24:48.800843Z","end":"2026-03-12T19:24:48.920351Z","steps":["trace[416955679] 'agreement among raft nodes before linearized reading' (duration: 119.234517ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:48.924162Z","caller":"traceutil/trace.go:171","msg":"trace[778169443] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"127.357477ms","start":"2026-03-12T19:24:48.792716Z","end":"2026-03-12T19:24:48.920073Z","steps":["trace[778169443] 'process raft request' (duration: 27.687353ms)","trace[778169443] 'compare' (duration: 99.369637ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:24:49.650091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.293411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:24:49.650203Z","caller":"traceutil/trace.go:171","msg":"trace[1001363766] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5526; }","duration":"182.481655ms","start":"2026-03-12T19:24:49.467696Z","end":"2026-03-12T19:24:49.650178Z","steps":["trace[1001363766] 'range keys from in-memory index tree' (duration: 182.149309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:49.65017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.333723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:24:49.650281Z","caller":"traceutil/trace.go:171","msg":"trace[1880355577] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5526; }","duration":"182.540267ms","start":"2026-03-12T19:24:49.467715Z","end":"2026-03-12T19:24:49.650255Z","steps":["trace[1880355577] 'range keys from in-memory index tree' (duration: 182.22205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:24:49.650357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.595192ms","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-03-12T19:24:49.650216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.696168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-03-12T19:24:49.65046Z","caller":"traceutil/trace.go:171","msg":"trace[1814971789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5526; }","duration":"179.732565ms","start":"2026-03-12T19:24:49.470705Z","end":"2026-03-12T19:24:49.650438Z","steps":["trace[1814971789] 'range keys from in-memory index tree' (duration: 179.361327ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:49.650519Z","caller":"traceutil/trace.go:171","msg":"trace[1686798494] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5526; }","duration":"177.014264ms","start":"2026-03-12T19:24:49.473444Z","end":"2026-03-12T19:24:49.650458Z","steps":["trace[1686798494] 'range keys from in-memory index tree' (duration: 176.461203ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:24:59.919988Z","caller":"traceutil/trace.go:171","msg":"trace[1503516918] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"196.513136ms","start":"2026-03-12T19:24:59.723444Z","end":"2026-03-12T19:24:59.919957Z","steps":["trace[1503516918] 'process raft request' (duration: 196.343482ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:25:02.38797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2709} {"level":"info","ts":"2026-03-12T19:25:02.453646Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2709,"took":"64.418455ms","hash":235603603,"current-db-size-bytes":41046016,"current-db-size":"41 MB","current-db-size-in-use-bytes":30420992,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-12T19:25:02.45373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":235603603,"revision":2709,"compact-revision":-1} {"level":"info","ts":"2026-03-12T19:29:42.143322Z","caller":"traceutil/trace.go:171","msg":"trace[1424211959] transaction","detail":"{read_only:false; response_revision:7448; number_of_response:1; }","duration":"103.91576ms","start":"2026-03-12T19:29:42.03938Z","end":"2026-03-12T19:29:42.143296Z","steps":["trace[1424211959] 'process raft request' (duration: 103.723486ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:29:55.80577Z","caller":"traceutil/trace.go:171","msg":"trace[1159955224] transaction","detail":"{read_only:false; response_revision:7652; number_of_response:1; }","duration":"162.109267ms","start":"2026-03-12T19:29:55.643629Z","end":"2026-03-12T19:29:55.805738Z","steps":["trace[1159955224] 'process raft request' (duration: 159.225763ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:29:55.805748Z","caller":"traceutil/trace.go:171","msg":"trace[2007885684] linearizableReadLoop","detail":"{readStateIndex:7935; appliedIndex:7934; }","duration":"155.610953ms","start":"2026-03-12T19:29:55.650102Z","end":"2026-03-12T19:29:55.805713Z","steps":["trace[2007885684] 'read index received' (duration: 152.74511ms)","trace[2007885684] 'applied index is now lower than readState.Index' (duration: 2.863973ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:29:55.806049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.93826ms","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-03-12T19:29:55.806201Z","caller":"traceutil/trace.go:171","msg":"trace[1196279851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7652; }","duration":"156.158315ms","start":"2026-03-12T19:29:55.650026Z","end":"2026-03-12T19:29:55.806184Z","steps":["trace[1196279851] 'agreement among raft nodes before linearized reading' (duration: 155.806467ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:29:57.818455Z","caller":"traceutil/trace.go:171","msg":"trace[329115431] linearizableReadLoop","detail":"{readStateIndex:7944; appliedIndex:7943; }","duration":"435.025085ms","start":"2026-03-12T19:29:57.383392Z","end":"2026-03-12T19:29:57.818418Z","steps":["trace[329115431] 'read index received' (duration: 434.732658ms)","trace[329115431] 'applied index is now lower than readState.Index' (duration: 290.857µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:29:57.818644Z","caller":"traceutil/trace.go:171","msg":"trace[1704818906] transaction","detail":"{read_only:false; response_revision:7661; number_of_response:1; }","duration":"685.382503ms","start":"2026-03-12T19:29:57.133211Z","end":"2026-03-12T19:29:57.818593Z","steps":["trace[1704818906] 'process raft request' (duration: 684.958734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:29:57.818824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:29:57.133191Z","time spent":"685.539976ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:29:57.818835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.411702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twdkv\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-12T19:29:57.818891Z","caller":"traceutil/trace.go:171","msg":"trace[1301119444] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twdkv; range_end:; response_count:1; response_revision:7661; }","duration":"435.554526ms","start":"2026-03-12T19:29:57.38332Z","end":"2026-03-12T19:29:57.818874Z","steps":["trace[1301119444] 'agreement among raft nodes before linearized reading' (duration: 435.30334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:29:57.818944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:29:57.383288Z","time spent":"435.643968ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-twdkv\" "} {"level":"info","ts":"2026-03-12T19:29:58.297533Z","caller":"traceutil/trace.go:171","msg":"trace[576275530] transaction","detail":"{read_only:false; response_revision:7662; number_of_response:1; }","duration":"470.794005ms","start":"2026-03-12T19:29:57.826697Z","end":"2026-03-12T19:29:58.297491Z","steps":["trace[576275530] 'process raft request' (duration: 428.05601ms)","trace[576275530] 'compare' (duration: 42.375838ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:29:58.297774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:29:57.826676Z","time spent":"470.97022ms","remote":"127.0.0.1:60552","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-03-12T19:30:02.395452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5607} {"level":"info","ts":"2026-03-12T19:30:02.491934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5607,"took":"94.250184ms","hash":110180222,"current-db-size-bytes":42213376,"current-db-size":"42 MB","current-db-size-in-use-bytes":23302144,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-12T19:30:02.492017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":110180222,"revision":5607,"compact-revision":2709} {"level":"info","ts":"2026-03-12T19:30:08.894905Z","caller":"traceutil/trace.go:171","msg":"trace[1966974207] transaction","detail":"{read_only:false; response_revision:7713; number_of_response:1; }","duration":"336.907213ms","start":"2026-03-12T19:30:08.557956Z","end":"2026-03-12T19:30:08.894863Z","steps":["trace[1966974207] 'process raft request' (duration: 315.374586ms)","trace[1966974207] 'compare' (duration: 21.314852ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:30:08.895104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:08.557935Z","time spent":"337.055157ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:30:08.931731Z","caller":"traceutil/trace.go:171","msg":"trace[1890710059] transaction","detail":"{read_only:false; response_revision:7714; number_of_response:1; }","duration":"373.027392ms","start":"2026-03-12T19:30:08.558681Z","end":"2026-03-12T19:30:08.931708Z","steps":["trace[1890710059] 'process raft request' (duration: 372.668504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:08.931842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:08.558655Z","time spent":"373.131435ms","remote":"127.0.0.1:60552","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-03-12T19:30:08.931928Z","caller":"traceutil/trace.go:171","msg":"trace[525656900] transaction","detail":"{read_only:false; response_revision:7715; number_of_response:1; }","duration":"373.065993ms","start":"2026-03-12T19:30:08.558846Z","end":"2026-03-12T19:30:08.931912Z","steps":["trace[525656900] 'process raft request' (duration: 372.638254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:08.932065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:08.558837Z","time spent":"373.156055ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:30:08.931866Z","caller":"traceutil/trace.go:171","msg":"trace[91303987] linearizableReadLoop","detail":"{readStateIndex:8002; appliedIndex:7999; }","duration":"305.483788ms","start":"2026-03-12T19:30:08.626358Z","end":"2026-03-12T19:30:08.931842Z","steps":["trace[91303987] 'read index received' (duration: 246.997084ms)","trace[91303987] 'applied index is now lower than readState.Index' (duration: 58.485814ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:30:08.932301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.929738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-12T19:30:08.932394Z","caller":"traceutil/trace.go:171","msg":"trace[530355812] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7715; }","duration":"306.091721ms","start":"2026-03-12T19:30:08.626282Z","end":"2026-03-12T19:30:08.932374Z","steps":["trace[530355812] 'agreement among raft nodes before linearized reading' (duration: 305.783875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:08.932442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:08.626253Z","time spent":"306.180314ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-12T19:30:10.186876Z","caller":"traceutil/trace.go:171","msg":"trace[2040136932] linearizableReadLoop","detail":"{readStateIndex:8003; appliedIndex:8002; }","duration":"303.28237ms","start":"2026-03-12T19:30:09.883564Z","end":"2026-03-12T19:30:10.186847Z","steps":["trace[2040136932] 'read index received' (duration: 303.025534ms)","trace[2040136932] 'applied index is now lower than readState.Index' (duration: 255.776µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:10.186948Z","caller":"traceutil/trace.go:171","msg":"trace[1195954841] transaction","detail":"{read_only:false; response_revision:7716; number_of_response:1; }","duration":"429.798898ms","start":"2026-03-12T19:30:09.757112Z","end":"2026-03-12T19:30:10.18691Z","steps":["trace[1195954841] 'process raft request' (duration: 429.543612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:10.187134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.560075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-12T19:30:10.187145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:09.757091Z","time spent":"429.948482ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:30:10.187183Z","caller":"traceutil/trace.go:171","msg":"trace[122961305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7716; }","duration":"303.626157ms","start":"2026-03-12T19:30:09.883544Z","end":"2026-03-12T19:30:10.18717Z","steps":["trace[122961305] 'agreement among raft nodes before linearized reading' (duration: 303.440633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:10.187219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:09.88345Z","time spent":"303.76038ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-12T19:30:10.187284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.463974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twdkv\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-12T19:30:10.187358Z","caller":"traceutil/trace.go:171","msg":"trace[102138275] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twdkv; range_end:; response_count:1; response_revision:7716; }","duration":"289.678498ms","start":"2026-03-12T19:30:09.897661Z","end":"2026-03-12T19:30:10.187339Z","steps":["trace[102138275] 'agreement among raft nodes before linearized reading' (duration: 289.386262ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:10.412348Z","caller":"traceutil/trace.go:171","msg":"trace[1362154985] linearizableReadLoop","detail":"{readStateIndex:8004; appliedIndex:8003; }","duration":"135.369976ms","start":"2026-03-12T19:30:10.276961Z","end":"2026-03-12T19:30:10.412331Z","steps":["trace[1362154985] 'read index received' (duration: 91.689719ms)","trace[1362154985] 'applied index is now lower than readState.Index' (duration: 43.678997ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:10.412461Z","caller":"traceutil/trace.go:171","msg":"trace[1333900829] transaction","detail":"{read_only:false; response_revision:7717; number_of_response:1; }","duration":"194.064114ms","start":"2026-03-12T19:30:10.218331Z","end":"2026-03-12T19:30:10.412395Z","steps":["trace[1333900829] 'process raft request' (duration: 150.308386ms)","trace[1333900829] 'compare' (duration: 43.526143ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:30:10.412732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.758114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:10.412785Z","caller":"traceutil/trace.go:171","msg":"trace[1199425854] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:7718; }","duration":"135.852936ms","start":"2026-03-12T19:30:10.276917Z","end":"2026-03-12T19:30:10.41277Z","steps":["trace[1199425854] 'agreement among raft nodes before linearized reading' (duration: 135.520639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:10.412781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.587217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223862"} {"level":"info","ts":"2026-03-12T19:30:10.41298Z","caller":"traceutil/trace.go:171","msg":"trace[374122891] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7716; }","duration":"195.930954ms","start":"2026-03-12T19:30:10.217022Z","end":"2026-03-12T19:30:10.412953Z","steps":["trace[374122891] 'range keys from in-memory index tree' (duration: 194.937523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:10.621512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.499578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:30:10.62172Z","caller":"traceutil/trace.go:171","msg":"trace[1483656612] transaction","detail":"{read_only:false; response_revision:7720; number_of_response:1; }","duration":"204.379472ms","start":"2026-03-12T19:30:10.417305Z","end":"2026-03-12T19:30:10.621684Z","steps":["trace[1483656612] 'process raft request' (duration: 96.604298ms)","trace[1483656612] 'compare' (duration: 107.365185ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:13.10448Z","caller":"traceutil/trace.go:171","msg":"trace[980469153] transaction","detail":"{read_only:false; response_revision:7731; number_of_response:1; }","duration":"119.375171ms","start":"2026-03-12T19:30:12.985036Z","end":"2026-03-12T19:30:13.104411Z","steps":["trace[980469153] 'process raft request' (duration: 119.171807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.368714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.519702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:30:13.368905Z","caller":"traceutil/trace.go:171","msg":"trace[276360502] linearizableReadLoop","detail":"{readStateIndex:8021; appliedIndex:8019; }","duration":"245.388908ms","start":"2026-03-12T19:30:13.123498Z","end":"2026-03-12T19:30:13.368887Z","steps":["trace[276360502] 'read index received' (duration: 48.043212ms)","trace[276360502] 'applied index is now lower than readState.Index' (duration: 197.344396ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:13.368955Z","caller":"traceutil/trace.go:171","msg":"trace[16129909] transaction","detail":"{read_only:false; response_revision:7734; number_of_response:1; }","duration":"244.06124ms","start":"2026-03-12T19:30:13.124863Z","end":"2026-03-12T19:30:13.368924Z","steps":["trace[16129909] 'process raft request' (duration: 243.965128ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.369011Z","caller":"traceutil/trace.go:171","msg":"trace[1087961470] transaction","detail":"{read_only:false; response_revision:7733; number_of_response:1; }","duration":"257.758763ms","start":"2026-03-12T19:30:13.111165Z","end":"2026-03-12T19:30:13.368924Z","steps":["trace[1087961470] 'process raft request' (duration: 138.928244ms)","trace[1087961470] 'compare' (duration: 118.364009ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:30:13.369085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.517041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.369119Z","caller":"traceutil/trace.go:171","msg":"trace[446688832] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:7734; }","duration":"245.573462ms","start":"2026-03-12T19:30:13.123536Z","end":"2026-03-12T19:30:13.369109Z","steps":["trace[446688832] 'agreement among raft nodes before linearized reading' (duration: 245.47289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.347146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.369213Z","caller":"traceutil/trace.go:171","msg":"trace[299261898] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:7734; }","duration":"117.451169ms","start":"2026-03-12T19:30:13.251746Z","end":"2026-03-12T19:30:13.369197Z","steps":["trace[299261898] 'agreement among raft nodes before linearized reading' (duration: 117.310676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.876752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.369282Z","caller":"traceutil/trace.go:171","msg":"trace[1606743608] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:7734; }","duration":"138.955903ms","start":"2026-03-12T19:30:13.230315Z","end":"2026-03-12T19:30:13.369271Z","steps":["trace[1606743608] 'agreement among raft nodes before linearized reading' (duration: 138.870031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.778726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.369425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.949541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.369461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.309327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.369472Z","caller":"traceutil/trace.go:171","msg":"trace[1011635157] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:7734; }","duration":"180.021112ms","start":"2026-03-12T19:30:13.189439Z","end":"2026-03-12T19:30:13.36946Z","steps":["trace[1011635157] 'agreement among raft nodes before linearized reading' (duration: 179.938571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.91264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.36963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.955274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.369663Z","caller":"traceutil/trace.go:171","msg":"trace[1531018693] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:7734; }","duration":"139.031896ms","start":"2026-03-12T19:30:13.230623Z","end":"2026-03-12T19:30:13.369655Z","steps":["trace[1531018693] 'agreement among raft nodes before linearized reading' (duration: 138.985335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.546505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.36972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.265002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.369682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.974506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.369779Z","caller":"traceutil/trace.go:171","msg":"trace[989125191] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:7734; }","duration":"224.327623ms","start":"2026-03-12T19:30:13.145438Z","end":"2026-03-12T19:30:13.369766Z","steps":["trace[989125191] 'agreement among raft nodes before linearized reading' (duration: 224.236011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.800175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.369815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.097319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.369798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.399572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.369667Z","caller":"traceutil/trace.go:171","msg":"trace[1923224335] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:7734; }","duration":"180.188836ms","start":"2026-03-12T19:30:13.189457Z","end":"2026-03-12T19:30:13.369646Z","steps":["trace[1923224335] 'agreement among raft nodes before linearized reading' (duration: 179.604044ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.369828Z","caller":"traceutil/trace.go:171","msg":"trace[1789137374] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:7734; }","duration":"224.203811ms","start":"2026-03-12T19:30:13.145587Z","end":"2026-03-12T19:30:13.369791Z","steps":["trace[1789137374] 'agreement among raft nodes before linearized reading' (duration: 223.914764ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.369962Z","caller":"traceutil/trace.go:171","msg":"trace[926650374] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:7734; }","duration":"159.882258ms","start":"2026-03-12T19:30:13.20995Z","end":"2026-03-12T19:30:13.369832Z","steps":["trace[926650374] 'agreement among raft nodes before linearized reading' (duration: 159.752065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.370104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.68783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.370198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.374179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.370249Z","caller":"traceutil/trace.go:171","msg":"trace[1702492631] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:7734; }","duration":"245.44603ms","start":"2026-03-12T19:30:13.12479Z","end":"2026-03-12T19:30:13.370236Z","steps":["trace[1702492631] 'agreement among raft nodes before linearized reading' (duration: 245.380239ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.370207Z","caller":"traceutil/trace.go:171","msg":"trace[625526191] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:7734; }","duration":"200.962236ms","start":"2026-03-12T19:30:13.169222Z","end":"2026-03-12T19:30:13.370185Z","steps":["trace[625526191] 'agreement among raft nodes before linearized reading' (duration: 200.404673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.370326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.318292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.370333Z","caller":"traceutil/trace.go:171","msg":"trace[736204060] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:7734; }","duration":"159.152611ms","start":"2026-03-12T19:30:13.210686Z","end":"2026-03-12T19:30:13.369839Z","steps":["trace[736204060] 'agreement among raft nodes before linearized reading' (duration: 159.072099ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.369728Z","caller":"traceutil/trace.go:171","msg":"trace[1000483519] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:7734; }","duration":"199.627926ms","start":"2026-03-12T19:30:13.170084Z","end":"2026-03-12T19:30:13.369712Z","steps":["trace[1000483519] 'agreement among raft nodes before linearized reading' (duration: 199.555924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.370367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.520017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.370382Z","caller":"traceutil/trace.go:171","msg":"trace[1274548942] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:7734; }","duration":"101.386584ms","start":"2026-03-12T19:30:13.268982Z","end":"2026-03-12T19:30:13.370368Z","steps":["trace[1274548942] 'agreement among raft nodes before linearized reading' (duration: 101.308063ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.370404Z","caller":"traceutil/trace.go:171","msg":"trace[842625780] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:7734; }","duration":"101.581038ms","start":"2026-03-12T19:30:13.268813Z","end":"2026-03-12T19:30:13.370394Z","steps":["trace[842625780] 'agreement among raft nodes before linearized reading' (duration: 101.522537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.45591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.37014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.904232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.370488Z","caller":"traceutil/trace.go:171","msg":"trace[188844364] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:7734; }","duration":"119.407472ms","start":"2026-03-12T19:30:13.251063Z","end":"2026-03-12T19:30:13.370471Z","steps":["trace[188844364] 'agreement among raft nodes before linearized reading' (duration: 118.463191ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.370529Z","caller":"traceutil/trace.go:171","msg":"trace[553321484] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:7734; }","duration":"242.318201ms","start":"2026-03-12T19:30:13.128197Z","end":"2026-03-12T19:30:13.370515Z","steps":["trace[553321484] 'agreement among raft nodes before linearized reading' (duration: 241.895742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.370301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.3568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.370599Z","caller":"traceutil/trace.go:171","msg":"trace[266029682] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:7734; }","duration":"223.555726ms","start":"2026-03-12T19:30:13.146324Z","end":"2026-03-12T19:30:13.36988Z","steps":["trace[266029682] 'agreement among raft nodes before linearized reading' (duration: 223.335801ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.370715Z","caller":"traceutil/trace.go:171","msg":"trace[87822575] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:7734; }","duration":"180.77787ms","start":"2026-03-12T19:30:13.189918Z","end":"2026-03-12T19:30:13.370696Z","steps":["trace[87822575] 'agreement among raft nodes before linearized reading' (duration: 180.342871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.303111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.369506Z","caller":"traceutil/trace.go:171","msg":"trace[665648701] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:7734; }","duration":"118.382429ms","start":"2026-03-12T19:30:13.251112Z","end":"2026-03-12T19:30:13.369494Z","steps":["trace[665648701] 'agreement among raft nodes before linearized reading' (duration: 118.319538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.619712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:30:13.370367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.411625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.370934Z","caller":"traceutil/trace.go:171","msg":"trace[1992779752] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:7734; }","duration":"160.90125ms","start":"2026-03-12T19:30:13.210005Z","end":"2026-03-12T19:30:13.370906Z","steps":["trace[1992779752] 'agreement among raft nodes before linearized reading' (duration: 159.607861ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.370942Z","caller":"traceutil/trace.go:171","msg":"trace[1826024555] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:7734; }","duration":"102.003067ms","start":"2026-03-12T19:30:13.268927Z","end":"2026-03-12T19:30:13.37093Z","steps":["trace[1826024555] 'agreement among raft nodes before linearized reading' (duration: 101.406234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:13.369588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.211727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:13.371089Z","caller":"traceutil/trace.go:171","msg":"trace[923451334] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:7734; }","duration":"139.710111ms","start":"2026-03-12T19:30:13.231357Z","end":"2026-03-12T19:30:13.371067Z","steps":["trace[923451334] 'agreement among raft nodes before linearized reading' (duration: 138.210098ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.370864Z","caller":"traceutil/trace.go:171","msg":"trace[695422915] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:7734; }","duration":"201.616401ms","start":"2026-03-12T19:30:13.169228Z","end":"2026-03-12T19:30:13.370844Z","steps":["trace[695422915] 'agreement among raft nodes before linearized reading' (duration: 200.295861ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:13.369576Z","caller":"traceutil/trace.go:171","msg":"trace[1698233650] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:7734; }","duration":"245.957541ms","start":"2026-03-12T19:30:13.123478Z","end":"2026-03-12T19:30:13.369436Z","steps":["trace[1698233650] 'agreement among raft nodes before linearized reading' (duration: 245.500811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:14.819549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.654261ms","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-03-12T19:30:14.819652Z","caller":"traceutil/trace.go:171","msg":"trace[505428083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7737; }","duration":"168.840075ms","start":"2026-03-12T19:30:14.65079Z","end":"2026-03-12T19:30:14.81963Z","steps":["trace[505428083] 'range keys from in-memory index tree' (duration: 168.339204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:14.820088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.131719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-12T19:30:14.820195Z","caller":"traceutil/trace.go:171","msg":"trace[1671649910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7737; }","duration":"153.317372ms","start":"2026-03-12T19:30:14.666852Z","end":"2026-03-12T19:30:14.820169Z","steps":["trace[1671649910] 'range keys from in-memory index tree' (duration: 152.917724ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:38.690275Z","caller":"traceutil/trace.go:171","msg":"trace[341637753] transaction","detail":"{read_only:false; response_revision:7909; number_of_response:1; }","duration":"107.153933ms","start":"2026-03-12T19:30:38.583091Z","end":"2026-03-12T19:30:38.690245Z","steps":["trace[341637753] 'process raft request' (duration: 106.969579ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:38.691386Z","caller":"traceutil/trace.go:171","msg":"trace[1528316725] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"107.713204ms","start":"2026-03-12T19:30:38.583633Z","end":"2026-03-12T19:30:38.691346Z","steps":["trace[1528316725] 'process raft request' (duration: 107.350886ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:42.460618Z","caller":"traceutil/trace.go:171","msg":"trace[615373850] linearizableReadLoop","detail":"{readStateIndex:8224; appliedIndex:8223; }","duration":"303.885193ms","start":"2026-03-12T19:30:42.156707Z","end":"2026-03-12T19:30:42.460592Z","steps":["trace[615373850] 'read index received' (duration: 302.237257ms)","trace[615373850] 'applied index is now lower than readState.Index' (duration: 1.646196ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:42.460926Z","caller":"traceutil/trace.go:171","msg":"trace[1767600662] transaction","detail":"{read_only:false; response_revision:7928; number_of_response:1; }","duration":"442.948003ms","start":"2026-03-12T19:30:42.017964Z","end":"2026-03-12T19:30:42.460912Z","steps":["trace[1767600662] 'process raft request' (duration: 440.947299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:42.461043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:42.017948Z","time spent":"443.018624ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:30:42.461275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.562958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:42.461315Z","caller":"traceutil/trace.go:171","msg":"trace[723619425] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:7928; }","duration":"304.64712ms","start":"2026-03-12T19:30:42.156656Z","end":"2026-03-12T19:30:42.461303Z","steps":["trace[723619425] 'agreement among raft nodes before linearized reading' (duration: 304.581388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:42.461349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:42.156631Z","time spent":"304.709241ms","remote":"127.0.0.1:60490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-12T19:30:42.846254Z","caller":"traceutil/trace.go:171","msg":"trace[1147361890] linearizableReadLoop","detail":"{readStateIndex:8226; appliedIndex:8224; }","duration":"130.399291ms","start":"2026-03-12T19:30:42.715823Z","end":"2026-03-12T19:30:42.846223Z","steps":["trace[1147361890] 'read index received' (duration: 116.835004ms)","trace[1147361890] 'applied index is now lower than readState.Index' (duration: 13.562767ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:30:42.846551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.711988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twdkv\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-12T19:30:42.846649Z","caller":"traceutil/trace.go:171","msg":"trace[1515950800] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twdkv; range_end:; response_count:1; response_revision:7930; }","duration":"130.861481ms","start":"2026-03-12T19:30:42.715764Z","end":"2026-03-12T19:30:42.846626Z","steps":["trace[1515950800] 'agreement among raft nodes before linearized reading' (duration: 130.621925ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:42.846757Z","caller":"traceutil/trace.go:171","msg":"trace[2136939886] transaction","detail":"{read_only:false; response_revision:7929; number_of_response:1; }","duration":"143.177489ms","start":"2026-03-12T19:30:42.703209Z","end":"2026-03-12T19:30:42.846386Z","steps":["trace[2136939886] 'process raft request' (duration: 129.428229ms)","trace[2136939886] 'compare' (duration: 13.33308ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:42.846873Z","caller":"traceutil/trace.go:171","msg":"trace[253613927] transaction","detail":"{read_only:false; response_revision:7930; number_of_response:1; }","duration":"138.139061ms","start":"2026-03-12T19:30:42.708721Z","end":"2026-03-12T19:30:42.84686Z","steps":["trace[253613927] 'process raft request' (duration: 137.451556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:42.84687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.910362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-12T19:30:42.84699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.973738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-12T19:30:42.847072Z","caller":"traceutil/trace.go:171","msg":"trace[1328840318] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:7930; }","duration":"128.06814ms","start":"2026-03-12T19:30:42.718985Z","end":"2026-03-12T19:30:42.847053Z","steps":["trace[1328840318] 'agreement among raft nodes before linearized reading' (duration: 127.878426ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:42.847013Z","caller":"traceutil/trace.go:171","msg":"trace[1176166616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7930; }","duration":"131.108226ms","start":"2026-03-12T19:30:42.715882Z","end":"2026-03-12T19:30:42.84699Z","steps":["trace[1176166616] 'agreement among raft nodes before linearized reading' (duration: 130.757999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:55.761694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.548871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-12T19:30:55.761812Z","caller":"traceutil/trace.go:171","msg":"trace[988070405] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:1; response_revision:8037; }","duration":"247.747235ms","start":"2026-03-12T19:30:55.514045Z","end":"2026-03-12T19:30:55.761792Z","steps":["trace[988070405] 'range keys from in-memory index tree' (duration: 247.377647ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:56.034465Z","caller":"traceutil/trace.go:171","msg":"trace[552054610] linearizableReadLoop","detail":"{readStateIndex:8339; appliedIndex:8339; }","duration":"271.191448ms","start":"2026-03-12T19:30:55.763231Z","end":"2026-03-12T19:30:56.034423Z","steps":["trace[552054610] 'read index received' (duration: 271.174457ms)","trace[552054610] 'applied index is now lower than readState.Index' (duration: 14.001µs)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:30:56.034711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.401163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:56.034904Z","caller":"traceutil/trace.go:171","msg":"trace[1989119285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8037; }","duration":"271.661878ms","start":"2026-03-12T19:30:55.763184Z","end":"2026-03-12T19:30:56.034846Z","steps":["trace[1989119285] 'agreement among raft nodes before linearized reading' (duration: 271.355731ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:56.32987Z","caller":"traceutil/trace.go:171","msg":"trace[32805106] linearizableReadLoop","detail":"{readStateIndex:8342; appliedIndex:8339; }","duration":"295.258765ms","start":"2026-03-12T19:30:56.03458Z","end":"2026-03-12T19:30:56.329839Z","steps":["trace[32805106] 'read index received' (duration: 295.074131ms)","trace[32805106] 'applied index is now lower than readState.Index' (duration: 183.444µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:56.329936Z","caller":"traceutil/trace.go:171","msg":"trace[2071694515] transaction","detail":"{read_only:false; response_revision:8038; number_of_response:1; }","duration":"564.958861ms","start":"2026-03-12T19:30:55.764919Z","end":"2026-03-12T19:30:56.329878Z","steps":["trace[2071694515] 'process raft request' (duration: 564.654875ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:56.330072Z","caller":"traceutil/trace.go:171","msg":"trace[1497779857] transaction","detail":"{read_only:false; response_revision:8039; number_of_response:1; }","duration":"505.180454ms","start":"2026-03-12T19:30:55.824863Z","end":"2026-03-12T19:30:56.330043Z","steps":["trace[1497779857] 'process raft request' (duration: 504.882017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:56.330214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:55.824832Z","time spent":"505.300926ms","remote":"127.0.0.1:60552","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-03-12T19:30:56.330216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:55.764907Z","time spent":"565.146715ms","remote":"127.0.0.1:60552","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-03-12T19:30:56.330286Z","caller":"traceutil/trace.go:171","msg":"trace[1644386820] transaction","detail":"{read_only:false; response_revision:8040; number_of_response:1; }","duration":"379.25454ms","start":"2026-03-12T19:30:55.950996Z","end":"2026-03-12T19:30:56.33025Z","steps":["trace[1644386820] 'process raft request' (duration: 378.78928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:56.330321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.158097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"warn","ts":"2026-03-12T19:30:56.330501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:55.950959Z","time spent":"379.418004ms","remote":"127.0.0.1:60506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:30:56.330397Z","caller":"traceutil/trace.go:171","msg":"trace[255715776] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8040; }","duration":"442.31619ms","start":"2026-03-12T19:30:55.888067Z","end":"2026-03-12T19:30:56.330384Z","steps":["trace[255715776] 'agreement among raft nodes before linearized reading' (duration: 441.890041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:56.330695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:55.888046Z","time spent":"442.636817ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5755,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-03-12T19:30:56.360347Z","caller":"traceutil/trace.go:171","msg":"trace[828916595] transaction","detail":"{read_only:false; response_revision:8041; number_of_response:1; }","duration":"324.577326ms","start":"2026-03-12T19:30:56.035739Z","end":"2026-03-12T19:30:56.360317Z","steps":["trace[828916595] 'process raft request' (duration: 324.393342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:56.360522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:56.035714Z","time spent":"324.721008ms","remote":"127.0.0.1:60256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:30:56.476501Z","caller":"traceutil/trace.go:171","msg":"trace[112124985] linearizableReadLoop","detail":"{readStateIndex:8344; appliedIndex:8343; }","duration":"113.554082ms","start":"2026-03-12T19:30:56.362907Z","end":"2026-03-12T19:30:56.476462Z","steps":["trace[112124985] 'read index received' (duration: 62.715291ms)","trace[112124985] 'applied index is now lower than readState.Index' (duration: 50.836601ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:30:56.477062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.085543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-12T19:30:56.477209Z","caller":"traceutil/trace.go:171","msg":"trace[805003] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8042; }","duration":"114.262707ms","start":"2026-03-12T19:30:56.362851Z","end":"2026-03-12T19:30:56.477113Z","steps":["trace[805003] 'agreement among raft nodes before linearized reading' (duration: 113.852849ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:56.47774Z","caller":"traceutil/trace.go:171","msg":"trace[360659501] transaction","detail":"{read_only:false; response_revision:8042; number_of_response:1; }","duration":"132.375365ms","start":"2026-03-12T19:30:56.345267Z","end":"2026-03-12T19:30:56.477643Z","steps":["trace[360659501] 'process raft request' (duration: 80.645773ms)","trace[360659501] 'compare' (duration: 50.352021ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:56.633588Z","caller":"traceutil/trace.go:171","msg":"trace[350188614] transaction","detail":"{read_only:false; response_revision:8043; number_of_response:1; }","duration":"140.711936ms","start":"2026-03-12T19:30:56.492844Z","end":"2026-03-12T19:30:56.633556Z","steps":["trace[350188614] 'process raft request' (duration: 140.537553ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:57.508997Z","caller":"traceutil/trace.go:171","msg":"trace[1240418265] linearizableReadLoop","detail":"{readStateIndex:8350; appliedIndex:8349; }","duration":"365.361117ms","start":"2026-03-12T19:30:57.143599Z","end":"2026-03-12T19:30:57.50896Z","steps":["trace[1240418265] 'read index received' (duration: 365.132043ms)","trace[1240418265] 'applied index is now lower than readState.Index' (duration: 227.414µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:57.509161Z","caller":"traceutil/trace.go:171","msg":"trace[2046517743] transaction","detail":"{read_only:false; response_revision:8048; number_of_response:1; }","duration":"398.626705ms","start":"2026-03-12T19:30:57.110489Z","end":"2026-03-12T19:30:57.509116Z","steps":["trace[2046517743] 'process raft request' (duration: 398.229076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:57.509367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:57.110471Z","time spent":"398.796079ms","remote":"127.0.0.1:60552","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-03-12T19:30:57.509429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.270938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-12T19:30:57.509488Z","caller":"traceutil/trace.go:171","msg":"trace[1799877237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8048; }","duration":"114.404071ms","start":"2026-03-12T19:30:57.395069Z","end":"2026-03-12T19:30:57.509473Z","steps":["trace[1799877237] 'agreement among raft nodes before linearized reading' (duration: 114.245768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:57.509546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.898909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-12T19:30:57.50965Z","caller":"traceutil/trace.go:171","msg":"trace[150770002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8048; }","duration":"366.049022ms","start":"2026-03-12T19:30:57.143581Z","end":"2026-03-12T19:30:57.50963Z","steps":["trace[150770002] 'agreement among raft nodes before linearized reading' (duration: 365.590042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:57.509744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:57.143574Z","time spent":"366.154974ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-12T19:30:57.509847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.989149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-03-12T19:30:57.509952Z","caller":"traceutil/trace.go:171","msg":"trace[1324163657] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8048; }","duration":"177.147422ms","start":"2026-03-12T19:30:57.332778Z","end":"2026-03-12T19:30:57.509925Z","steps":["trace[1324163657] 'agreement among raft nodes before linearized reading' (duration: 176.474168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:57.879675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.56385ms","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-03-12T19:30:57.879813Z","caller":"traceutil/trace.go:171","msg":"trace[970035021] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8049; }","duration":"182.718254ms","start":"2026-03-12T19:30:57.697076Z","end":"2026-03-12T19:30:57.879794Z","steps":["trace[970035021] 'range keys from in-memory index tree' (duration: 182.54523ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:57.880812Z","caller":"traceutil/trace.go:171","msg":"trace[584458693] linearizableReadLoop","detail":"{readStateIndex:8354; appliedIndex:8350; }","duration":"364.048868ms","start":"2026-03-12T19:30:57.516747Z","end":"2026-03-12T19:30:57.880796Z","steps":["trace[584458693] 'read index received' (duration: 40.719381ms)","trace[584458693] 'applied index is now lower than readState.Index' (duration: 323.328557ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:57.880802Z","caller":"traceutil/trace.go:171","msg":"trace[744745779] transaction","detail":"{read_only:false; response_revision:8050; number_of_response:1; }","duration":"365.777105ms","start":"2026-03-12T19:30:57.514969Z","end":"2026-03-12T19:30:57.880747Z","steps":["trace[744745779] 'process raft request' (duration: 345.129524ms)","trace[744745779] 'compare' (duration: 19.949086ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:57.88092Z","caller":"traceutil/trace.go:171","msg":"trace[421295237] transaction","detail":"{read_only:false; response_revision:8052; number_of_response:1; }","duration":"365.146072ms","start":"2026-03-12T19:30:57.515762Z","end":"2026-03-12T19:30:57.880908Z","steps":["trace[421295237] 'process raft request' (duration: 364.987488ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:57.880931Z","caller":"traceutil/trace.go:171","msg":"trace[1780950355] transaction","detail":"{read_only:false; response_revision:8051; number_of_response:1; }","duration":"365.884628ms","start":"2026-03-12T19:30:57.514993Z","end":"2026-03-12T19:30:57.880878Z","steps":["trace[1780950355] 'process raft request' (duration: 365.224774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:57.881015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:57.514949Z","time spent":"365.950219ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:30:57.881095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:57.515736Z","time spent":"365.220805ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:30:57.88118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.413607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ceph.189c2ed30f604f70\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-03-12T19:30:57.881265Z","caller":"traceutil/trace.go:171","msg":"trace[2107663645] range","detail":"{range_begin:/registry/events/openstack/ceph.189c2ed30f604f70; range_end:; response_count:1; response_revision:8052; }","duration":"364.53427ms","start":"2026-03-12T19:30:57.516712Z","end":"2026-03-12T19:30:57.881246Z","steps":["trace[2107663645] 'agreement among raft nodes before linearized reading' (duration: 364.280344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:57.881308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:57.516702Z","time spent":"364.597801ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":722,"request content":"key:\"/registry/events/openstack/ceph.189c2ed30f604f70\" "} {"level":"warn","ts":"2026-03-12T19:30:57.881195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:30:57.514975Z","time spent":"366.039492ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:30:57.882144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.418013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:30:57.8825Z","caller":"traceutil/trace.go:171","msg":"trace[777218305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8052; }","duration":"147.634227ms","start":"2026-03-12T19:30:57.734605Z","end":"2026-03-12T19:30:57.88224Z","steps":["trace[777218305] 'agreement among raft nodes before linearized reading' (duration: 147.408152ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:58.090177Z","caller":"traceutil/trace.go:171","msg":"trace[1803720309] transaction","detail":"{read_only:false; response_revision:8053; number_of_response:1; }","duration":"206.207508ms","start":"2026-03-12T19:30:57.883927Z","end":"2026-03-12T19:30:58.090135Z","steps":["trace[1803720309] 'process raft request' (duration: 200.638196ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:58.141766Z","caller":"traceutil/trace.go:171","msg":"trace[174406860] transaction","detail":"{read_only:false; response_revision:8054; number_of_response:1; }","duration":"177.772257ms","start":"2026-03-12T19:30:57.963955Z","end":"2026-03-12T19:30:58.141727Z","steps":["trace[174406860] 'process raft request' (duration: 177.552013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:58.313222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.367966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:30:58.313354Z","caller":"traceutil/trace.go:171","msg":"trace[1860444561] transaction","detail":"{read_only:false; response_revision:8055; number_of_response:1; }","duration":"165.791654ms","start":"2026-03-12T19:30:58.147542Z","end":"2026-03-12T19:30:58.313334Z","steps":["trace[1860444561] 'process raft request' (duration: 64.242385ms)","trace[1860444561] 'compare' (duration: 100.398884ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:58.322023Z","caller":"traceutil/trace.go:171","msg":"trace[1008884800] linearizableReadLoop","detail":"{readStateIndex:8358; appliedIndex:8356; }","duration":"173.019153ms","start":"2026-03-12T19:30:58.148994Z","end":"2026-03-12T19:30:58.322013Z","steps":["trace[1008884800] 'read index received' (duration: 62.905246ms)","trace[1008884800] 'applied index is now lower than readState.Index' (duration: 110.112957ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:58.322218Z","caller":"traceutil/trace.go:171","msg":"trace[1605329551] transaction","detail":"{read_only:false; response_revision:8056; number_of_response:1; }","duration":"174.141317ms","start":"2026-03-12T19:30:58.148066Z","end":"2026-03-12T19:30:58.322208Z","steps":["trace[1605329551] 'process raft request' (duration: 173.866511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:30:58.322468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.470953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-03-12T19:30:58.3225Z","caller":"traceutil/trace.go:171","msg":"trace[367524051] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:8056; }","duration":"173.546044ms","start":"2026-03-12T19:30:58.148946Z","end":"2026-03-12T19:30:58.322492Z","steps":["trace[367524051] 'agreement among raft nodes before linearized reading' (duration: 173.396901ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:30:58.457753Z","caller":"traceutil/trace.go:171","msg":"trace[1867283611] linearizableReadLoop","detail":"{readStateIndex:8359; appliedIndex:8358; }","duration":"122.445556ms","start":"2026-03-12T19:30:58.335259Z","end":"2026-03-12T19:30:58.457704Z","steps":["trace[1867283611] 'read index received' (duration: 94.641208ms)","trace[1867283611] 'applied index is now lower than readState.Index' (duration: 27.802658ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:30:58.457971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.711652ms","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-03-12T19:30:58.457973Z","caller":"traceutil/trace.go:171","msg":"trace[213118355] transaction","detail":"{read_only:false; response_revision:8057; number_of_response:1; }","duration":"128.085599ms","start":"2026-03-12T19:30:58.329821Z","end":"2026-03-12T19:30:58.457906Z","steps":["trace[213118355] 'process raft request' (duration: 99.971775ms)","trace[213118355] 'compare' (duration: 27.765467ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:30:58.458166Z","caller":"traceutil/trace.go:171","msg":"trace[1104686810] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8057; }","duration":"122.799673ms","start":"2026-03-12T19:30:58.335217Z","end":"2026-03-12T19:30:58.458016Z","steps":["trace[1104686810] 'agreement among raft nodes before linearized reading' (duration: 122.608479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:31:08.785685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.003921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-12T19:31:08.785818Z","caller":"traceutil/trace.go:171","msg":"trace[1328171012] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8134; }","duration":"178.239966ms","start":"2026-03-12T19:31:08.607548Z","end":"2026-03-12T19:31:08.785788Z","steps":["trace[1328171012] 'count revisions from in-memory index tree' (duration: 177.864958ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:32:00.965084Z","caller":"traceutil/trace.go:171","msg":"trace[1078039631] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"103.003211ms","start":"2026-03-12T19:32:00.862057Z","end":"2026-03-12T19:32:00.96506Z","steps":["trace[1078039631] 'process raft request' (duration: 102.876917ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:32:01.067212Z","caller":"traceutil/trace.go:171","msg":"trace[1354636959] linearizableReadLoop","detail":"{readStateIndex:9006; appliedIndex:9005; }","duration":"100.989876ms","start":"2026-03-12T19:32:00.966194Z","end":"2026-03-12T19:32:01.067184Z","steps":["trace[1354636959] 'read index received' (duration: 32.978747ms)","trace[1354636959] 'applied index is now lower than readState.Index' (duration: 68.009609ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:32:01.067318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.101988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:32:01.067369Z","caller":"traceutil/trace.go:171","msg":"trace[2086142902] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8687; }","duration":"101.201541ms","start":"2026-03-12T19:32:00.966156Z","end":"2026-03-12T19:32:01.067358Z","steps":["trace[2086142902] 'agreement among raft nodes before linearized reading' (duration: 101.098168ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:32:01.605388Z","caller":"traceutil/trace.go:171","msg":"trace[360813244] linearizableReadLoop","detail":"{readStateIndex:9009; appliedIndex:9008; }","duration":"152.866154ms","start":"2026-03-12T19:32:01.452497Z","end":"2026-03-12T19:32:01.605364Z","steps":["trace[360813244] 'read index received' (duration: 152.628069ms)","trace[360813244] 'applied index is now lower than readState.Index' (duration: 237.105µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:32:01.605728Z","caller":"traceutil/trace.go:171","msg":"trace[1987773541] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"214.006189ms","start":"2026-03-12T19:32:01.3917Z","end":"2026-03-12T19:32:01.605706Z","steps":["trace[1987773541] 'process raft request' (duration: 213.497928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:32:01.605867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.357524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twdkv\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-03-12T19:32:01.605869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.917431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:32:01.60594Z","caller":"traceutil/trace.go:171","msg":"trace[1852062032] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twdkv; range_end:; response_count:1; response_revision:8690; }","duration":"153.477628ms","start":"2026-03-12T19:32:01.452445Z","end":"2026-03-12T19:32:01.605922Z","steps":["trace[1852062032] 'agreement among raft nodes before linearized reading' (duration: 153.284694ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:32:01.605957Z","caller":"traceutil/trace.go:171","msg":"trace[395760305] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8690; }","duration":"117.047173ms","start":"2026-03-12T19:32:01.488885Z","end":"2026-03-12T19:32:01.605933Z","steps":["trace[395760305] 'agreement among raft nodes before linearized reading' (duration: 116.88621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:32:01.606064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.010453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-12T19:32:01.606196Z","caller":"traceutil/trace.go:171","msg":"trace[2022626652] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8690; }","duration":"134.198538ms","start":"2026-03-12T19:32:01.471966Z","end":"2026-03-12T19:32:01.606165Z","steps":["trace[2022626652] 'agreement among raft nodes before linearized reading' (duration: 133.809059ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:32:14.795187Z","caller":"traceutil/trace.go:171","msg":"trace[783663275] linearizableReadLoop","detail":"{readStateIndex:9079; appliedIndex:9078; }","duration":"481.469723ms","start":"2026-03-12T19:32:14.313697Z","end":"2026-03-12T19:32:14.795167Z","steps":["trace[783663275] 'read index received' (duration: 481.270698ms)","trace[783663275] 'applied index is now lower than readState.Index' (duration: 198.295µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:32:14.795307Z","caller":"traceutil/trace.go:171","msg":"trace[1245139436] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"507.877324ms","start":"2026-03-12T19:32:14.287391Z","end":"2026-03-12T19:32:14.795268Z","steps":["trace[1245139436] 'process raft request' (duration: 507.645069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:32:14.795443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.72361ms","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-03-12T19:32:14.795511Z","caller":"traceutil/trace.go:171","msg":"trace[857390745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8758; }","duration":"481.842142ms","start":"2026-03-12T19:32:14.313653Z","end":"2026-03-12T19:32:14.795495Z","steps":["trace[857390745] 'agreement among raft nodes before linearized reading' (duration: 481.630806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:32:14.79555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:32:14.313634Z","time spent":"481.905854ms","remote":"127.0.0.1:60552","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-03-12T19:32:14.79554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:32:14.287361Z","time spent":"508.033147ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:32:14.951371Z","caller":"traceutil/trace.go:171","msg":"trace[1367622052] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"150.534742ms","start":"2026-03-12T19:32:14.800808Z","end":"2026-03-12T19:32:14.951343Z","steps":["trace[1367622052] 'process raft request' (duration: 150.334638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:32:15.201495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.278535ms","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-03-12T19:32:15.201594Z","caller":"traceutil/trace.go:171","msg":"trace[1865196409] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8759; }","duration":"167.439049ms","start":"2026-03-12T19:32:15.034136Z","end":"2026-03-12T19:32:15.201575Z","steps":["trace[1865196409] 'range keys from in-memory index tree' (duration: 167.101641ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:32:15.384513Z","caller":"traceutil/trace.go:171","msg":"trace[1351506434] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"173.031375ms","start":"2026-03-12T19:32:15.211444Z","end":"2026-03-12T19:32:15.384475Z","steps":["trace[1351506434] 'process raft request' (duration: 150.031341ms)","trace[1351506434] 'compare' (duration: 22.845541ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:32:15.390731Z","caller":"traceutil/trace.go:171","msg":"trace[573499604] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"104.474612ms","start":"2026-03-12T19:32:15.286224Z","end":"2026-03-12T19:32:15.390699Z","steps":["trace[573499604] 'process raft request' (duration: 104.416831ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:32:15.390754Z","caller":"traceutil/trace.go:171","msg":"trace[1243164161] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"136.024828ms","start":"2026-03-12T19:32:15.2547Z","end":"2026-03-12T19:32:15.390725Z","steps":["trace[1243164161] 'process raft request' (duration: 135.755792ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:34:27.358924Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cf809824ab95ac81","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-12T19:34:27.37865Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-12T19:34:27.378847Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-12T19:35:02.403506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7682} {"level":"info","ts":"2026-03-12T19:35:02.473254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7682,"took":"67.358429ms","hash":2275371640,"current-db-size-bytes":42213376,"current-db-size":"42 MB","current-db-size-in-use-bytes":26021888,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-12T19:35:02.473313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2275371640,"revision":7682,"compact-revision":5607} {"level":"info","ts":"2026-03-12T19:40:02.413326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9922} {"level":"info","ts":"2026-03-12T19:40:02.493534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9922,"took":"77.794957ms","hash":1198623746,"current-db-size-bytes":42213376,"current-db-size":"42 MB","current-db-size-in-use-bytes":17297408,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-12T19:40:02.49361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1198623746,"revision":9922,"compact-revision":7682}