{"level":"warn","ts":"2026-05-10T00:53:00.719688Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T00:53:00.719775Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-05-10T00:53:00.719838Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T00:53:00.71985Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-10T00:53:00.719866Z","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-05-10T00:53:00.720589Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-10T00:53:00.720666Z","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://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-05-10T00:53:00.72404Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.177979ms"} {"level":"info","ts":"2026-05-10T00:53:00.735849Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-10T00:53:00.735982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T00:53:00.736017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-10T00:53:00.736033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T00:53:00.736052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-10T00:53:00.736107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-10T00:53:00.741377Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T00:53:00.742513Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T00:53:00.743432Z","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-05-10T00:53:00.745233Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T00:53:00.745428Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T00:53:00.745735Z","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-05-10T00:53:00.746437Z","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-05-10T00:53:00.746552Z","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-05-10T00:53:00.747494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-10T00:53:00.747708Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-10T00:53:00.749677Z","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-05-10T00:53:00.749781Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-10T00:53:00.749841Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-10T00:53:00.750059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T00:53:00.750125Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T00:53:01.736781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T00:53:01.736846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T00:53:01.736888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-10T00:53:01.736908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-10T00:53:01.736919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-10T00:53:01.736935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-10T00:53:01.736948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-10T00:53:01.738107Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T00:53:01.738116Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:53:01.738183Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:01.738215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:53:01.738506Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T00:53:01.73859Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T00:53:01.739458Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:01.739664Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:01.739705Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:01.741981Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-10T00:53:01.742373Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-10T00:53:32.50943Z","caller":"traceutil/trace.go:171","msg":"trace[26255650] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"231.690563ms","start":"2026-05-10T00:53:32.277707Z","end":"2026-05-10T00:53:32.509398Z","steps":["trace[26255650] 'read index received' (duration: 229.958845ms)","trace[26255650] 'applied index is now lower than readState.Index' (duration: 1.730518ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:53:32.509496Z","caller":"traceutil/trace.go:171","msg":"trace[1738155970] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"242.966296ms","start":"2026-05-10T00:53:32.26648Z","end":"2026-05-10T00:53:32.509446Z","steps":["trace[1738155970] 'process raft request' (duration: 241.630278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:32.509678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.93666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-05-10T00:53:32.509782Z","caller":"traceutil/trace.go:171","msg":"trace[1968567141] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:590; }","duration":"232.081373ms","start":"2026-05-10T00:53:32.277689Z","end":"2026-05-10T00:53:32.50977Z","steps":["trace[1968567141] 'agreement among raft nodes before linearized reading' (duration: 231.827026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:32.509676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.982392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:53:32.509945Z","caller":"traceutil/trace.go:171","msg":"trace[1794395542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"161.321072ms","start":"2026-05-10T00:53:32.348611Z","end":"2026-05-10T00:53:32.509932Z","steps":["trace[1794395542] 'agreement among raft nodes before linearized reading' (duration: 161.002943ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:36.925329Z","caller":"traceutil/trace.go:171","msg":"trace[420086427] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"101.801161ms","start":"2026-05-10T00:53:36.823499Z","end":"2026-05-10T00:53:36.9253Z","steps":["trace[420086427] 'read index received' (duration: 101.234806ms)","trace[420086427] 'applied index is now lower than readState.Index' (duration: 565.295µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:53:36.925466Z","caller":"traceutil/trace.go:171","msg":"trace[349025234] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"133.985565ms","start":"2026-05-10T00:53:36.791438Z","end":"2026-05-10T00:53:36.925424Z","steps":["trace[349025234] 'process raft request' (duration: 133.362868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:36.925733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.217563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zjk6g\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-10T00:53:36.925899Z","caller":"traceutil/trace.go:171","msg":"trace[1858235571] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zjk6g; range_end:; response_count:1; response_revision:618; }","duration":"102.342276ms","start":"2026-05-10T00:53:36.823436Z","end":"2026-05-10T00:53:36.925778Z","steps":["trace[1858235571] 'agreement among raft nodes before linearized reading' (duration: 101.979556ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:37.128922Z","caller":"traceutil/trace.go:171","msg":"trace[1750430066] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"177.215432ms","start":"2026-05-10T00:53:36.951658Z","end":"2026-05-10T00:53:37.128874Z","steps":["trace[1750430066] 'process raft request' (duration: 176.958855ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:37.137854Z","caller":"traceutil/trace.go:171","msg":"trace[1286884779] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"144.527757ms","start":"2026-05-10T00:53:36.993299Z","end":"2026-05-10T00:53:37.137827Z","steps":["trace[1286884779] 'process raft request' (duration: 144.474185ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:37.138094Z","caller":"traceutil/trace.go:171","msg":"trace[825458166] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"145.118443ms","start":"2026-05-10T00:53:36.992937Z","end":"2026-05-10T00:53:37.138056Z","steps":["trace[825458166] 'process raft request' (duration: 144.717082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:37.607473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.473122ms","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-05-10T00:53:37.607581Z","caller":"traceutil/trace.go:171","msg":"trace[2073817635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"255.641557ms","start":"2026-05-10T00:53:37.351917Z","end":"2026-05-10T00:53:37.607559Z","steps":["trace[2073817635] 'range keys from in-memory index tree' (duration: 255.208984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:37.607569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.219368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:53:37.607678Z","caller":"traceutil/trace.go:171","msg":"trace[953895438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"258.416193ms","start":"2026-05-10T00:53:37.349233Z","end":"2026-05-10T00:53:37.607649Z","steps":["trace[953895438] 'range keys from in-memory index tree' (duration: 258.098004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:37.607674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.220946ms","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-05-10T00:53:37.607759Z","caller":"traceutil/trace.go:171","msg":"trace[1627247722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"224.338648ms","start":"2026-05-10T00:53:37.383399Z","end":"2026-05-10T00:53:37.607737Z","steps":["trace[1627247722] 'range keys from in-memory index tree' (duration: 223.990739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:37.607868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.37659ms","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-05-10T00:53:37.607999Z","caller":"traceutil/trace.go:171","msg":"trace[2103864423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"224.552415ms","start":"2026-05-10T00:53:37.38341Z","end":"2026-05-10T00:53:37.607962Z","steps":["trace[2103864423] 'range keys from in-memory index tree' (duration: 224.105432ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:51.840344Z","caller":"traceutil/trace.go:171","msg":"trace[626448982] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"127.788571ms","start":"2026-05-10T00:53:51.712526Z","end":"2026-05-10T00:53:51.840315Z","steps":["trace[626448982] 'process raft request' (duration: 127.582606ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:51.880007Z","caller":"traceutil/trace.go:171","msg":"trace[58362548] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"166.111168ms","start":"2026-05-10T00:53:51.713868Z","end":"2026-05-10T00:53:51.87998Z","steps":["trace[58362548] 'process raft request' (duration: 166.061057ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:51.880066Z","caller":"traceutil/trace.go:171","msg":"trace[1796851433] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"167.169217ms","start":"2026-05-10T00:53:51.712858Z","end":"2026-05-10T00:53:51.880028Z","steps":["trace[1796851433] 'process raft request' (duration: 166.852308ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:51.880112Z","caller":"traceutil/trace.go:171","msg":"trace[935298138] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"166.818288ms","start":"2026-05-10T00:53:51.713256Z","end":"2026-05-10T00:53:51.880075Z","steps":["trace[935298138] 'process raft request' (duration: 166.600891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:10.665155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.350771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:55:10.665469Z","caller":"traceutil/trace.go:171","msg":"trace[881944113] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"199.77038ms","start":"2026-05-10T00:55:10.46568Z","end":"2026-05-10T00:55:10.66545Z","steps":["trace[881944113] 'process raft request' (duration: 199.665107ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:10.665605Z","caller":"traceutil/trace.go:171","msg":"trace[1164543962] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"225.482288ms","start":"2026-05-10T00:55:10.44007Z","end":"2026-05-10T00:55:10.665552Z","steps":["trace[1164543962] 'read index received' (duration: 34.090869ms)","trace[1164543962] 'applied index is now lower than readState.Index' (duration: 191.386219ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:55:10.665753Z","caller":"traceutil/trace.go:171","msg":"trace[333923416] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"287.908767ms","start":"2026-05-10T00:55:10.377706Z","end":"2026-05-10T00:55:10.665615Z","steps":["trace[333923416] 'process raft request' (duration: 96.435175ms)","trace[333923416] 'compare' (duration: 190.097864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:55:10.666058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.680873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a5cc678b-774f-4b51-80e3-61af8783a61c\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-05-10T00:55:10.666117Z","caller":"traceutil/trace.go:171","msg":"trace[300906383] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a5cc678b-774f-4b51-80e3-61af8783a61c; range_end:; response_count:1; response_revision:1849; }","duration":"226.081994ms","start":"2026-05-10T00:55:10.440011Z","end":"2026-05-10T00:55:10.666093Z","steps":["trace[300906383] 'agreement among raft nodes before linearized reading' (duration: 225.676003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:10.666521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.571452ms","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-05-10T00:55:10.666597Z","caller":"traceutil/trace.go:171","msg":"trace[480342463] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1849; }","duration":"172.687035ms","start":"2026-05-10T00:55:10.493893Z","end":"2026-05-10T00:55:10.66658Z","steps":["trace[480342463] 'agreement among raft nodes before linearized reading' (duration: 172.058287ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.726519Z","caller":"traceutil/trace.go:171","msg":"trace[95921290] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"132.946831ms","start":"2026-05-10T00:55:54.593546Z","end":"2026-05-10T00:55:54.726493Z","steps":["trace[95921290] 'process raft request' (duration: 132.793837ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:54.756068Z","caller":"traceutil/trace.go:171","msg":"trace[754557387] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"161.815816ms","start":"2026-05-10T00:55:54.594226Z","end":"2026-05-10T00:55:54.756041Z","steps":["trace[754557387] 'process raft request' (duration: 161.701213ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:50.021071Z","caller":"traceutil/trace.go:171","msg":"trace[1317777554] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"102.524339ms","start":"2026-05-10T00:56:49.918515Z","end":"2026-05-10T00:56:50.02104Z","steps":["trace[1317777554] 'process raft request' (duration: 102.278822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:59:59.607147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.177117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:59:59.607264Z","caller":"traceutil/trace.go:171","msg":"trace[1869482762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3839; }","duration":"258.348782ms","start":"2026-05-10T00:59:59.348884Z","end":"2026-05-10T00:59:59.607233Z","steps":["trace[1869482762] 'range keys from in-memory index tree' (duration: 258.060613ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:59:59.607332Z","caller":"traceutil/trace.go:171","msg":"trace[1355463411] transaction","detail":"{read_only:false; response_revision:3840; number_of_response:1; }","duration":"109.54469ms","start":"2026-05-10T00:59:59.497765Z","end":"2026-05-10T00:59:59.60731Z","steps":["trace[1355463411] 'process raft request' (duration: 109.076447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:28.74808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.208928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T01:01:28.748204Z","caller":"traceutil/trace.go:171","msg":"trace[259685575] linearizableReadLoop","detail":"{readStateIndex:5221; appliedIndex:5220; }","duration":"286.742033ms","start":"2026-05-10T01:01:28.461443Z","end":"2026-05-10T01:01:28.748185Z","steps":["trace[259685575] 'read index received' (duration: 113.253968ms)","trace[259685575] 'applied index is now lower than readState.Index' (duration: 173.486275ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:01:28.7483Z","caller":"traceutil/trace.go:171","msg":"trace[151808032] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"307.376911ms","start":"2026-05-10T01:01:28.440911Z","end":"2026-05-10T01:01:28.748288Z","steps":["trace[151808032] 'process raft request' (duration: 133.848464ms)","trace[151808032] 'compare' (duration: 172.994493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:01:28.748359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:28.440889Z","time spent":"307.436683ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3702,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:01:28.74851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.044852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T01:01:28.748598Z","caller":"traceutil/trace.go:171","msg":"trace[106763551] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5032; }","duration":"287.169285ms","start":"2026-05-10T01:01:28.461406Z","end":"2026-05-10T01:01:28.748575Z","steps":["trace[106763551] 'agreement among raft nodes before linearized reading' (duration: 287.01843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:28.748637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.967925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-19671dd0-6b3b-4634-b16d-3d8867e71037\" ","response":"range_response_count:1 size:3717"} {"level":"info","ts":"2026-05-10T01:01:28.748723Z","caller":"traceutil/trace.go:171","msg":"trace[232175963] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-19671dd0-6b3b-4634-b16d-3d8867e71037; range_end:; response_count:1; response_revision:5032; }","duration":"198.111759ms","start":"2026-05-10T01:01:28.550591Z","end":"2026-05-10T01:01:28.748702Z","steps":["trace[232175963] 'agreement among raft nodes before linearized reading' (duration: 197.943384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:28.748833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.244324ms","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-05-10T01:01:28.748893Z","caller":"traceutil/trace.go:171","msg":"trace[1735855921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5032; }","duration":"131.346298ms","start":"2026-05-10T01:01:28.617534Z","end":"2026-05-10T01:01:28.74888Z","steps":["trace[1735855921] 'agreement among raft nodes before linearized reading' (duration: 131.188963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:28.748808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.355113ms","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-05-10T01:01:28.749044Z","caller":"traceutil/trace.go:171","msg":"trace[190217753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5032; }","duration":"126.623409ms","start":"2026-05-10T01:01:28.622385Z","end":"2026-05-10T01:01:28.749008Z","steps":["trace[190217753] 'agreement among raft nodes before linearized reading' (duration: 126.221488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:29.14059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.110019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T01:01:29.140722Z","caller":"traceutil/trace.go:171","msg":"trace[757032463] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"183.482114ms","start":"2026-05-10T01:01:28.957214Z","end":"2026-05-10T01:01:29.140697Z","steps":["trace[757032463] 'process raft request' (duration: 18.18751ms)","trace[757032463] 'compare' (duration: 164.971465ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:01:41.099923Z","caller":"traceutil/trace.go:171","msg":"trace[354884674] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"197.324639ms","start":"2026-05-10T01:01:40.902539Z","end":"2026-05-10T01:01:41.099864Z","steps":["trace[354884674] 'process raft request' (duration: 99.027994ms)","trace[354884674] 'compare' (duration: 98.101259ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:01:49.428529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.352748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T01:01:49.428838Z","caller":"traceutil/trace.go:171","msg":"trace[690817930] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"183.272599ms","start":"2026-05-10T01:01:49.245543Z","end":"2026-05-10T01:01:49.428816Z","steps":["trace[690817930] 'process raft request' (duration: 183.137626ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:49.428932Z","caller":"traceutil/trace.go:171","msg":"trace[342594797] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5663; }","duration":"215.779397ms","start":"2026-05-10T01:01:49.213115Z","end":"2026-05-10T01:01:49.428895Z","steps":["trace[342594797] 'read index received' (duration: 90.988228ms)","trace[342594797] 'applied index is now lower than readState.Index' (duration: 124.787329ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:01:49.428951Z","caller":"traceutil/trace.go:171","msg":"trace[1996203617] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"221.303987ms","start":"2026-05-10T01:01:49.20756Z","end":"2026-05-10T01:01:49.428864Z","steps":["trace[1996203617] 'process raft request' (duration: 96.519267ms)","trace[1996203617] 'compare' (duration: 123.903576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:01:49.429232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.142983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9ba764e-c6aa-484f-b290-ab24a177d0fc\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-05-10T01:01:49.429227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.094136ms","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-05-10T01:01:49.42928Z","caller":"traceutil/trace.go:171","msg":"trace[1948682391] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9ba764e-c6aa-484f-b290-ab24a177d0fc; range_end:; response_count:1; response_revision:5465; }","duration":"107.262006ms","start":"2026-05-10T01:01:49.322004Z","end":"2026-05-10T01:01:49.429266Z","steps":["trace[1948682391] 'agreement among raft nodes before linearized reading' (duration: 107.095632ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:49.429294Z","caller":"traceutil/trace.go:171","msg":"trace[1933443140] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5465; }","duration":"216.191759ms","start":"2026-05-10T01:01:49.213085Z","end":"2026-05-10T01:01:49.429277Z","steps":["trace[1933443140] 'agreement among raft nodes before linearized reading' (duration: 215.929132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.865963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.798399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T01:01:49.866271Z","caller":"traceutil/trace.go:171","msg":"trace[1127460954] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"429.90441ms","start":"2026-05-10T01:01:49.43632Z","end":"2026-05-10T01:01:49.866224Z","steps":["trace[1127460954] 'process raft request' (duration: 140.764211ms)","trace[1127460954] 'compare' (duration: 288.502912ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:01:49.866351Z","caller":"traceutil/trace.go:171","msg":"trace[1685134458] linearizableReadLoop","detail":"{readStateIndex:5666; appliedIndex:5665; }","duration":"428.621235ms","start":"2026-05-10T01:01:49.437683Z","end":"2026-05-10T01:01:49.866305Z","steps":["trace[1685134458] 'read index received' (duration: 139.536678ms)","trace[1685134458] 'applied index is now lower than readState.Index' (duration: 289.080067ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:01:49.866435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.436302Z","time spent":"430.047254ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":919,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T01:01:49.866406Z","caller":"traceutil/trace.go:171","msg":"trace[19723899] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"427.743122ms","start":"2026-05-10T01:01:49.438597Z","end":"2026-05-10T01:01:49.86634Z","steps":["trace[19723899] 'process raft request' (duration: 427.650478ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:49.866485Z","caller":"traceutil/trace.go:171","msg":"trace[267549132] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"427.157966ms","start":"2026-05-10T01:01:49.439306Z","end":"2026-05-10T01:01:49.866464Z","steps":["trace[267549132] 'process raft request' (duration: 427.023412ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:49.866505Z","caller":"traceutil/trace.go:171","msg":"trace[608732954] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"426.783167ms","start":"2026-05-10T01:01:49.439703Z","end":"2026-05-10T01:01:49.866486Z","steps":["trace[608732954] 'process raft request' (duration: 426.719565ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:49.866526Z","caller":"traceutil/trace.go:171","msg":"trace[686132416] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"426.990451ms","start":"2026-05-10T01:01:49.439489Z","end":"2026-05-10T01:01:49.866479Z","steps":["trace[686132416] 'process raft request' (duration: 426.905939ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:49.866573Z","caller":"traceutil/trace.go:171","msg":"trace[672001911] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"427.30199ms","start":"2026-05-10T01:01:49.439253Z","end":"2026-05-10T01:01:49.866555Z","steps":["trace[672001911] 'process raft request' (duration: 427.043733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.866591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.438585Z","time spent":"427.917586ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":919,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T01:01:49.866596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.439672Z","time spent":"426.890039ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":916,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T01:01:49.866605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.439292Z","time spent":"427.253998ms","remote":"127.0.0.1:40018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1603,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:01:49.866688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.439479Z","time spent":"427.120406ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T01:01:49.866727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.439243Z","time spent":"427.369232ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":910,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T01:01:49.866901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.203172ms","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-05-10T01:01:49.866917Z","caller":"traceutil/trace.go:171","msg":"trace[638877934] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"426.159799ms","start":"2026-05-10T01:01:49.440741Z","end":"2026-05-10T01:01:49.8669Z","steps":["trace[638877934] 'process raft request' (duration: 425.708837ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:49.866942Z","caller":"traceutil/trace.go:171","msg":"trace[1224434521] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5472; }","duration":"429.266704ms","start":"2026-05-10T01:01:49.437667Z","end":"2026-05-10T01:01:49.866934Z","steps":["trace[1224434521] 'agreement among raft nodes before linearized reading' (duration: 428.843342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.866964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.437659Z","time spent":"429.300534ms","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":732,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-05-10T01:01:49.866978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.440729Z","time spent":"426.21819ms","remote":"127.0.0.1:40018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1411,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:01:49.86699Z","caller":"traceutil/trace.go:171","msg":"trace[1754905877] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"426.19441ms","start":"2026-05-10T01:01:49.44078Z","end":"2026-05-10T01:01:49.866975Z","steps":["trace[1754905877] 'process raft request' (duration: 426.008085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.867059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.440771Z","time spent":"426.249621ms","remote":"127.0.0.1:40018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1619,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:01:49.867433Z","caller":"traceutil/trace.go:171","msg":"trace[291978792] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"426.596411ms","start":"2026-05-10T01:01:49.440824Z","end":"2026-05-10T01:01:49.867421Z","steps":["trace[291978792] 'process raft request' (duration: 426.072027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.867496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.440806Z","time spent":"426.662162ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":934,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T01:01:49.86754Z","caller":"traceutil/trace.go:171","msg":"trace[300526116] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"417.121445ms","start":"2026-05-10T01:01:49.45033Z","end":"2026-05-10T01:01:49.867451Z","steps":["trace[300526116] 'process raft request' (duration: 417.015583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.867679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.450303Z","time spent":"417.32548ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4226,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:01:49.885202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.993952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-d64np\" ","response":"range_response_count:1 size:8066"} {"level":"info","ts":"2026-05-10T01:01:49.885309Z","caller":"traceutil/trace.go:171","msg":"trace[1280675259] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-d64np; range_end:; response_count:1; response_revision:5476; }","duration":"163.212848ms","start":"2026-05-10T01:01:49.722072Z","end":"2026-05-10T01:01:49.885285Z","steps":["trace[1280675259] 'agreement among raft nodes before linearized reading' (duration: 162.843028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.885318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.828155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-10T01:01:49.885389Z","caller":"traceutil/trace.go:171","msg":"trace[1286813239] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5476; }","duration":"420.954278ms","start":"2026-05-10T01:01:49.464415Z","end":"2026-05-10T01:01:49.88537Z","steps":["trace[1286813239] 'agreement among raft nodes before linearized reading' (duration: 420.775373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.885428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.464402Z","time spent":"421.018559ms","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1845,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-10T01:01:49.885674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.779411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-94nfb\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-05-10T01:01:49.885675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.054904ms","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-05-10T01:01:49.885725Z","caller":"traceutil/trace.go:171","msg":"trace[307838859] range","detail":"{range_begin:/registry/pods/openstack/keepalived-94nfb; range_end:; response_count:1; response_revision:5476; }","duration":"444.882383ms","start":"2026-05-10T01:01:49.440833Z","end":"2026-05-10T01:01:49.885715Z","steps":["trace[307838859] 'agreement among raft nodes before linearized reading' (duration: 444.74733ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:49.885739Z","caller":"traceutil/trace.go:171","msg":"trace[695401733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5476; }","duration":"183.169326ms","start":"2026-05-10T01:01:49.702555Z","end":"2026-05-10T01:01:49.885724Z","steps":["trace[695401733] 'agreement among raft nodes before linearized reading' (duration: 182.977001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:01:49.885756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:01:49.440818Z","time spent":"444.929275ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-94nfb\" "} {"level":"info","ts":"2026-05-10T01:03:01.768054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3028} {"level":"info","ts":"2026-05-10T01:03:01.865523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3028,"took":"95.642811ms","hash":322832980,"current-db-size-bytes":57057280,"current-db-size":"57 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-10T01:03:01.865599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":322832980,"revision":3028,"compact-revision":-1} {"level":"info","ts":"2026-05-10T01:08:01.172265Z","caller":"traceutil/trace.go:171","msg":"trace[1101872211] transaction","detail":"{read_only:false; response_revision:8301; number_of_response:1; }","duration":"220.40687ms","start":"2026-05-10T01:08:00.951809Z","end":"2026-05-10T01:08:01.172215Z","steps":["trace[1101872211] 'process raft request' (duration: 220.138113ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:01.788562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6112} {"level":"info","ts":"2026-05-10T01:08:01.888338Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6112,"took":"96.914036ms","hash":744531396,"current-db-size-bytes":57057280,"current-db-size":"57 MB","current-db-size-in-use-bytes":23769088,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-10T01:08:01.888435Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":744531396,"revision":6112,"compact-revision":3028} {"level":"info","ts":"2026-05-10T01:08:12.792246Z","caller":"traceutil/trace.go:171","msg":"trace[790073871] transaction","detail":"{read_only:false; response_revision:8360; number_of_response:1; }","duration":"168.043172ms","start":"2026-05-10T01:08:12.624163Z","end":"2026-05-10T01:08:12.792206Z","steps":["trace[790073871] 'process raft request' (duration: 167.867707ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:12.835368Z","caller":"traceutil/trace.go:171","msg":"trace[10923994] linearizableReadLoop","detail":"{readStateIndex:8656; appliedIndex:8654; }","duration":"108.689185ms","start":"2026-05-10T01:08:12.726647Z","end":"2026-05-10T01:08:12.835336Z","steps":["trace[10923994] 'read index received' (duration: 65.489384ms)","trace[10923994] 'applied index is now lower than readState.Index' (duration: 43.197771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:08:12.835638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.956962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-10T01:08:12.835689Z","caller":"traceutil/trace.go:171","msg":"trace[818745345] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8361; }","duration":"109.052684ms","start":"2026-05-10T01:08:12.72662Z","end":"2026-05-10T01:08:12.835673Z","steps":["trace[818745345] 'agreement among raft nodes before linearized reading' (duration: 108.841208ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:12.835489Z","caller":"traceutil/trace.go:171","msg":"trace[1344928590] transaction","detail":"{read_only:false; response_revision:8361; number_of_response:1; }","duration":"209.573237ms","start":"2026-05-10T01:08:12.62588Z","end":"2026-05-10T01:08:12.835454Z","steps":["trace[1344928590] 'process raft request' (duration: 209.275328ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:13.162637Z","caller":"traceutil/trace.go:171","msg":"trace[309563490] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"314.348086ms","start":"2026-05-10T01:08:12.848255Z","end":"2026-05-10T01:08:13.162603Z","steps":["trace[309563490] 'process raft request' (duration: 313.853403ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:13.162652Z","caller":"traceutil/trace.go:171","msg":"trace[637730900] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"364.298989ms","start":"2026-05-10T01:08:12.798299Z","end":"2026-05-10T01:08:13.162598Z","steps":["trace[637730900] 'process raft request' (duration: 325.769536ms)","trace[637730900] 'compare' (duration: 37.633119ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:08:13.162856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:08:12.798264Z","time spent":"364.485554ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:08:13.163184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:08:12.848227Z","time spent":"314.5118ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:08:13.957928Z","caller":"traceutil/trace.go:171","msg":"trace[286936628] transaction","detail":"{read_only:false; response_revision:8368; number_of_response:1; }","duration":"201.838108ms","start":"2026-05-10T01:08:13.756054Z","end":"2026-05-10T01:08:13.957892Z","steps":["trace[286936628] 'process raft request' (duration: 197.641575ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:41.978352Z","caller":"traceutil/trace.go:171","msg":"trace[1032231550] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"136.780746ms","start":"2026-05-10T01:08:41.841536Z","end":"2026-05-10T01:08:41.978316Z","steps":["trace[1032231550] 'process raft request' (duration: 136.57337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:08:42.17931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.333838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-10T01:08:42.179432Z","caller":"traceutil/trace.go:171","msg":"trace[617365664] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8571; }","duration":"148.498723ms","start":"2026-05-10T01:08:42.030895Z","end":"2026-05-10T01:08:42.179393Z","steps":["trace[617365664] 'range keys from in-memory index tree' (duration: 148.137773ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:54.705969Z","caller":"traceutil/trace.go:171","msg":"trace[549447695] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"113.014822ms","start":"2026-05-10T01:08:54.592922Z","end":"2026-05-10T01:08:54.705937Z","steps":["trace[549447695] 'process raft request' (duration: 112.826957ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:54.739531Z","caller":"traceutil/trace.go:171","msg":"trace[438247294] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"139.264813ms","start":"2026-05-10T01:08:54.600233Z","end":"2026-05-10T01:08:54.739498Z","steps":["trace[438247294] 'process raft request' (duration: 139.033067ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:55.67231Z","caller":"traceutil/trace.go:171","msg":"trace[825614891] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"122.400048ms","start":"2026-05-10T01:08:55.549862Z","end":"2026-05-10T01:08:55.672262Z","steps":["trace[825614891] 'process raft request' (duration: 122.030498ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:09:25.878656Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002463.wal"} {"level":"info","ts":"2026-05-10T01:10:13.868131Z","caller":"traceutil/trace.go:171","msg":"trace[99421805] transaction","detail":"{read_only:false; response_revision:9446; number_of_response:1; }","duration":"262.416921ms","start":"2026-05-10T01:10:13.605674Z","end":"2026-05-10T01:10:13.868091Z","steps":["trace[99421805] 'process raft request' (duration: 246.435629ms)","trace[99421805] 'compare' (duration: 15.700374ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:10:44.498617Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-10T01:10:44.508644Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-10T01:10:44.508808Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-10T01:13:01.796043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8306} {"level":"info","ts":"2026-05-10T01:13:01.879889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8306,"took":"80.696114ms","hash":584281182,"current-db-size-bytes":57057280,"current-db-size":"57 MB","current-db-size-in-use-bytes":26415104,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-10T01:13:01.880008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":584281182,"revision":8306,"compact-revision":6112} {"level":"info","ts":"2026-05-10T01:18:01.803259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10794} {"level":"info","ts":"2026-05-10T01:18:01.886854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10794,"took":"80.529325ms","hash":494404015,"current-db-size-bytes":57057280,"current-db-size":"57 MB","current-db-size-in-use-bytes":26730496,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-10T01:18:01.886934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":494404015,"revision":10794,"compact-revision":8306} {"level":"warn","ts":"2026-05-10T01:20:27.683701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.11265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T01:20:27.683802Z","caller":"traceutil/trace.go:171","msg":"trace[346976601] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:13907; }","duration":"252.247483ms","start":"2026-05-10T01:20:27.431533Z","end":"2026-05-10T01:20:27.68378Z","steps":["trace[346976601] 'count revisions from in-memory index tree' (duration: 251.923185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:20:27.68385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.609978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T01:20:27.68399Z","caller":"traceutil/trace.go:171","msg":"trace[778342259] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:13907; }","duration":"226.848274ms","start":"2026-05-10T01:20:27.457115Z","end":"2026-05-10T01:20:27.683963Z","steps":["trace[778342259] 'count revisions from in-memory index tree' (duration: 226.431653ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:20:27.81178Z","caller":"traceutil/trace.go:171","msg":"trace[614206239] transaction","detail":"{read_only:false; response_revision:13908; number_of_response:1; }","duration":"122.895625ms","start":"2026-05-10T01:20:27.688844Z","end":"2026-05-10T01:20:27.81174Z","steps":["trace[614206239] 'process raft request' (duration: 122.646068ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:20:27.891404Z","caller":"traceutil/trace.go:171","msg":"trace[1129236537] transaction","detail":"{read_only:false; response_revision:13909; number_of_response:1; }","duration":"201.297111ms","start":"2026-05-10T01:20:27.690081Z","end":"2026-05-10T01:20:27.891378Z","steps":["trace[1129236537] 'process raft request' (duration: 196.130611ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:20:27.8914Z","caller":"traceutil/trace.go:171","msg":"trace[380627853] linearizableReadLoop","detail":"{readStateIndex:14385; appliedIndex:14383; }","duration":"138.912219ms","start":"2026-05-10T01:20:27.752447Z","end":"2026-05-10T01:20:27.891359Z","steps":["trace[380627853] 'read index received' (duration: 59.130205ms)","trace[380627853] 'applied index is now lower than readState.Index' (duration: 79.780904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:20:27.891615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.151665ms","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-05-10T01:20:27.891661Z","caller":"traceutil/trace.go:171","msg":"trace[1422600013] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:13909; }","duration":"139.267628ms","start":"2026-05-10T01:20:27.752378Z","end":"2026-05-10T01:20:27.891645Z","steps":["trace[1422600013] 'agreement among raft nodes before linearized reading' (duration: 139.072913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:20:50.085106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.147822ms","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-05-10T01:20:50.085233Z","caller":"traceutil/trace.go:171","msg":"trace[1239921074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14219; }","duration":"150.319187ms","start":"2026-05-10T01:20:49.934889Z","end":"2026-05-10T01:20:50.085208Z","steps":["trace[1239921074] 'range keys from in-memory index tree' (duration: 149.891426ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:22:20.485902Z","caller":"traceutil/trace.go:171","msg":"trace[933273675] linearizableReadLoop","detail":"{readStateIndex:15560; appliedIndex:15559; }","duration":"116.158409ms","start":"2026-05-10T01:22:20.369716Z","end":"2026-05-10T01:22:20.485874Z","steps":["trace[933273675] 'read index received' (duration: 115.753608ms)","trace[933273675] 'applied index is now lower than readState.Index' (duration: 403.431µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:22:20.486048Z","caller":"traceutil/trace.go:171","msg":"trace[2104919157] transaction","detail":"{read_only:false; response_revision:15046; number_of_response:1; }","duration":"117.488115ms","start":"2026-05-10T01:22:20.368508Z","end":"2026-05-10T01:22:20.485996Z","steps":["trace[2104919157] 'process raft request' (duration: 116.969131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:22:20.486251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.485867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T01:22:20.486393Z","caller":"traceutil/trace.go:171","msg":"trace[1990922458] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15046; }","duration":"116.667293ms","start":"2026-05-10T01:22:20.369698Z","end":"2026-05-10T01:22:20.486366Z","steps":["trace[1990922458] 'agreement among raft nodes before linearized reading' (duration: 116.400016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:22:20.495951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.369276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-10T01:22:20.495995Z","caller":"traceutil/trace.go:171","msg":"trace[1093491990] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15046; }","duration":"109.443288ms","start":"2026-05-10T01:22:20.38654Z","end":"2026-05-10T01:22:20.495984Z","steps":["trace[1093491990] 'agreement among raft nodes before linearized reading' (duration: 109.290984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:22:20.496122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.14856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T01:22:20.496145Z","caller":"traceutil/trace.go:171","msg":"trace[1606514134] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15046; }","duration":"126.18321ms","start":"2026-05-10T01:22:20.369956Z","end":"2026-05-10T01:22:20.496139Z","steps":["trace[1606514134] 'agreement among raft nodes before linearized reading' (duration: 126.15034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:22:20.49622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.361466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T01:22:20.496241Z","caller":"traceutil/trace.go:171","msg":"trace[924781492] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15046; }","duration":"126.429467ms","start":"2026-05-10T01:22:20.369805Z","end":"2026-05-10T01:22:20.496235Z","steps":["trace[924781492] 'agreement among raft nodes before linearized reading' (duration: 126.399876ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:23:01.811309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12866} {"level":"info","ts":"2026-05-10T01:23:01.888828Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12866,"took":"73.346693ms","hash":2114917921,"current-db-size-bytes":57057280,"current-db-size":"57 MB","current-db-size-in-use-bytes":29949952,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-10T01:23:01.888913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2114917921,"revision":12866,"compact-revision":10794} {"level":"info","ts":"2026-05-10T01:28:01.819261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15325} {"level":"info","ts":"2026-05-10T01:28:01.918766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15325,"took":"95.331941ms","hash":2966412837,"current-db-size-bytes":57057280,"current-db-size":"57 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-10T01:28:01.918844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2966412837,"revision":15325,"compact-revision":12866} {"level":"warn","ts":"2026-05-10T01:28:07.892058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.146951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-10T01:28:07.892184Z","caller":"traceutil/trace.go:171","msg":"trace[680063445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17627; }","duration":"175.332897ms","start":"2026-05-10T01:28:07.716818Z","end":"2026-05-10T01:28:07.892151Z","steps":["trace[680063445] 'range keys from in-memory index tree' (duration: 174.860033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:28:07.892326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.227706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-10T01:28:07.892368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.41356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-10T01:28:07.892453Z","caller":"traceutil/trace.go:171","msg":"trace[390893257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17627; }","duration":"178.410371ms","start":"2026-05-10T01:28:07.714014Z","end":"2026-05-10T01:28:07.892425Z","steps":["trace[390893257] 'range keys from in-memory index tree' (duration: 177.957398ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:28:07.892488Z","caller":"traceutil/trace.go:171","msg":"trace[632310831] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17627; }","duration":"175.586934ms","start":"2026-05-10T01:28:07.716874Z","end":"2026-05-10T01:28:07.892461Z","steps":["trace[632310831] 'range keys from in-memory index tree' (duration: 175.143912ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:28:08.236329Z","caller":"traceutil/trace.go:171","msg":"trace[1972888535] transaction","detail":"{read_only:false; response_revision:17629; number_of_response:1; }","duration":"338.228577ms","start":"2026-05-10T01:28:07.898072Z","end":"2026-05-10T01:28:08.236301Z","steps":["trace[1972888535] 'process raft request' (duration: 323.615191ms)","trace[1972888535] 'compare' (duration: 13.907527ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:28:08.236342Z","caller":"traceutil/trace.go:171","msg":"trace[1034295388] transaction","detail":"{read_only:false; response_revision:17630; number_of_response:1; }","duration":"338.214506ms","start":"2026-05-10T01:28:07.898097Z","end":"2026-05-10T01:28:08.236311Z","steps":["trace[1034295388] 'process raft request' (duration: 338.099913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:28:08.236469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.723637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:28:08.236488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:28:07.898028Z","time spent":"338.380851ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:28:08.236518Z","caller":"traceutil/trace.go:171","msg":"trace[942870101] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:17630; }","duration":"192.84549ms","start":"2026-05-10T01:28:08.043656Z","end":"2026-05-10T01:28:08.236501Z","steps":["trace[942870101] 'agreement among raft nodes before linearized reading' (duration: 192.751337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:28:08.236516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:28:07.898072Z","time spent":"338.3554ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:28:08.236347Z","caller":"traceutil/trace.go:171","msg":"trace[1076187799] linearizableReadLoop","detail":"{readStateIndex:18230; appliedIndex:18228; }","duration":"192.545593ms","start":"2026-05-10T01:28:08.043758Z","end":"2026-05-10T01:28:08.236304Z","steps":["trace[1076187799] 'read index received' (duration: 177.868576ms)","trace[1076187799] 'applied index is now lower than readState.Index' (duration: 14.676077ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:31:32.471602Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-10T01:31:32.478907Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-10T01:31:32.47903Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-10T01:33:01.832749Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17597} {"level":"info","ts":"2026-05-10T01:33:01.935882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17597,"took":"98.940406ms","hash":2011949873,"current-db-size-bytes":57057280,"current-db-size":"57 MB","current-db-size-in-use-bytes":34189312,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-10T01:33:01.935945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2011949873,"revision":17597,"compact-revision":15325} {"level":"info","ts":"2026-05-10T01:33:57.314621Z","caller":"traceutil/trace.go:171","msg":"trace[1079245953] transaction","detail":"{read_only:false; response_revision:20321; number_of_response:1; }","duration":"141.585714ms","start":"2026-05-10T01:33:57.173001Z","end":"2026-05-10T01:33:57.314587Z","steps":["trace[1079245953] 'process raft request' (duration: 141.409309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:33:58.261996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.690042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-10T01:33:58.262127Z","caller":"traceutil/trace.go:171","msg":"trace[1911851326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20323; }","duration":"157.849206ms","start":"2026-05-10T01:33:58.104249Z","end":"2026-05-10T01:33:58.262099Z","steps":["trace[1911851326] 'range keys from in-memory index tree' (duration: 157.385343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:33:58.262029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.058008ms","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-05-10T01:33:58.262306Z","caller":"traceutil/trace.go:171","msg":"trace[1797128839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20323; }","duration":"159.333046ms","start":"2026-05-10T01:33:58.102906Z","end":"2026-05-10T01:33:58.262239Z","steps":["trace[1797128839] 'range keys from in-memory index tree' (duration: 158.912135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:33:58.528134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.800684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:33:58.528268Z","caller":"traceutil/trace.go:171","msg":"trace[1438803909] transaction","detail":"{read_only:false; response_revision:20325; number_of_response:1; }","duration":"261.596482ms","start":"2026-05-10T01:33:58.266649Z","end":"2026-05-10T01:33:58.528246Z","steps":["trace[1438803909] 'process raft request' (duration: 120.590674ms)","trace[1438803909] 'compare' (duration: 140.638989ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:33:58.545878Z","caller":"traceutil/trace.go:171","msg":"trace[238085742] linearizableReadLoop","detail":"{readStateIndex:21016; appliedIndex:21014; }","duration":"265.627351ms","start":"2026-05-10T01:33:58.280235Z","end":"2026-05-10T01:33:58.545863Z","steps":["trace[238085742] 'read index received' (duration: 107.035236ms)","trace[238085742] 'applied index is now lower than readState.Index' (duration: 158.591135ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:33:58.545903Z","caller":"traceutil/trace.go:171","msg":"trace[485248478] transaction","detail":"{read_only:false; response_revision:20326; number_of_response:1; }","duration":"278.48249ms","start":"2026-05-10T01:33:58.267403Z","end":"2026-05-10T01:33:58.545886Z","steps":["trace[485248478] 'process raft request' (duration: 278.313506ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:33:58.546038Z","caller":"traceutil/trace.go:171","msg":"trace[179807458] transaction","detail":"{read_only:false; response_revision:20327; number_of_response:1; }","duration":"224.344441ms","start":"2026-05-10T01:33:58.321639Z","end":"2026-05-10T01:33:58.545984Z","steps":["trace[179807458] 'process raft request' (duration: 224.170316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:33:58.546073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.827127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:33:58.546179Z","caller":"traceutil/trace.go:171","msg":"trace[1230181115] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:20327; }","duration":"265.961601ms","start":"2026-05-10T01:33:58.280202Z","end":"2026-05-10T01:33:58.546163Z","steps":["trace[1230181115] 'agreement among raft nodes before linearized reading' (duration: 265.781216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:33:58.546154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.135629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:33:58.546368Z","caller":"traceutil/trace.go:171","msg":"trace[684794262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20327; }","duration":"198.407917ms","start":"2026-05-10T01:33:58.34793Z","end":"2026-05-10T01:33:58.546337Z","steps":["trace[684794262] 'agreement among raft nodes before linearized reading' (duration: 198.116769ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:38:01.841236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20036} {"level":"info","ts":"2026-05-10T01:38:01.952516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20036,"took":"106.853493ms","hash":867435129,"current-db-size-bytes":57057280,"current-db-size":"57 MB","current-db-size-in-use-bytes":40591360,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-10T01:38:01.952611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":867435129,"revision":20036,"compact-revision":17597} {"level":"info","ts":"2026-05-10T01:41:16.533036Z","caller":"traceutil/trace.go:171","msg":"trace[1474015148] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"120.294867ms","start":"2026-05-10T01:41:16.412671Z","end":"2026-05-10T01:41:16.532966Z","steps":["trace[1474015148] 'process raft request' (duration: 119.457854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:22.207318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.395448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:22.207418Z","caller":"traceutil/trace.go:171","msg":"trace[1813170735] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:24897; }","duration":"172.513721ms","start":"2026-05-10T01:41:22.034882Z","end":"2026-05-10T01:41:22.207396Z","steps":["trace[1813170735] 'count revisions from in-memory index tree' (duration: 172.283984ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:22.26288Z","caller":"traceutil/trace.go:171","msg":"trace[765445346] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"101.380036ms","start":"2026-05-10T01:41:22.161465Z","end":"2026-05-10T01:41:22.262845Z","steps":["trace[765445346] 'process raft request' (duration: 101.030786ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:22.38656Z","caller":"traceutil/trace.go:171","msg":"trace[477292496] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"201.065285ms","start":"2026-05-10T01:41:22.185465Z","end":"2026-05-10T01:41:22.38653Z","steps":["trace[477292496] 'process raft request' (duration: 200.721626ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:22.38678Z","caller":"traceutil/trace.go:171","msg":"trace[2025323603] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"200.948711ms","start":"2026-05-10T01:41:22.185805Z","end":"2026-05-10T01:41:22.386754Z","steps":["trace[2025323603] 'process raft request' (duration: 200.630882ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:26.73695Z","caller":"traceutil/trace.go:171","msg":"trace[1958049377] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"103.657977ms","start":"2026-05-10T01:41:26.633246Z","end":"2026-05-10T01:41:26.736904Z","steps":["trace[1958049377] 'process raft request' (duration: 92.770792ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:26.854544Z","caller":"traceutil/trace.go:171","msg":"trace[305411081] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"113.138143ms","start":"2026-05-10T01:41:26.741357Z","end":"2026-05-10T01:41:26.854495Z","steps":["trace[305411081] 'process raft request' (duration: 100.977824ms)","trace[305411081] 'compare' (duration: 11.599964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:27.151166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.481568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-10T01:41:27.151832Z","caller":"traceutil/trace.go:171","msg":"trace[346417428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24934; }","duration":"167.138125ms","start":"2026-05-10T01:41:26.984665Z","end":"2026-05-10T01:41:27.151803Z","steps":["trace[346417428] 'range keys from in-memory index tree' (duration: 166.391745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:27.150865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.266412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-10T01:41:27.152589Z","caller":"traceutil/trace.go:171","msg":"trace[332333080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24934; }","duration":"168.053581ms","start":"2026-05-10T01:41:26.984516Z","end":"2026-05-10T01:41:27.15257Z","steps":["trace[332333080] 'range keys from in-memory index tree' (duration: 166.047077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:27.831805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.079075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:27.832102Z","caller":"traceutil/trace.go:171","msg":"trace[824838167] linearizableReadLoop","detail":"{readStateIndex:25761; appliedIndex:25759; }","duration":"230.45316ms","start":"2026-05-10T01:41:27.601629Z","end":"2026-05-10T01:41:27.832083Z","steps":["trace[824838167] 'read index received' (duration: 111.105338ms)","trace[824838167] 'applied index is now lower than readState.Index' (duration: 119.346172ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:27.832174Z","caller":"traceutil/trace.go:171","msg":"trace[1930430025] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"252.673762ms","start":"2026-05-10T01:41:27.579449Z","end":"2026-05-10T01:41:27.832123Z","steps":["trace[1930430025] 'process raft request' (duration: 252.515658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:27.832284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.641565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:41:27.832331Z","caller":"traceutil/trace.go:171","msg":"trace[430240660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24940; }","duration":"230.719918ms","start":"2026-05-10T01:41:27.601596Z","end":"2026-05-10T01:41:27.832316Z","steps":["trace[430240660] 'agreement among raft nodes before linearized reading' (duration: 230.600474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:27.832413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.186566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:27.832391Z","caller":"traceutil/trace.go:171","msg":"trace[1601417955] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"347.111549ms","start":"2026-05-10T01:41:27.485237Z","end":"2026-05-10T01:41:27.832349Z","steps":["trace[1601417955] 'process raft request' (duration: 227.370287ms)","trace[1601417955] 'compare' (duration: 118.820728ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:27.832454Z","caller":"traceutil/trace.go:171","msg":"trace[241313602] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24940; }","duration":"222.273669ms","start":"2026-05-10T01:41:27.61017Z","end":"2026-05-10T01:41:27.832444Z","steps":["trace[241313602] 'agreement among raft nodes before linearized reading' (duration: 222.176186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:27.832787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:27.485222Z","time spent":"347.248924ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:28.552345Z","caller":"traceutil/trace.go:171","msg":"trace[1316996348] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"270.230367ms","start":"2026-05-10T01:41:28.282083Z","end":"2026-05-10T01:41:28.552313Z","steps":["trace[1316996348] 'process raft request' (duration: 270.011451ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:28.719547Z","caller":"traceutil/trace.go:171","msg":"trace[237522642] linearizableReadLoop","detail":"{readStateIndex:25764; appliedIndex:25762; }","duration":"372.379164ms","start":"2026-05-10T01:41:28.34714Z","end":"2026-05-10T01:41:28.719519Z","steps":["trace[237522642] 'read index received' (duration: 203.933263ms)","trace[237522642] 'applied index is now lower than readState.Index' (duration: 168.444691ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:28.719548Z","caller":"traceutil/trace.go:171","msg":"trace[1434447460] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"393.976219ms","start":"2026-05-10T01:41:28.325517Z","end":"2026-05-10T01:41:28.719493Z","steps":["trace[1434447460] 'process raft request' (duration: 359.488935ms)","trace[1434447460] 'compare' (duration: 33.95808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:28.719744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:28.325484Z","time spent":"394.160464ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:28.719805Z","caller":"traceutil/trace.go:171","msg":"trace[505993723] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"311.973968ms","start":"2026-05-10T01:41:28.407822Z","end":"2026-05-10T01:41:28.719796Z","steps":["trace[505993723] 'process raft request' (duration: 311.551337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:28.719962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:28.407806Z","time spent":"312.02541ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:28.72007Z","caller":"traceutil/trace.go:171","msg":"trace[683844686] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"309.46238ms","start":"2026-05-10T01:41:28.4106Z","end":"2026-05-10T01:41:28.720062Z","steps":["trace[683844686] 'process raft request' (duration: 308.875734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:28.72012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:28.410575Z","time spent":"309.515082ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:28.720201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.064172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:28.720223Z","caller":"traceutil/trace.go:171","msg":"trace[1117618096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24945; }","duration":"373.130324ms","start":"2026-05-10T01:41:28.347086Z","end":"2026-05-10T01:41:28.720216Z","steps":["trace[1117618096] 'agreement among raft nodes before linearized reading' (duration: 373.084433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:28.720241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:28.347061Z","time spent":"373.175686ms","remote":"127.0.0.1:39700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T01:41:28.720406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.431723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:41:28.720502Z","caller":"traceutil/trace.go:171","msg":"trace[590333922] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:24945; }","duration":"349.583287ms","start":"2026-05-10T01:41:28.370907Z","end":"2026-05-10T01:41:28.720491Z","steps":["trace[590333922] 'agreement among raft nodes before linearized reading' (duration: 349.468204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:28.720525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:28.370879Z","time spent":"349.638318ms","remote":"127.0.0.1:39988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":32,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:28.720664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.212199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:28.720685Z","caller":"traceutil/trace.go:171","msg":"trace[502077604] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24945; }","duration":"153.27606ms","start":"2026-05-10T01:41:28.567401Z","end":"2026-05-10T01:41:28.720677Z","steps":["trace[502077604] 'agreement among raft nodes before linearized reading' (duration: 153.237569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:28.720744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.378746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:28.72079Z","caller":"traceutil/trace.go:171","msg":"trace[1255534485] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24945; }","duration":"327.476648ms","start":"2026-05-10T01:41:28.3933Z","end":"2026-05-10T01:41:28.720776Z","steps":["trace[1255534485] 'agreement among raft nodes before linearized reading' (duration: 327.393086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:28.720825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:28.393272Z","time spent":"327.54447ms","remote":"127.0.0.1:45746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-10T01:41:29.213778Z","caller":"traceutil/trace.go:171","msg":"trace[466336995] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"267.517514ms","start":"2026-05-10T01:41:28.946227Z","end":"2026-05-10T01:41:29.213744Z","steps":["trace[466336995] 'process raft request' (duration: 267.271467ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:29.229099Z","caller":"traceutil/trace.go:171","msg":"trace[1564453864] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"271.661556ms","start":"2026-05-10T01:41:28.957414Z","end":"2026-05-10T01:41:29.229076Z","steps":["trace[1564453864] 'process raft request' (duration: 271.561363ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:29.229109Z","caller":"traceutil/trace.go:171","msg":"trace[2017865785] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"275.254454ms","start":"2026-05-10T01:41:28.953811Z","end":"2026-05-10T01:41:29.229066Z","steps":["trace[2017865785] 'process raft request' (duration: 275.007617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:29.407359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.840766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:29.407741Z","caller":"traceutil/trace.go:171","msg":"trace[1871175849] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"174.080215ms","start":"2026-05-10T01:41:29.233619Z","end":"2026-05-10T01:41:29.407699Z","steps":["trace[1871175849] 'process raft request' (duration: 19.799716ms)","trace[1871175849] 'compare' (duration: 153.688542ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:29.407773Z","caller":"traceutil/trace.go:171","msg":"trace[415857180] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"174.116856ms","start":"2026-05-10T01:41:29.233619Z","end":"2026-05-10T01:41:29.407736Z","steps":["trace[415857180] 'process raft request' (duration: 173.868888ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:29.673722Z","caller":"traceutil/trace.go:171","msg":"trace[708272591] linearizableReadLoop","detail":"{readStateIndex:25773; appliedIndex:25772; }","duration":"177.736523ms","start":"2026-05-10T01:41:29.495955Z","end":"2026-05-10T01:41:29.673691Z","steps":["trace[708272591] 'read index received' (duration: 151.76188ms)","trace[708272591] 'applied index is now lower than readState.Index' (duration: 25.973073ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:29.673797Z","caller":"traceutil/trace.go:171","msg":"trace[1570910585] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"185.963015ms","start":"2026-05-10T01:41:29.487783Z","end":"2026-05-10T01:41:29.673746Z","steps":["trace[1570910585] 'process raft request' (duration: 159.90946ms)","trace[1570910585] 'compare' (duration: 25.683225ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:29.67408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.292894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-10T01:41:29.674106Z","caller":"traceutil/trace.go:171","msg":"trace[1175415796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24952; }","duration":"145.398668ms","start":"2026-05-10T01:41:29.528698Z","end":"2026-05-10T01:41:29.674096Z","steps":["trace[1175415796] 'agreement among raft nodes before linearized reading' (duration: 145.214423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:29.674137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.150725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"info","ts":"2026-05-10T01:41:29.674229Z","caller":"traceutil/trace.go:171","msg":"trace[1523813487] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24952; }","duration":"178.356941ms","start":"2026-05-10T01:41:29.495848Z","end":"2026-05-10T01:41:29.674205Z","steps":["trace[1523813487] 'agreement among raft nodes before linearized reading' (duration: 177.96295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:29.973728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.689395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:29.973853Z","caller":"traceutil/trace.go:171","msg":"trace[574733656] linearizableReadLoop","detail":"{readStateIndex:25774; appliedIndex:25773; }","duration":"232.932048ms","start":"2026-05-10T01:41:29.7409Z","end":"2026-05-10T01:41:29.973832Z","steps":["trace[574733656] 'read index received' (duration: 127.999716ms)","trace[574733656] 'applied index is now lower than readState.Index' (duration: 104.931042ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:29.973858Z","caller":"traceutil/trace.go:171","msg":"trace[79106595] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"294.447844ms","start":"2026-05-10T01:41:29.679387Z","end":"2026-05-10T01:41:29.973835Z","steps":["trace[79106595] 'process raft request' (duration: 189.492271ms)","trace[79106595] 'compare' (duration: 104.459568ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:29.974355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.451403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-10T01:41:29.974405Z","caller":"traceutil/trace.go:171","msg":"trace[385211599] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24953; }","duration":"233.552635ms","start":"2026-05-10T01:41:29.74084Z","end":"2026-05-10T01:41:29.974392Z","steps":["trace[385211599] 'agreement among raft nodes before linearized reading' (duration: 233.072733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:29.976957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.365734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:29.97706Z","caller":"traceutil/trace.go:171","msg":"trace[1060001754] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:24954; }","duration":"160.542898ms","start":"2026-05-10T01:41:29.816493Z","end":"2026-05-10T01:41:29.977036Z","steps":["trace[1060001754] 'agreement among raft nodes before linearized reading' (duration: 160.345393ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:29.977478Z","caller":"traceutil/trace.go:171","msg":"trace[919425527] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"139.184519ms","start":"2026-05-10T01:41:29.83828Z","end":"2026-05-10T01:41:29.977465Z","steps":["trace[919425527] 'process raft request' (duration: 138.387488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:29.977772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.379121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-10T01:41:29.977813Z","caller":"traceutil/trace.go:171","msg":"trace[1360373813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24954; }","duration":"140.474244ms","start":"2026-05-10T01:41:29.83733Z","end":"2026-05-10T01:41:29.977804Z","steps":["trace[1360373813] 'agreement among raft nodes before linearized reading' (duration: 140.33237ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.15734Z","caller":"traceutil/trace.go:171","msg":"trace[1081431811] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"175.491522ms","start":"2026-05-10T01:41:29.981805Z","end":"2026-05-10T01:41:30.157296Z","steps":["trace[1081431811] 'process raft request' (duration: 94.296994ms)","trace[1081431811] 'compare' (duration: 80.86946ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:30.246806Z","caller":"traceutil/trace.go:171","msg":"trace[1209158909] linearizableReadLoop","detail":"{readStateIndex:25777; appliedIndex:25775; }","duration":"227.269044ms","start":"2026-05-10T01:41:30.01951Z","end":"2026-05-10T01:41:30.246779Z","steps":["trace[1209158909] 'read index received' (duration: 56.682535ms)","trace[1209158909] 'applied index is now lower than readState.Index' (duration: 170.585059ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:30.246852Z","caller":"traceutil/trace.go:171","msg":"trace[1505500561] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"259.390494ms","start":"2026-05-10T01:41:29.987386Z","end":"2026-05-10T01:41:30.246777Z","steps":["trace[1505500561] 'process raft request' (duration: 258.784468ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.24691Z","caller":"traceutil/trace.go:171","msg":"trace[1229324833] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"188.208797ms","start":"2026-05-10T01:41:30.058686Z","end":"2026-05-10T01:41:30.246894Z","steps":["trace[1229324833] 'process raft request' (duration: 187.775765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:30.247043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.516241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:41:30.247087Z","caller":"traceutil/trace.go:171","msg":"trace[1710856248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24957; }","duration":"227.630384ms","start":"2026-05-10T01:41:30.019446Z","end":"2026-05-10T01:41:30.247076Z","steps":["trace[1710856248] 'agreement among raft nodes before linearized reading' (duration: 227.440649ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.422438Z","caller":"traceutil/trace.go:171","msg":"trace[91900732] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"170.857687ms","start":"2026-05-10T01:41:30.251561Z","end":"2026-05-10T01:41:30.422419Z","steps":["trace[91900732] 'process raft request' (duration: 170.674122ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.450169Z","caller":"traceutil/trace.go:171","msg":"trace[913589890] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"112.847917ms","start":"2026-05-10T01:41:30.3373Z","end":"2026-05-10T01:41:30.450147Z","steps":["trace[913589890] 'process raft request' (duration: 112.627241ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.467881Z","caller":"traceutil/trace.go:171","msg":"trace[789707730] linearizableReadLoop","detail":"{readStateIndex:25780; appliedIndex:25778; }","duration":"121.030868ms","start":"2026-05-10T01:41:30.346828Z","end":"2026-05-10T01:41:30.467859Z","steps":["trace[789707730] 'read index received' (duration: 75.513605ms)","trace[789707730] 'applied index is now lower than readState.Index' (duration: 45.515863ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:30.468137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.225713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:30.468238Z","caller":"traceutil/trace.go:171","msg":"trace[264135348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24959; }","duration":"121.440218ms","start":"2026-05-10T01:41:30.346783Z","end":"2026-05-10T01:41:30.468223Z","steps":["trace[264135348] 'agreement among raft nodes before linearized reading' (duration: 121.217942ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.678905Z","caller":"traceutil/trace.go:171","msg":"trace[1009206839] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"116.246187ms","start":"2026-05-10T01:41:30.562628Z","end":"2026-05-10T01:41:30.678874Z","steps":["trace[1009206839] 'process raft request' (duration: 115.763765ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.865655Z","caller":"traceutil/trace.go:171","msg":"trace[1593912892] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"138.622014ms","start":"2026-05-10T01:41:30.727005Z","end":"2026-05-10T01:41:30.865627Z","steps":["trace[1593912892] 'process raft request' (duration: 138.413268ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.865851Z","caller":"traceutil/trace.go:171","msg":"trace[1361680760] linearizableReadLoop","detail":"{readStateIndex:25784; appliedIndex:25782; }","duration":"116.569797ms","start":"2026-05-10T01:41:30.749244Z","end":"2026-05-10T01:41:30.865814Z","steps":["trace[1361680760] 'read index received' (duration: 66.413229ms)","trace[1361680760] 'applied index is now lower than readState.Index' (duration: 50.153818ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:30.866136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.870665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-05-10T01:41:30.86621Z","caller":"traceutil/trace.go:171","msg":"trace[1094966807] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24963; }","duration":"117.019609ms","start":"2026-05-10T01:41:30.749172Z","end":"2026-05-10T01:41:30.866192Z","steps":["trace[1094966807] 'agreement among raft nodes before linearized reading' (duration: 116.732781ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:31.449688Z","caller":"traceutil/trace.go:171","msg":"trace[517756229] linearizableReadLoop","detail":"{readStateIndex:25787; appliedIndex:25786; }","duration":"102.100724ms","start":"2026-05-10T01:41:31.347565Z","end":"2026-05-10T01:41:31.449666Z","steps":["trace[517756229] 'read index received' (duration: 97.932592ms)","trace[517756229] 'applied index is now lower than readState.Index' (duration: 4.166542ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:31.449828Z","caller":"traceutil/trace.go:171","msg":"trace[909784849] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"136.133346ms","start":"2026-05-10T01:41:31.313641Z","end":"2026-05-10T01:41:31.449774Z","steps":["trace[909784849] 'process raft request' (duration: 131.951724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:31.449912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.342302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:31.450014Z","caller":"traceutil/trace.go:171","msg":"trace[1619037999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24965; }","duration":"102.502776ms","start":"2026-05-10T01:41:31.34748Z","end":"2026-05-10T01:41:31.449983Z","steps":["trace[1619037999] 'agreement among raft nodes before linearized reading' (duration: 102.32173ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:31.801035Z","caller":"traceutil/trace.go:171","msg":"trace[1490820654] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"119.783784ms","start":"2026-05-10T01:41:31.68123Z","end":"2026-05-10T01:41:31.801014Z","steps":["trace[1490820654] 'process raft request' (duration: 119.643751ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:32.199372Z","caller":"traceutil/trace.go:171","msg":"trace[2109957253] linearizableReadLoop","detail":"{readStateIndex:25791; appliedIndex:25790; }","duration":"157.176586ms","start":"2026-05-10T01:41:32.042137Z","end":"2026-05-10T01:41:32.199313Z","steps":["trace[2109957253] 'read index received' (duration: 156.487158ms)","trace[2109957253] 'applied index is now lower than readState.Index' (duration: 687.978µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:32.199657Z","caller":"traceutil/trace.go:171","msg":"trace[1891616249] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"216.699227ms","start":"2026-05-10T01:41:31.982915Z","end":"2026-05-10T01:41:32.199614Z","steps":["trace[1891616249] 'process raft request' (duration: 215.792153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:32.199793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.598827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-10T01:41:32.199872Z","caller":"traceutil/trace.go:171","msg":"trace[1679219926] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24969; }","duration":"157.774582ms","start":"2026-05-10T01:41:32.042081Z","end":"2026-05-10T01:41:32.199856Z","steps":["trace[1679219926] 'agreement among raft nodes before linearized reading' (duration: 157.399922ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:32.213608Z","caller":"traceutil/trace.go:171","msg":"trace[2051770789] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"135.988002ms","start":"2026-05-10T01:41:32.077594Z","end":"2026-05-10T01:41:32.213582Z","steps":["trace[2051770789] 'process raft request' (duration: 135.804667ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:32.384131Z","caller":"traceutil/trace.go:171","msg":"trace[963988067] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"128.117759ms","start":"2026-05-10T01:41:32.255973Z","end":"2026-05-10T01:41:32.384091Z","steps":["trace[963988067] 'process raft request' (duration: 123.804532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:32.682577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.542116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:32.682902Z","caller":"traceutil/trace.go:171","msg":"trace[1806148527] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"250.385761ms","start":"2026-05-10T01:41:32.432337Z","end":"2026-05-10T01:41:32.682723Z","steps":["trace[1806148527] 'process raft request' (duration: 106.548026ms)","trace[1806148527] 'compare' (duration: 143.147236ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:33.016233Z","caller":"traceutil/trace.go:171","msg":"trace[358665805] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"191.557448ms","start":"2026-05-10T01:41:32.824645Z","end":"2026-05-10T01:41:33.016203Z","steps":["trace[358665805] 'process raft request' (duration: 191.353483ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:33.095243Z","caller":"traceutil/trace.go:171","msg":"trace[1271095520] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"222.709312ms","start":"2026-05-10T01:41:32.872497Z","end":"2026-05-10T01:41:33.095207Z","steps":["trace[1271095520] 'process raft request' (duration: 222.415204ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:33.095244Z","caller":"traceutil/trace.go:171","msg":"trace[813328159] linearizableReadLoop","detail":"{readStateIndex:25798; appliedIndex:25795; }","duration":"179.362107ms","start":"2026-05-10T01:41:32.915827Z","end":"2026-05-10T01:41:33.09519Z","steps":["trace[813328159] 'read index received' (duration: 100.137872ms)","trace[813328159] 'applied index is now lower than readState.Index' (duration: 79.222935ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:33.095329Z","caller":"traceutil/trace.go:171","msg":"trace[947130232] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"222.677461ms","start":"2026-05-10T01:41:32.87261Z","end":"2026-05-10T01:41:33.095288Z","steps":["trace[947130232] 'process raft request' (duration: 222.511166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:33.095466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.610173ms","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:6"} {"level":"info","ts":"2026-05-10T01:41:33.095523Z","caller":"traceutil/trace.go:171","msg":"trace[107717832] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:24976; }","duration":"179.733377ms","start":"2026-05-10T01:41:32.915773Z","end":"2026-05-10T01:41:33.095507Z","steps":["trace[107717832] 'agreement among raft nodes before linearized reading' (duration: 179.601243ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:40.871079Z","caller":"traceutil/trace.go:171","msg":"trace[521673793] linearizableReadLoop","detail":"{readStateIndex:25851; appliedIndex:25850; }","duration":"516.108227ms","start":"2026-05-10T01:41:40.35493Z","end":"2026-05-10T01:41:40.871038Z","steps":["trace[521673793] 'read index received' (duration: 434.424695ms)","trace[521673793] 'applied index is now lower than readState.Index' (duration: 81.681922ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:40.871131Z","caller":"traceutil/trace.go:171","msg":"trace[1267511005] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"567.976331ms","start":"2026-05-10T01:41:40.303105Z","end":"2026-05-10T01:41:40.871081Z","steps":["trace[1267511005] 'process raft request' (duration: 486.228386ms)","trace[1267511005] 'compare' (duration: 81.413885ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:40.871298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.350744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:40.871346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:40.303064Z","time spent":"568.156196ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:40.871424Z","caller":"traceutil/trace.go:171","msg":"trace[1669023840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25028; }","duration":"516.531588ms","start":"2026-05-10T01:41:40.354858Z","end":"2026-05-10T01:41:40.87139Z","steps":["trace[1669023840] 'agreement among raft nodes before linearized reading' (duration: 516.329323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:40.871474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:40.354828Z","time spent":"516.636491ms","remote":"127.0.0.1:39700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T01:41:40.946882Z","caller":"traceutil/trace.go:171","msg":"trace[1986854450] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"225.23137ms","start":"2026-05-10T01:41:40.721598Z","end":"2026-05-10T01:41:40.946829Z","steps":["trace[1986854450] 'process raft request' (duration: 225.031684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:40.946942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.33473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-05-10T01:41:40.946979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.67302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:40.947014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.555704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-10T01:41:40.947105Z","caller":"traceutil/trace.go:171","msg":"trace[1456127092] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25029; }","duration":"202.509925ms","start":"2026-05-10T01:41:40.744505Z","end":"2026-05-10T01:41:40.947015Z","steps":["trace[1456127092] 'agreement among raft nodes before linearized reading' (duration: 202.34045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:40.947086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.445953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:40.947192Z","caller":"traceutil/trace.go:171","msg":"trace[479748483] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25029; }","duration":"462.608167ms","start":"2026-05-10T01:41:40.484556Z","end":"2026-05-10T01:41:40.947164Z","steps":["trace[479748483] 'agreement among raft nodes before linearized reading' (duration: 462.427312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:40.947259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:40.484539Z","time spent":"462.70347ms","remote":"127.0.0.1:45134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:40.94724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.15672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-10T01:41:40.947272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.811997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-10T01:41:40.94736Z","caller":"traceutil/trace.go:171","msg":"trace[291337828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25029; }","duration":"221.334914ms","start":"2026-05-10T01:41:40.725996Z","end":"2026-05-10T01:41:40.947331Z","steps":["trace[291337828] 'agreement among raft nodes before linearized reading' (duration: 220.963134ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:40.947368Z","caller":"traceutil/trace.go:171","msg":"trace[451913445] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25029; }","duration":"543.966391ms","start":"2026-05-10T01:41:40.40338Z","end":"2026-05-10T01:41:40.947346Z","steps":["trace[451913445] 'agreement among raft nodes before linearized reading' (duration: 543.680803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:40.94742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:40.403353Z","time spent":"544.057533ms","remote":"127.0.0.1:39816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-05-10T01:41:40.947504Z","caller":"traceutil/trace.go:171","msg":"trace[1846091411] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25029; }","duration":"349.834574ms","start":"2026-05-10T01:41:40.597212Z","end":"2026-05-10T01:41:40.947047Z","steps":["trace[1846091411] 'agreement among raft nodes before linearized reading' (duration: 349.655269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:40.947579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:40.597184Z","time spent":"350.380858ms","remote":"127.0.0.1:45242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"info","ts":"2026-05-10T01:41:40.947591Z","caller":"traceutil/trace.go:171","msg":"trace[1074348315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25029; }","duration":"514.664538ms","start":"2026-05-10T01:41:40.432394Z","end":"2026-05-10T01:41:40.947058Z","steps":["trace[1074348315] 'agreement among raft nodes before linearized reading' (duration: 514.476453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:40.947722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:40.43236Z","time spent":"515.325036ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T01:41:40.948041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.649085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:40.948103Z","caller":"traceutil/trace.go:171","msg":"trace[239517218] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25029; }","duration":"169.776578ms","start":"2026-05-10T01:41:40.778311Z","end":"2026-05-10T01:41:40.948088Z","steps":["trace[239517218] 'agreement among raft nodes before linearized reading' (duration: 168.485394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:40.948178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.739358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:41:40.948241Z","caller":"traceutil/trace.go:171","msg":"trace[138186367] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25029; }","duration":"124.886671ms","start":"2026-05-10T01:41:40.823337Z","end":"2026-05-10T01:41:40.948224Z","steps":["trace[138186367] 'agreement among raft nodes before linearized reading' (duration: 124.260694ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:41.175128Z","caller":"traceutil/trace.go:171","msg":"trace[85988883] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"222.456694ms","start":"2026-05-10T01:41:40.952641Z","end":"2026-05-10T01:41:41.175097Z","steps":["trace[85988883] 'process raft request' (duration: 221.132928ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:41.197436Z","caller":"traceutil/trace.go:171","msg":"trace[869727136] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"242.901288ms","start":"2026-05-10T01:41:40.954507Z","end":"2026-05-10T01:41:41.197408Z","steps":["trace[869727136] 'process raft request' (duration: 242.58869ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:41.197436Z","caller":"traceutil/trace.go:171","msg":"trace[2123744618] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"155.329896ms","start":"2026-05-10T01:41:41.042072Z","end":"2026-05-10T01:41:41.197402Z","steps":["trace[2123744618] 'process raft request' (duration: 155.203433ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:41.19788Z","caller":"traceutil/trace.go:171","msg":"trace[2009335682] linearizableReadLoop","detail":"{readStateIndex:25855; appliedIndex:25853; }","duration":"189.113641ms","start":"2026-05-10T01:41:41.008735Z","end":"2026-05-10T01:41:41.197849Z","steps":["trace[2009335682] 'read index received' (duration: 165.137362ms)","trace[2009335682] 'applied index is now lower than readState.Index' (duration: 23.975249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:41.19814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.362117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:41.198202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.075169ms","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:6"} {"level":"warn","ts":"2026-05-10T01:41:41.198236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.913426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:41.198292Z","caller":"traceutil/trace.go:171","msg":"trace[116142610] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25034; }","duration":"107.208623ms","start":"2026-05-10T01:41:41.091064Z","end":"2026-05-10T01:41:41.198273Z","steps":["trace[116142610] 'agreement among raft nodes before linearized reading' (duration: 107.050369ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:41.198326Z","caller":"traceutil/trace.go:171","msg":"trace[140812742] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25034; }","duration":"175.04686ms","start":"2026-05-10T01:41:41.023264Z","end":"2026-05-10T01:41:41.198311Z","steps":["trace[140812742] 'agreement among raft nodes before linearized reading' (duration: 174.879606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:41.198178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.950049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:41.198249Z","caller":"traceutil/trace.go:171","msg":"trace[1753733024] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25034; }","duration":"189.532662ms","start":"2026-05-10T01:41:41.008696Z","end":"2026-05-10T01:41:41.198229Z","steps":["trace[1753733024] 'agreement among raft nodes before linearized reading' (duration: 189.252474ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:41.19841Z","caller":"traceutil/trace.go:171","msg":"trace[24045540] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25034; }","duration":"178.223206ms","start":"2026-05-10T01:41:41.020159Z","end":"2026-05-10T01:41:41.198383Z","steps":["trace[24045540] 'agreement among raft nodes before linearized reading' (duration: 177.864416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:41.508354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.859295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:41.508436Z","caller":"traceutil/trace.go:171","msg":"trace[1421169202] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25034; }","duration":"165.023689ms","start":"2026-05-10T01:41:41.343392Z","end":"2026-05-10T01:41:41.508416Z","steps":["trace[1421169202] 'count revisions from in-memory index tree' (duration: 164.740452ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:41.508721Z","caller":"traceutil/trace.go:171","msg":"trace[2113054212] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"149.438347ms","start":"2026-05-10T01:41:41.359259Z","end":"2026-05-10T01:41:41.508697Z","steps":["trace[2113054212] 'process raft request' (duration: 135.857709ms)","trace[2113054212] 'compare' (duration: 13.455334ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:42.058403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.21088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:42.058524Z","caller":"traceutil/trace.go:171","msg":"trace[409231997] linearizableReadLoop","detail":"{readStateIndex:25860; appliedIndex:25859; }","duration":"517.783601ms","start":"2026-05-10T01:41:41.540722Z","end":"2026-05-10T01:41:42.058506Z","steps":["trace[409231997] 'read index received' (duration: 248.320314ms)","trace[409231997] 'applied index is now lower than readState.Index' (duration: 269.461817ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:42.058798Z","caller":"traceutil/trace.go:171","msg":"trace[1475110721] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"543.890809ms","start":"2026-05-10T01:41:41.514835Z","end":"2026-05-10T01:41:42.058725Z","steps":["trace[1475110721] 'process raft request' (duration: 274.243156ms)","trace[1475110721] 'compare' (duration: 269.062037ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:42.058841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.09255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:41:42.058933Z","caller":"traceutil/trace.go:171","msg":"trace[1705923380] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25036; }","duration":"518.266625ms","start":"2026-05-10T01:41:41.540645Z","end":"2026-05-10T01:41:42.058911Z","steps":["trace[1705923380] 'agreement among raft nodes before linearized reading' (duration: 518.0613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:42.058988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:41.540621Z","time spent":"518.355617ms","remote":"127.0.0.1:40018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":18,"response size":32,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:42.059038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:41.514816Z","time spent":"544.094365ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:42.067936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.188643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-10T01:41:42.068014Z","caller":"traceutil/trace.go:171","msg":"trace[1291290914] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25037; }","duration":"439.366288ms","start":"2026-05-10T01:41:41.628627Z","end":"2026-05-10T01:41:42.067993Z","steps":["trace[1291290914] 'agreement among raft nodes before linearized reading' (duration: 439.012438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:42.068066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:41.628593Z","time spent":"439.46156ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":239,"response size":33,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:42.068169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.591845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-05-10T01:41:42.068289Z","caller":"traceutil/trace.go:171","msg":"trace[895916526] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25037; }","duration":"336.79385ms","start":"2026-05-10T01:41:41.731468Z","end":"2026-05-10T01:41:42.068262Z","steps":["trace[895916526] 'agreement among raft nodes before linearized reading' (duration: 336.193584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:42.068349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:41.731413Z","time spent":"336.926744ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7557,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-05-10T01:41:42.068421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.296897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-10T01:41:42.068457Z","caller":"traceutil/trace.go:171","msg":"trace[793881754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25037; }","duration":"511.360248ms","start":"2026-05-10T01:41:41.557085Z","end":"2026-05-10T01:41:42.068446Z","steps":["trace[793881754] 'agreement among raft nodes before linearized reading' (duration: 511.238325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:42.068501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:41.55708Z","time spent":"511.399889ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T01:41:42.068611Z","caller":"traceutil/trace.go:171","msg":"trace[526578209] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"230.351058ms","start":"2026-05-10T01:41:41.83824Z","end":"2026-05-10T01:41:42.068591Z","steps":["trace[526578209] 'process raft request' (duration: 229.213267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:42.06822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.144642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-10T01:41:42.068677Z","caller":"traceutil/trace.go:171","msg":"trace[2106972868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25037; }","duration":"511.651405ms","start":"2026-05-10T01:41:41.557008Z","end":"2026-05-10T01:41:42.06866Z","steps":["trace[2106972868] 'agreement among raft nodes before linearized reading' (duration: 511.026329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:42.068716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:41.55698Z","time spent":"511.724798ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T01:41:42.068306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.645116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:42.068821Z","caller":"traceutil/trace.go:171","msg":"trace[1093016195] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25037; }","duration":"447.21445ms","start":"2026-05-10T01:41:41.621591Z","end":"2026-05-10T01:41:42.068805Z","steps":["trace[1093016195] 'agreement among raft nodes before linearized reading' (duration: 446.658706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:42.068858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:41.621559Z","time spent":"447.282802ms","remote":"127.0.0.1:41778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:42.068944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.094388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-10T01:41:42.068982Z","caller":"traceutil/trace.go:171","msg":"trace[356818552] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25037; }","duration":"173.1923ms","start":"2026-05-10T01:41:41.895779Z","end":"2026-05-10T01:41:42.068971Z","steps":["trace[356818552] 'agreement among raft nodes before linearized reading' (duration: 172.914802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:42.068602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.278272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:42.069067Z","caller":"traceutil/trace.go:171","msg":"trace[1775538555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25037; }","duration":"269.784186ms","start":"2026-05-10T01:41:41.799267Z","end":"2026-05-10T01:41:42.069051Z","steps":["trace[1775538555] 'agreement among raft nodes before linearized reading' (duration: 269.292123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:43.438501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.224183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a1720d30-6452-4ea9-9366-6a478e3fd582\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-10T01:41:43.438606Z","caller":"traceutil/trace.go:171","msg":"trace[195471278] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a1720d30-6452-4ea9-9366-6a478e3fd582; range_end:; response_count:1; response_revision:25106; }","duration":"104.407877ms","start":"2026-05-10T01:41:43.33417Z","end":"2026-05-10T01:41:43.438578Z","steps":["trace[195471278] 'range keys from in-memory index tree' (duration: 103.932624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:49.867006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.406354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-10T01:41:49.867121Z","caller":"traceutil/trace.go:171","msg":"trace[1573366106] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25180; }","duration":"129.571478ms","start":"2026-05-10T01:41:49.737522Z","end":"2026-05-10T01:41:49.867093Z","steps":["trace[1573366106] 'range keys from in-memory index tree' (duration: 129.201898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:49.867204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.950678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-05-10T01:41:49.868495Z","caller":"traceutil/trace.go:171","msg":"trace[1862034439] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25180; }","duration":"175.115842ms","start":"2026-05-10T01:41:49.692167Z","end":"2026-05-10T01:41:49.867282Z","steps":["trace[1862034439] 'range keys from in-memory index tree' (duration: 174.722722ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:50.247628Z","caller":"traceutil/trace.go:171","msg":"trace[56668400] linearizableReadLoop","detail":"{readStateIndex:26007; appliedIndex:26006; }","duration":"109.980199ms","start":"2026-05-10T01:41:50.137616Z","end":"2026-05-10T01:41:50.247596Z","steps":["trace[56668400] 'read index received' (duration: 109.907458ms)","trace[56668400] 'applied index is now lower than readState.Index' (duration: 71.661µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:50.24796Z","caller":"traceutil/trace.go:171","msg":"trace[498349141] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"363.767991ms","start":"2026-05-10T01:41:49.884114Z","end":"2026-05-10T01:41:50.247882Z","steps":["trace[498349141] 'process raft request' (duration: 363.175535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:50.248211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.570835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-10T01:41:50.248234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:49.884082Z","time spent":"364.029898ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10630,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:50.248273Z","caller":"traceutil/trace.go:171","msg":"trace[1778950976] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25181; }","duration":"110.704628ms","start":"2026-05-10T01:41:50.137548Z","end":"2026-05-10T01:41:50.248252Z","steps":["trace[1778950976] 'agreement among raft nodes before linearized reading' (duration: 110.333038ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:50.317594Z","caller":"traceutil/trace.go:171","msg":"trace[1476686751] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"125.085717ms","start":"2026-05-10T01:41:50.192475Z","end":"2026-05-10T01:41:50.317561Z","steps":["trace[1476686751] 'process raft request' (duration: 83.129211ms)","trace[1476686751] 'compare' (duration: 41.771791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:50.317841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.538698ms","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-05-10T01:41:50.317924Z","caller":"traceutil/trace.go:171","msg":"trace[254478570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25182; }","duration":"122.678732ms","start":"2026-05-10T01:41:50.195227Z","end":"2026-05-10T01:41:50.317906Z","steps":["trace[254478570] 'agreement among raft nodes before linearized reading' (duration: 122.336443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:50.317852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.56652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-10T01:41:50.318062Z","caller":"traceutil/trace.go:171","msg":"trace[1313767253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25182; }","duration":"122.829996ms","start":"2026-05-10T01:41:50.195206Z","end":"2026-05-10T01:41:50.318036Z","steps":["trace[1313767253] 'agreement among raft nodes before linearized reading' (duration: 122.334433ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:12.61998Z","caller":"traceutil/trace.go:171","msg":"trace[327929078] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"205.123745ms","start":"2026-05-10T01:42:12.414829Z","end":"2026-05-10T01:42:12.619953Z","steps":["trace[327929078] 'process raft request' (duration: 204.95301ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:12.636603Z","caller":"traceutil/trace.go:171","msg":"trace[2004231133] linearizableReadLoop","detail":"{readStateIndex:26196; appliedIndex:26194; }","duration":"197.719284ms","start":"2026-05-10T01:42:12.438852Z","end":"2026-05-10T01:42:12.636571Z","steps":["trace[2004231133] 'read index received' (duration: 180.993332ms)","trace[2004231133] 'applied index is now lower than readState.Index' (duration: 16.723952ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:12.63665Z","caller":"traceutil/trace.go:171","msg":"trace[92955348] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"210.313505ms","start":"2026-05-10T01:42:12.426301Z","end":"2026-05-10T01:42:12.636615Z","steps":["trace[92955348] 'process raft request' (duration: 209.959866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:12.636862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.980602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-10T01:42:12.636949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.316322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-10T01:42:12.636963Z","caller":"traceutil/trace.go:171","msg":"trace[760985742] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25365; }","duration":"198.129315ms","start":"2026-05-10T01:42:12.438806Z","end":"2026-05-10T01:42:12.636936Z","steps":["trace[760985742] 'agreement among raft nodes before linearized reading' (duration: 197.880628ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:12.636999Z","caller":"traceutil/trace.go:171","msg":"trace[714562881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25365; }","duration":"184.404143ms","start":"2026-05-10T01:42:12.452581Z","end":"2026-05-10T01:42:12.636985Z","steps":["trace[714562881] 'agreement among raft nodes before linearized reading' (duration: 184.212848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:12.637043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.993106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-10T01:42:12.637132Z","caller":"traceutil/trace.go:171","msg":"trace[131668481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25365; }","duration":"186.14532ms","start":"2026-05-10T01:42:12.450972Z","end":"2026-05-10T01:42:12.637118Z","steps":["trace[131668481] 'agreement among raft nodes before linearized reading' (duration: 185.804161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:12.846891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.214444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:12.847148Z","caller":"traceutil/trace.go:171","msg":"trace[20135319] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"204.088327ms","start":"2026-05-10T01:42:12.643024Z","end":"2026-05-10T01:42:12.847112Z","steps":["trace[20135319] 'process raft request' (duration: 65.472302ms)","trace[20135319] 'compare' (duration: 137.937587ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:12.847195Z","caller":"traceutil/trace.go:171","msg":"trace[1929044303] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"203.138572ms","start":"2026-05-10T01:42:12.64404Z","end":"2026-05-10T01:42:12.847178Z","steps":["trace[1929044303] 'process raft request' (duration: 202.988138ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:12.847247Z","caller":"traceutil/trace.go:171","msg":"trace[1073892902] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"200.943342ms","start":"2026-05-10T01:42:12.646284Z","end":"2026-05-10T01:42:12.847228Z","steps":["trace[1073892902] 'process raft request' (duration: 200.86545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:13.406097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.125232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:13.406419Z","caller":"traceutil/trace.go:171","msg":"trace[1205761728] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"328.26231ms","start":"2026-05-10T01:42:13.078138Z","end":"2026-05-10T01:42:13.406401Z","steps":["trace[1205761728] 'process raft request' (duration: 207.732706ms)","trace[1205761728] 'compare' (duration: 119.989638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:13.406475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:13.078107Z","time spent":"328.344612ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:42:13.406601Z","caller":"traceutil/trace.go:171","msg":"trace[1376968198] linearizableReadLoop","detail":"{readStateIndex:26201; appliedIndex:26200; }","duration":"248.752597ms","start":"2026-05-10T01:42:13.1578Z","end":"2026-05-10T01:42:13.406553Z","steps":["trace[1376968198] 'read index received' (duration: 128.044678ms)","trace[1376968198] 'applied index is now lower than readState.Index' (duration: 120.704809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:13.406803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.144204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:13.40685Z","caller":"traceutil/trace.go:171","msg":"trace[567960448] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25371; }","duration":"291.264397ms","start":"2026-05-10T01:42:13.115571Z","end":"2026-05-10T01:42:13.406835Z","steps":["trace[567960448] 'agreement among raft nodes before linearized reading' (duration: 291.152064ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:13.607996Z","caller":"traceutil/trace.go:171","msg":"trace[2059952951] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"177.20521ms","start":"2026-05-10T01:42:13.430749Z","end":"2026-05-10T01:42:13.607954Z","steps":["trace[2059952951] 'process raft request' (duration: 176.914051ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:13.624735Z","caller":"traceutil/trace.go:171","msg":"trace[1085543700] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"193.49245ms","start":"2026-05-10T01:42:13.431198Z","end":"2026-05-10T01:42:13.62469Z","steps":["trace[1085543700] 'process raft request' (duration: 193.195372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:14.295481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.663773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:14.295635Z","caller":"traceutil/trace.go:171","msg":"trace[965154483] linearizableReadLoop","detail":"{readStateIndex:26208; appliedIndex:26207; }","duration":"122.083856ms","start":"2026-05-10T01:42:14.173532Z","end":"2026-05-10T01:42:14.295616Z","steps":["trace[965154483] 'read index received' (duration: 3.105255ms)","trace[965154483] 'applied index is now lower than readState.Index' (duration: 118.976041ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:14.295666Z","caller":"traceutil/trace.go:171","msg":"trace[542402267] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"505.009086ms","start":"2026-05-10T01:42:13.790623Z","end":"2026-05-10T01:42:14.295632Z","steps":["trace[542402267] 'process raft request' (duration: 386.095136ms)","trace[542402267] 'compare' (duration: 117.910223ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:14.295854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.327573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-10T01:42:14.295863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:13.790584Z","time spent":"505.16983ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:42:14.295922Z","caller":"traceutil/trace.go:171","msg":"trace[998698196] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25377; }","duration":"122.405494ms","start":"2026-05-10T01:42:14.173483Z","end":"2026-05-10T01:42:14.295888Z","steps":["trace[998698196] 'agreement among raft nodes before linearized reading' (duration: 122.23113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:14.77804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.637367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:14.778177Z","caller":"traceutil/trace.go:171","msg":"trace[1053694890] linearizableReadLoop","detail":"{readStateIndex:26209; appliedIndex:26208; }","duration":"473.092311ms","start":"2026-05-10T01:42:14.305063Z","end":"2026-05-10T01:42:14.778155Z","steps":["trace[1053694890] 'read index received' (duration: 366.187557ms)","trace[1053694890] 'applied index is now lower than readState.Index' (duration: 106.902854ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:14.77836Z","caller":"traceutil/trace.go:171","msg":"trace[1739000027] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"474.605422ms","start":"2026-05-10T01:42:14.303565Z","end":"2026-05-10T01:42:14.778171Z","steps":["trace[1739000027] 'process raft request' (duration: 367.708017ms)","trace[1739000027] 'compare' (duration: 105.678882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:14.778459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.372429ms","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-05-10T01:42:14.77855Z","caller":"traceutil/trace.go:171","msg":"trace[1396736133] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25378; }","duration":"473.525153ms","start":"2026-05-10T01:42:14.305005Z","end":"2026-05-10T01:42:14.77853Z","steps":["trace[1396736133] 'agreement among raft nodes before linearized reading' (duration: 473.210225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:14.778593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:14.30499Z","time spent":"473.596835ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-10T01:42:14.778565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:14.303538Z","time spent":"474.91418ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:42:14.830844Z","caller":"traceutil/trace.go:171","msg":"trace[924696555] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"204.417315ms","start":"2026-05-10T01:42:14.626387Z","end":"2026-05-10T01:42:14.830805Z","steps":["trace[924696555] 'process raft request' (duration: 204.175689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:14.831182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.720303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:14.831253Z","caller":"traceutil/trace.go:171","msg":"trace[2020657862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25380; }","duration":"475.854877ms","start":"2026-05-10T01:42:14.355381Z","end":"2026-05-10T01:42:14.831236Z","steps":["trace[2020657862] 'agreement among raft nodes before linearized reading' (duration: 475.694253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:14.831296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:14.355361Z","time spent":"475.926408ms","remote":"127.0.0.1:39700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T01:42:14.831456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.928891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:42:14.831559Z","caller":"traceutil/trace.go:171","msg":"trace[112356987] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25380; }","duration":"478.128257ms","start":"2026-05-10T01:42:14.353411Z","end":"2026-05-10T01:42:14.831539Z","steps":["trace[112356987] 'agreement among raft nodes before linearized reading' (duration: 477.86172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:14.831578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.899451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-05-10T01:42:14.831621Z","caller":"traceutil/trace.go:171","msg":"trace[793964903] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25380; }","duration":"340.989044ms","start":"2026-05-10T01:42:14.490618Z","end":"2026-05-10T01:42:14.831607Z","steps":["trace[793964903] 'agreement among raft nodes before linearized reading' (duration: 340.85785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:14.831664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:14.49059Z","time spent":"341.067765ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-10T01:42:14.831481Z","caller":"traceutil/trace.go:171","msg":"trace[478891943] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"187.785495ms","start":"2026-05-10T01:42:14.643662Z","end":"2026-05-10T01:42:14.831447Z","steps":["trace[478891943] 'process raft request' (duration: 187.084617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:14.831618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:14.353382Z","time spent":"478.22584ms","remote":"127.0.0.1:40206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":60,"response size":32,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:42:15.110884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.900828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:15.111214Z","caller":"traceutil/trace.go:171","msg":"trace[1775971236] linearizableReadLoop","detail":"{readStateIndex:26214; appliedIndex:26211; }","duration":"251.706976ms","start":"2026-05-10T01:42:14.859483Z","end":"2026-05-10T01:42:15.11119Z","steps":["trace[1775971236] 'read index received' (duration: 128.432698ms)","trace[1775971236] 'applied index is now lower than readState.Index' (duration: 123.272488ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:15.111219Z","caller":"traceutil/trace.go:171","msg":"trace[1414001732] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"255.120698ms","start":"2026-05-10T01:42:14.856053Z","end":"2026-05-10T01:42:15.111174Z","steps":["trace[1414001732] 'process raft request' (duration: 131.807579ms)","trace[1414001732] 'compare' (duration: 122.715214ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:15.111245Z","caller":"traceutil/trace.go:171","msg":"trace[425557532] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"255.062337ms","start":"2026-05-10T01:42:14.856142Z","end":"2026-05-10T01:42:15.111204Z","steps":["trace[425557532] 'process raft request' (duration: 254.873952ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.111312Z","caller":"traceutil/trace.go:171","msg":"trace[19947608] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"254.813971ms","start":"2026-05-10T01:42:14.856482Z","end":"2026-05-10T01:42:15.111296Z","steps":["trace[19947608] 'process raft request' (duration: 254.636746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.111601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.108427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-05-10T01:42:15.111657Z","caller":"traceutil/trace.go:171","msg":"trace[1110658117] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25383; }","duration":"252.177449ms","start":"2026-05-10T01:42:14.859462Z","end":"2026-05-10T01:42:15.111639Z","steps":["trace[1110658117] 'agreement among raft nodes before linearized reading' (duration: 251.960203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.60928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.107985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:15.609525Z","caller":"traceutil/trace.go:171","msg":"trace[315854809] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"445.930815ms","start":"2026-05-10T01:42:15.163573Z","end":"2026-05-10T01:42:15.609504Z","steps":["trace[315854809] 'process raft request' (duration: 445.848813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.609608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:15.163542Z","time spent":"446.030778ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:42:15.609593Z","caller":"traceutil/trace.go:171","msg":"trace[1967366856] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"502.507448ms","start":"2026-05-10T01:42:15.107002Z","end":"2026-05-10T01:42:15.60951Z","steps":["trace[1967366856] 'process raft request' (duration: 109.047893ms)","trace[1967366856] 'compare' (duration: 392.935001ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:15.609935Z","caller":"traceutil/trace.go:171","msg":"trace[2022642563] linearizableReadLoop","detail":"{readStateIndex:26215; appliedIndex:26214; }","duration":"498.661004ms","start":"2026-05-10T01:42:15.111259Z","end":"2026-05-10T01:42:15.60992Z","steps":["trace[2022642563] 'read index received' (duration: 104.804829ms)","trace[2022642563] 'applied index is now lower than readState.Index' (duration: 393.854335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:15.610052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.283051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:15.610088Z","caller":"traceutil/trace.go:171","msg":"trace[1604546568] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25385; }","duration":"708.365033ms","start":"2026-05-10T01:42:14.901713Z","end":"2026-05-10T01:42:15.610078Z","steps":["trace[1604546568] 'agreement among raft nodes before linearized reading' (duration: 708.294651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.610122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:14.901692Z","time spent":"708.423594ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-10T01:42:15.610252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.410547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-10T01:42:15.61033Z","caller":"traceutil/trace.go:171","msg":"trace[1454893498] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:25385; }","duration":"580.52758ms","start":"2026-05-10T01:42:15.029789Z","end":"2026-05-10T01:42:15.610316Z","steps":["trace[1454893498] 'agreement among raft nodes before linearized reading' (duration: 580.314674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.610381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:15.029764Z","time spent":"580.609993ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-05-10T01:42:15.610692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:15.106975Z","time spent":"502.792085ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:42:15.621371Z","caller":"traceutil/trace.go:171","msg":"trace[170472376] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"182.209194ms","start":"2026-05-10T01:42:15.43915Z","end":"2026-05-10T01:42:15.621359Z","steps":["trace[170472376] 'process raft request' (duration: 182.131922ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.621373Z","caller":"traceutil/trace.go:171","msg":"trace[327739070] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"205.864454ms","start":"2026-05-10T01:42:15.41549Z","end":"2026-05-10T01:42:15.621355Z","steps":["trace[327739070] 'process raft request' (duration: 205.71493ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.621677Z","caller":"traceutil/trace.go:171","msg":"trace[2022988101] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"182.216264ms","start":"2026-05-10T01:42:15.439426Z","end":"2026-05-10T01:42:15.621642Z","steps":["trace[2022988101] 'process raft request' (duration: 181.898906ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.621729Z","caller":"traceutil/trace.go:171","msg":"trace[1446302813] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"126.219438ms","start":"2026-05-10T01:42:15.4955Z","end":"2026-05-10T01:42:15.621719Z","steps":["trace[1446302813] 'process raft request' (duration: 126.053274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.621942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.584727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-05-10T01:42:15.621947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.534903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-10T01:42:15.621973Z","caller":"traceutil/trace.go:171","msg":"trace[1829641095] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25389; }","duration":"503.631218ms","start":"2026-05-10T01:42:15.118331Z","end":"2026-05-10T01:42:15.621962Z","steps":["trace[1829641095] 'agreement among raft nodes before linearized reading' (duration: 503.537245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.622002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:15.118321Z","time spent":"503.675459ms","remote":"127.0.0.1:39890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1884,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-05-10T01:42:15.622005Z","caller":"traceutil/trace.go:171","msg":"trace[1011531710] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25389; }","duration":"432.626125ms","start":"2026-05-10T01:42:15.189365Z","end":"2026-05-10T01:42:15.621991Z","steps":["trace[1011531710] 'agreement among raft nodes before linearized reading' (duration: 432.44002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.622038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:15.189352Z","time spent":"432.678896ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-10T01:42:15.622025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.213008ms","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-05-10T01:42:15.622118Z","caller":"traceutil/trace.go:171","msg":"trace[650042311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25389; }","duration":"208.387144ms","start":"2026-05-10T01:42:15.413713Z","end":"2026-05-10T01:42:15.6221Z","steps":["trace[650042311] 'agreement among raft nodes before linearized reading' (duration: 208.041614ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.918624Z","caller":"traceutil/trace.go:171","msg":"trace[441457399] linearizableReadLoop","detail":"{readStateIndex:26223; appliedIndex:26221; }","duration":"277.988148ms","start":"2026-05-10T01:42:15.640608Z","end":"2026-05-10T01:42:15.918596Z","steps":["trace[441457399] 'read index received' (duration: 277.803013ms)","trace[441457399] 'applied index is now lower than readState.Index' (duration: 184.165µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:15.918658Z","caller":"traceutil/trace.go:171","msg":"trace[1994662279] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"292.569952ms","start":"2026-05-10T01:42:15.626051Z","end":"2026-05-10T01:42:15.918621Z","steps":["trace[1994662279] 'process raft request' (duration: 292.202742ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.918887Z","caller":"traceutil/trace.go:171","msg":"trace[287676448] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"290.473955ms","start":"2026-05-10T01:42:15.62838Z","end":"2026-05-10T01:42:15.918854Z","steps":["trace[287676448] 'process raft request' (duration: 290.164957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.918927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.474805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:42:15.918979Z","caller":"traceutil/trace.go:171","msg":"trace[1486561539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25391; }","duration":"290.573828ms","start":"2026-05-10T01:42:15.62839Z","end":"2026-05-10T01:42:15.918964Z","steps":["trace[1486561539] 'agreement among raft nodes before linearized reading' (duration: 290.345002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.919061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.368421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-05-10T01:42:15.91918Z","caller":"traceutil/trace.go:171","msg":"trace[2106474877] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25391; }","duration":"284.527815ms","start":"2026-05-10T01:42:15.634623Z","end":"2026-05-10T01:42:15.919151Z","steps":["trace[2106474877] 'agreement among raft nodes before linearized reading' (duration: 284.118843ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.934893Z","caller":"traceutil/trace.go:171","msg":"trace[1734106856] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"292.795078ms","start":"2026-05-10T01:42:15.642073Z","end":"2026-05-10T01:42:15.934868Z","steps":["trace[1734106856] 'process raft request' (duration: 292.13169ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.934941Z","caller":"traceutil/trace.go:171","msg":"trace[1120314300] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"290.499737ms","start":"2026-05-10T01:42:15.644423Z","end":"2026-05-10T01:42:15.934922Z","steps":["trace[1120314300] 'process raft request' (duration: 290.342562ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:15.934968Z","caller":"traceutil/trace.go:171","msg":"trace[1946289751] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"229.32143ms","start":"2026-05-10T01:42:15.705614Z","end":"2026-05-10T01:42:15.934936Z","steps":["trace[1946289751] 'process raft request' (duration: 229.215147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.935251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.592617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-10T01:42:15.935331Z","caller":"traceutil/trace.go:171","msg":"trace[75225838] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25394; }","duration":"212.71486ms","start":"2026-05-10T01:42:15.722594Z","end":"2026-05-10T01:42:15.935309Z","steps":["trace[75225838] 'agreement among raft nodes before linearized reading' (duration: 212.418322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:15.935336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.816568ms","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-05-10T01:42:15.935485Z","caller":"traceutil/trace.go:171","msg":"trace[1308972907] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25394; }","duration":"290.037923ms","start":"2026-05-10T01:42:15.645436Z","end":"2026-05-10T01:42:15.935474Z","steps":["trace[1308972907] 'agreement among raft nodes before linearized reading' (duration: 289.758416ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:16.986297Z","caller":"traceutil/trace.go:171","msg":"trace[1654150537] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26228; }","duration":"122.413925ms","start":"2026-05-10T01:42:16.86384Z","end":"2026-05-10T01:42:16.986254Z","steps":["trace[1654150537] 'read index received' (duration: 41.601527ms)","trace[1654150537] 'applied index is now lower than readState.Index' (duration: 80.811088ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:16.986348Z","caller":"traceutil/trace.go:171","msg":"trace[1601788749] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"146.988171ms","start":"2026-05-10T01:42:16.839335Z","end":"2026-05-10T01:42:16.986323Z","steps":["trace[1601788749] 'process raft request' (duration: 130.296339ms)","trace[1601788749] 'compare' (duration: 16.476196ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:16.986467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.5959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:16.986506Z","caller":"traceutil/trace.go:171","msg":"trace[889148115] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25398; }","duration":"122.704943ms","start":"2026-05-10T01:42:16.863789Z","end":"2026-05-10T01:42:16.986494Z","steps":["trace[889148115] 'agreement among raft nodes before linearized reading' (duration: 122.59521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:16.986569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.826088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:16.986656Z","caller":"traceutil/trace.go:171","msg":"trace[1800356200] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25398; }","duration":"111.951542ms","start":"2026-05-10T01:42:16.874688Z","end":"2026-05-10T01:42:16.986639Z","steps":["trace[1800356200] 'agreement among raft nodes before linearized reading' (duration: 111.795508ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:17.732385Z","caller":"traceutil/trace.go:171","msg":"trace[967275335] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"104.371105ms","start":"2026-05-10T01:42:17.62798Z","end":"2026-05-10T01:42:17.732351Z","steps":["trace[967275335] 'process raft request' (duration: 104.103148ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:17.732577Z","caller":"traceutil/trace.go:171","msg":"trace[982479027] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"103.211795ms","start":"2026-05-10T01:42:17.629333Z","end":"2026-05-10T01:42:17.732545Z","steps":["trace[982479027] 'process raft request' (duration: 102.919797ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:18.132135Z","caller":"traceutil/trace.go:171","msg":"trace[364191519] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"191.772293ms","start":"2026-05-10T01:42:17.940314Z","end":"2026-05-10T01:42:18.132086Z","steps":["trace[364191519] 'process raft request' (duration: 131.786089ms)","trace[364191519] 'compare' (duration: 59.824149ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:18.132162Z","caller":"traceutil/trace.go:171","msg":"trace[2124103910] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"135.333975ms","start":"2026-05-10T01:42:17.996801Z","end":"2026-05-10T01:42:18.132135Z","steps":["trace[2124103910] 'process raft request' (duration: 135.277723ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:18.132238Z","caller":"traceutil/trace.go:171","msg":"trace[641043882] linearizableReadLoop","detail":"{readStateIndex:26239; appliedIndex:26238; }","duration":"140.237729ms","start":"2026-05-10T01:42:17.991961Z","end":"2026-05-10T01:42:18.132199Z","steps":["trace[641043882] 'read index received' (duration: 80.160101ms)","trace[641043882] 'applied index is now lower than readState.Index' (duration: 60.073168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:18.13245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.722084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:18.132524Z","caller":"traceutil/trace.go:171","msg":"trace[2075890827] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25408; }","duration":"190.863278ms","start":"2026-05-10T01:42:17.941643Z","end":"2026-05-10T01:42:18.132506Z","steps":["trace[2075890827] 'agreement among raft nodes before linearized reading' (duration: 190.665983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:18.132569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.672557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:42:18.132619Z","caller":"traceutil/trace.go:171","msg":"trace[1947527968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25408; }","duration":"192.768811ms","start":"2026-05-10T01:42:17.939836Z","end":"2026-05-10T01:42:18.132605Z","steps":["trace[1947527968] 'agreement among raft nodes before linearized reading' (duration: 192.480893ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:19.14519Z","caller":"traceutil/trace.go:171","msg":"trace[270710564] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"153.214479ms","start":"2026-05-10T01:42:18.991939Z","end":"2026-05-10T01:42:19.145153Z","steps":["trace[270710564] 'process raft request' (duration: 78.674761ms)","trace[270710564] 'compare' (duration: 74.358273ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:20.747396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.666157ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-10T01:42:41.939638Z","caller":"traceutil/trace.go:171","msg":"trace[1530528074] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"133.798464ms","start":"2026-05-10T01:42:41.805809Z","end":"2026-05-10T01:42:41.939608Z","steps":["trace[1530528074] 'process raft request' (duration: 133.586218ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:42.010771Z","caller":"traceutil/trace.go:171","msg":"trace[1761366222] linearizableReadLoop","detail":"{readStateIndex:26427; appliedIndex:26423; }","duration":"128.894781ms","start":"2026-05-10T01:42:41.881841Z","end":"2026-05-10T01:42:42.010736Z","steps":["trace[1761366222] 'read index received' (duration: 57.58135ms)","trace[1761366222] 'applied index is now lower than readState.Index' (duration: 71.311571ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:42.010802Z","caller":"traceutil/trace.go:171","msg":"trace[723822165] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"188.761721ms","start":"2026-05-10T01:42:41.821987Z","end":"2026-05-10T01:42:42.010749Z","steps":["trace[723822165] 'process raft request' (duration: 188.620517ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:42.010826Z","caller":"traceutil/trace.go:171","msg":"trace[795235508] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"189.212033ms","start":"2026-05-10T01:42:41.821576Z","end":"2026-05-10T01:42:42.010788Z","steps":["trace[795235508] 'process raft request' (duration: 180.273451ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:42.010811Z","caller":"traceutil/trace.go:171","msg":"trace[59127616] transaction","detail":"{read_only:false; response_revision:25590; number_of_response:1; }","duration":"187.147007ms","start":"2026-05-10T01:42:41.823614Z","end":"2026-05-10T01:42:42.010761Z","steps":["trace[59127616] 'process raft request' (duration: 187.050985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:42.011049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.191658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-10T01:42:42.0111Z","caller":"traceutil/trace.go:171","msg":"trace[1808957074] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25590; }","duration":"129.308372ms","start":"2026-05-10T01:42:41.881776Z","end":"2026-05-10T01:42:42.011085Z","steps":["trace[1808957074] 'agreement among raft nodes before linearized reading' (duration: 129.092396ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:42.576906Z","caller":"traceutil/trace.go:171","msg":"trace[923980411] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"368.224812ms","start":"2026-05-10T01:42:42.208653Z","end":"2026-05-10T01:42:42.576877Z","steps":["trace[923980411] 'process raft request' (duration: 368.016796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:42.577105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:42.208623Z","time spent":"368.369305ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:42:42.772738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.496502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:42.772867Z","caller":"traceutil/trace.go:171","msg":"trace[1753665948] linearizableReadLoop","detail":"{readStateIndex:26429; appliedIndex:26427; }","duration":"516.549467ms","start":"2026-05-10T01:42:42.256299Z","end":"2026-05-10T01:42:42.772849Z","steps":["trace[1753665948] 'read index received' (duration: 320.431887ms)","trace[1753665948] 'applied index is now lower than readState.Index' (duration: 196.11612ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:42.772918Z","caller":"traceutil/trace.go:171","msg":"trace[500031748] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"537.369891ms","start":"2026-05-10T01:42:42.235481Z","end":"2026-05-10T01:42:42.772851Z","steps":["trace[500031748] 'process raft request' (duration: 378.659383ms)","trace[500031748] 'compare' (duration: 158.317587ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:42.772997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:42.235451Z","time spent":"537.510844ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:42:42.773105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.807554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-10T01:42:42.773137Z","caller":"traceutil/trace.go:171","msg":"trace[572919295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25592; }","duration":"516.884228ms","start":"2026-05-10T01:42:42.256241Z","end":"2026-05-10T01:42:42.773125Z","steps":["trace[572919295] 'agreement among raft nodes before linearized reading' (duration: 516.714342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:42.77317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:42.256219Z","time spent":"516.944099ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T01:42:42.773134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.304773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:42.773231Z","caller":"traceutil/trace.go:171","msg":"trace[343981829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25592; }","duration":"423.470508ms","start":"2026-05-10T01:42:42.349735Z","end":"2026-05-10T01:42:42.773206Z","steps":["trace[343981829] 'agreement among raft nodes before linearized reading' (duration: 423.300982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:42.773277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.400514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-10T01:42:42.773245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.7061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-10T01:42:42.773278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:42.349704Z","time spent":"423.561071ms","remote":"127.0.0.1:39700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T01:42:42.773304Z","caller":"traceutil/trace.go:171","msg":"trace[493904021] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25592; }","duration":"232.430504ms","start":"2026-05-10T01:42:42.540865Z","end":"2026-05-10T01:42:42.773295Z","steps":["trace[493904021] 'agreement among raft nodes before linearized reading' (duration: 232.370553ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:42.773367Z","caller":"traceutil/trace.go:171","msg":"trace[1769468246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25592; }","duration":"510.844134ms","start":"2026-05-10T01:42:42.262466Z","end":"2026-05-10T01:42:42.77331Z","steps":["trace[1769468246] 'agreement among raft nodes before linearized reading' (duration: 510.574476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:42.773456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:42.262449Z","time spent":"510.994658ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T01:42:42.773618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.616405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:42.773649Z","caller":"traceutil/trace.go:171","msg":"trace[1043351889] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25592; }","duration":"470.674325ms","start":"2026-05-10T01:42:42.302962Z","end":"2026-05-10T01:42:42.773636Z","steps":["trace[1043351889] 'agreement among raft nodes before linearized reading' (duration: 470.612434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:42.77368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:42.302951Z","time spent":"470.720276ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:42:43.073331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.246682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:43.073568Z","caller":"traceutil/trace.go:171","msg":"trace[712613599] linearizableReadLoop","detail":"{readStateIndex:26431; appliedIndex:26429; }","duration":"234.664445ms","start":"2026-05-10T01:42:42.83888Z","end":"2026-05-10T01:42:43.073545Z","steps":["trace[712613599] 'read index received' (duration: 92.171246ms)","trace[712613599] 'applied index is now lower than readState.Index' (duration: 142.491969ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:43.073742Z","caller":"traceutil/trace.go:171","msg":"trace[403115796] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"296.089088ms","start":"2026-05-10T01:42:42.777608Z","end":"2026-05-10T01:42:43.073697Z","steps":["trace[403115796] 'process raft request' (duration: 153.352223ms)","trace[403115796] 'compare' (duration: 141.914693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:43.073814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.935462ms","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:6"} {"level":"info","ts":"2026-05-10T01:42:43.07396Z","caller":"traceutil/trace.go:171","msg":"trace[499380223] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25594; }","duration":"235.082827ms","start":"2026-05-10T01:42:42.838858Z","end":"2026-05-10T01:42:43.073941Z","steps":["trace[499380223] 'agreement among raft nodes before linearized reading' (duration: 234.898452ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:43.074834Z","caller":"traceutil/trace.go:171","msg":"trace[2080434790] transaction","detail":"{read_only:false; response_revision:25594; number_of_response:1; }","duration":"294.412322ms","start":"2026-05-10T01:42:42.779287Z","end":"2026-05-10T01:42:43.0737Z","steps":["trace[2080434790] 'process raft request' (duration: 294.190386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:43.080166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.846125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:43.080215Z","caller":"traceutil/trace.go:171","msg":"trace[52723971] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25595; }","duration":"133.959308ms","start":"2026-05-10T01:42:42.946241Z","end":"2026-05-10T01:42:43.080201Z","steps":["trace[52723971] 'agreement among raft nodes before linearized reading' (duration: 133.832685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:43.729929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.600973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:43.730218Z","caller":"traceutil/trace.go:171","msg":"trace[95190712] linearizableReadLoop","detail":"{readStateIndex:26436; appliedIndex:26434; }","duration":"137.820032ms","start":"2026-05-10T01:42:43.592376Z","end":"2026-05-10T01:42:43.730196Z","steps":["trace[95190712] 'read index received' (duration: 71.652µs)","trace[95190712] 'applied index is now lower than readState.Index' (duration: 137.7466ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:43.73032Z","caller":"traceutil/trace.go:171","msg":"trace[1204089932] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"278.900362ms","start":"2026-05-10T01:42:43.451388Z","end":"2026-05-10T01:42:43.730288Z","steps":["trace[1204089932] 'process raft request' (duration: 278.653786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:43.730354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.982077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:43.730441Z","caller":"traceutil/trace.go:171","msg":"trace[1115339305] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25599; }","duration":"138.103529ms","start":"2026-05-10T01:42:43.592302Z","end":"2026-05-10T01:42:43.730406Z","steps":["trace[1115339305] 'agreement among raft nodes before linearized reading' (duration: 137.960115ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:43.730471Z","caller":"traceutil/trace.go:171","msg":"trace[947640756] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"279.19814ms","start":"2026-05-10T01:42:43.451222Z","end":"2026-05-10T01:42:43.73042Z","steps":["trace[947640756] 'process raft request' (duration: 133.010741ms)","trace[947640756] 'compare' (duration: 145.451239ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:44.06183Z","caller":"traceutil/trace.go:171","msg":"trace[1501316509] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"116.088414ms","start":"2026-05-10T01:42:43.945706Z","end":"2026-05-10T01:42:44.061794Z","steps":["trace[1501316509] 'process raft request' (duration: 115.794795ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:44.349801Z","caller":"traceutil/trace.go:171","msg":"trace[930067583] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"332.493133ms","start":"2026-05-10T01:42:44.017273Z","end":"2026-05-10T01:42:44.349766Z","steps":["trace[930067583] 'process raft request' (duration: 236.931515ms)","trace[930067583] 'compare' (duration: 94.87586ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:44.349911Z","caller":"traceutil/trace.go:171","msg":"trace[1315040098] linearizableReadLoop","detail":"{readStateIndex:26440; appliedIndex:26437; }","duration":"288.167824ms","start":"2026-05-10T01:42:44.061717Z","end":"2026-05-10T01:42:44.349885Z","steps":["trace[1315040098] 'read index received' (duration: 192.471313ms)","trace[1315040098] 'applied index is now lower than readState.Index' (duration: 95.695081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:44.349997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:44.017248Z","time spent":"332.635377ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:42:44.350008Z","caller":"traceutil/trace.go:171","msg":"trace[1086885735] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"329.600285ms","start":"2026-05-10T01:42:44.020372Z","end":"2026-05-10T01:42:44.349972Z","steps":["trace[1086885735] 'process raft request' (duration: 329.451701ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:44.350172Z","caller":"traceutil/trace.go:171","msg":"trace[1506646531] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"331.729503ms","start":"2026-05-10T01:42:44.018429Z","end":"2026-05-10T01:42:44.350158Z","steps":["trace[1506646531] 'process raft request' (duration: 331.259561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:44.350193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.490561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:42:44.350246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:44.018414Z","time spent":"331.793965ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:42:44.350247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:44.020363Z","time spent":"329.78489ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:42:44.350306Z","caller":"traceutil/trace.go:171","msg":"trace[831341115] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25603; }","duration":"343.651016ms","start":"2026-05-10T01:42:44.006628Z","end":"2026-05-10T01:42:44.350279Z","steps":["trace[831341115] 'agreement among raft nodes before linearized reading' (duration: 343.418259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:44.350242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.713698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-10T01:42:44.350368Z","caller":"traceutil/trace.go:171","msg":"trace[1928686116] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25603; }","duration":"298.899905ms","start":"2026-05-10T01:42:44.051454Z","end":"2026-05-10T01:42:44.350354Z","steps":["trace[1928686116] 'agreement among raft nodes before linearized reading' (duration: 298.554954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:44.350396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:44.0066Z","time spent":"343.75146ms","remote":"127.0.0.1:45286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":30,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-05-10T01:42:44.57417Z","caller":"traceutil/trace.go:171","msg":"trace[599747972] linearizableReadLoop","detail":"{readStateIndex:26442; appliedIndex:26441; }","duration":"154.960286ms","start":"2026-05-10T01:42:44.419152Z","end":"2026-05-10T01:42:44.574112Z","steps":["trace[599747972] 'read index received' (duration: 114.199743ms)","trace[599747972] 'applied index is now lower than readState.Index' (duration: 40.759023ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:44.574945Z","caller":"traceutil/trace.go:171","msg":"trace[1319598889] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"224.249413ms","start":"2026-05-10T01:42:44.350637Z","end":"2026-05-10T01:42:44.574886Z","steps":["trace[1319598889] 'process raft request' (duration: 182.747609ms)","trace[1319598889] 'compare' (duration: 40.464435ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:44.575705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.501808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:42:44.575776Z","caller":"traceutil/trace.go:171","msg":"trace[84331159] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25605; }","duration":"156.650602ms","start":"2026-05-10T01:42:44.419106Z","end":"2026-05-10T01:42:44.575757Z","steps":["trace[84331159] 'agreement among raft nodes before linearized reading' (duration: 155.147341ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:43:01.86206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22870} {"level":"info","ts":"2026-05-10T01:43:02.010074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22870,"took":"143.073984ms","hash":1134871677,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":34541568,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-10T01:43:02.010154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1134871677,"revision":22870,"compact-revision":20036} {"level":"info","ts":"2026-05-10T01:43:40.609301Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069a8.wal"} {"level":"warn","ts":"2026-05-10T01:44:35.389383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.533682ms","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-05-10T01:44:35.389472Z","caller":"traceutil/trace.go:171","msg":"trace[237534472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26695; }","duration":"119.685086ms","start":"2026-05-10T01:44:35.269771Z","end":"2026-05-10T01:44:35.389456Z","steps":["trace[237534472] 'range keys from in-memory index tree' (duration: 119.339408ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:48:01.871248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25735} {"level":"info","ts":"2026-05-10T01:48:02.011082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25735,"took":"134.318502ms","hash":3873345337,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":37306368,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-10T01:48:02.01126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3873345337,"revision":25735,"compact-revision":22870} {"level":"info","ts":"2026-05-10T01:49:05.912413Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-10T01:49:05.917616Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-10T01:49:05.917732Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-10T01:53:01.878045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28606} {"level":"info","ts":"2026-05-10T01:53:02.019178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28606,"took":"135.520518ms","hash":2548110639,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":30076928,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-10T01:53:02.019251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2548110639,"revision":28606,"compact-revision":25735} {"level":"info","ts":"2026-05-10T01:58:01.885188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30826} {"level":"info","ts":"2026-05-10T01:58:02.002649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30826,"took":"112.399602ms","hash":2842674072,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-10T01:58:02.002761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2842674072,"revision":30826,"compact-revision":28606} {"level":"info","ts":"2026-05-10T02:03:01.892129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33087} {"level":"info","ts":"2026-05-10T02:03:02.043462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33087,"took":"145.62389ms","hash":2608517486,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":31768576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-10T02:03:02.043539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2608517486,"revision":33087,"compact-revision":30826} {"level":"info","ts":"2026-05-10T02:08:01.901063Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35443} {"level":"info","ts":"2026-05-10T02:08:02.060108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35443,"took":"153.04038ms","hash":645753058,"current-db-size-bytes":59658240,"current-db-size":"60 MB","current-db-size-in-use-bytes":31465472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-10T02:08:02.060203Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":645753058,"revision":35443,"compact-revision":33087} {"level":"info","ts":"2026-05-10T02:10:19.872086Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-10T02:10:19.878513Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-10T02:10:19.878636Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-10T02:13:01.907468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37701} {"level":"info","ts":"2026-05-10T02:13:02.025314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37701,"took":"113.412898ms","hash":2232314730,"current-db-size-bytes":59658240,"current-db-size":"60 MB","current-db-size-in-use-bytes":30806016,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-10T02:13:02.025421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2232314730,"revision":37701,"compact-revision":35443}