{"level":"warn","ts":"2026-04-12T18:35:56.081976Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T18:35:56.082113Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-04-12T18:35:56.082202Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T18:35:56.082221Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-04-12T18:35:56.082246Z","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-04-12T18:35:56.083097Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-04-12T18:35:56.083185Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-04-12T18:35:56.090223Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.807988ms"} {"level":"info","ts":"2026-04-12T18:35:56.099768Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-04-12T18:35:56.099927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T18:35:56.099968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-04-12T18:35:56.1Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T18:35:56.100022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-04-12T18:35:56.100074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-04-12T18:35:56.108387Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T18:35:56.110036Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T18:35:56.111609Z","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-04-12T18:35:56.114546Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T18:35:56.114752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T18:35:56.114858Z","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-04-12T18:35:56.115027Z","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-04-12T18:35:56.11508Z","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-04-12T18:35:56.117438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-04-12T18:35:56.117696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-04-12T18:35:56.119926Z","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-04-12T18:35:56.120313Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T18:35:56.12035Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T18:35:56.120071Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-04-12T18:35:56.120474Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-04-12T18:35:56.901192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T18:35:56.901294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T18:35:56.901324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-04-12T18:35:56.901345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-04-12T18:35:56.901357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-04-12T18:35:56.901375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-04-12T18:35:56.901389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-04-12T18:35:56.902663Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T18:35:56.902665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T18:35:56.902798Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:35:56.902977Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T18:35:56.903404Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T18:35:56.903441Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T18:35:56.904278Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:35:56.904525Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:35:56.904614Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:35:56.905249Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-04-12T18:35:56.907976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T18:36:12.562081Z","caller":"traceutil/trace.go:171","msg":"trace[338631696] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"115.225501ms","start":"2026-04-12T18:36:12.446829Z","end":"2026-04-12T18:36:12.562054Z","steps":["trace[338631696] 'process raft request' (duration: 115.087588ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:36:12.562053Z","caller":"traceutil/trace.go:171","msg":"trace[1919909734] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"117.330987ms","start":"2026-04-12T18:36:12.444644Z","end":"2026-04-12T18:36:12.561975Z","steps":["trace[1919909734] 'process raft request' (duration: 90.261859ms)","trace[1919909734] 'compare' (duration: 26.852143ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:36:12.562573Z","caller":"traceutil/trace.go:171","msg":"trace[813042852] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"110.495629ms","start":"2026-04-12T18:36:12.452057Z","end":"2026-04-12T18:36:12.562553Z","steps":["trace[813042852] 'process raft request' (duration: 110.308565ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:36:12.562639Z","caller":"traceutil/trace.go:171","msg":"trace[1768825013] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"115.636259ms","start":"2026-04-12T18:36:12.446939Z","end":"2026-04-12T18:36:12.562576Z","steps":["trace[1768825013] 'process raft request' (duration: 115.043587ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:36:21.845024Z","caller":"traceutil/trace.go:171","msg":"trace[2115195832] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"121.117139ms","start":"2026-04-12T18:36:21.723881Z","end":"2026-04-12T18:36:21.844999Z","steps":["trace[2115195832] 'process raft request' (duration: 120.966336ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:36:32.345157Z","caller":"traceutil/trace.go:171","msg":"trace[1419205186] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"203.921726ms","start":"2026-04-12T18:36:32.14117Z","end":"2026-04-12T18:36:32.345091Z","steps":["trace[1419205186] 'read index received' (duration: 197.7319ms)","trace[1419205186] 'applied index is now lower than readState.Index' (duration: 6.188346ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:36:32.345189Z","caller":"traceutil/trace.go:171","msg":"trace[1966326731] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"214.629739ms","start":"2026-04-12T18:36:32.130496Z","end":"2026-04-12T18:36:32.345126Z","steps":["trace[1966326731] 'process raft request' (duration: 210.712604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:36:32.345666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.446627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-12T18:36:32.346111Z","caller":"traceutil/trace.go:171","msg":"trace[578042227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"204.889226ms","start":"2026-04-12T18:36:32.141132Z","end":"2026-04-12T18:36:32.346021Z","steps":["trace[578042227] 'agreement among raft nodes before linearized reading' (duration: 204.067198ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:36:32.500969Z","caller":"traceutil/trace.go:171","msg":"trace[75356387] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"148.15575ms","start":"2026-04-12T18:36:32.352783Z","end":"2026-04-12T18:36:32.500939Z","steps":["trace[75356387] 'process raft request' (duration: 147.938405ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:36:32.507404Z","caller":"traceutil/trace.go:171","msg":"trace[1755433751] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"112.757289ms","start":"2026-04-12T18:36:32.394613Z","end":"2026-04-12T18:36:32.50737Z","steps":["trace[1755433751] 'process raft request' (duration: 112.39834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:36:32.808761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.599948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:36:32.808872Z","caller":"traceutil/trace.go:171","msg":"trace[48230282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"114.769282ms","start":"2026-04-12T18:36:32.694079Z","end":"2026-04-12T18:36:32.808848Z","steps":["trace[48230282] 'range keys from in-memory index tree' (duration: 114.491556ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:36:32.971457Z","caller":"traceutil/trace.go:171","msg":"trace[196056832] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"143.669061ms","start":"2026-04-12T18:36:32.827753Z","end":"2026-04-12T18:36:32.971422Z","steps":["trace[196056832] 'process raft request' (duration: 143.430606ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:36:48.719685Z","caller":"traceutil/trace.go:171","msg":"trace[1996931595] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"108.835822ms","start":"2026-04-12T18:36:48.61082Z","end":"2026-04-12T18:36:48.719656Z","steps":["trace[1996931595] 'read index received' (duration: 108.569007ms)","trace[1996931595] 'applied index is now lower than readState.Index' (duration: 265.345µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:36:48.719812Z","caller":"traceutil/trace.go:171","msg":"trace[1198546417] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"136.306652ms","start":"2026-04-12T18:36:48.583463Z","end":"2026-04-12T18:36:48.71977Z","steps":["trace[1198546417] 'process raft request' (duration: 135.978115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:36:48.719947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.107708ms","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-04-12T18:36:48.719995Z","caller":"traceutil/trace.go:171","msg":"trace[855288136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"109.22615ms","start":"2026-04-12T18:36:48.610756Z","end":"2026-04-12T18:36:48.719982Z","steps":["trace[855288136] 'agreement among raft nodes before linearized reading' (duration: 109.053147ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:37:47.392314Z","caller":"traceutil/trace.go:171","msg":"trace[1691061886] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"116.347651ms","start":"2026-04-12T18:37:47.275931Z","end":"2026-04-12T18:37:47.392279Z","steps":["trace[1691061886] 'process raft request' (duration: 116.095285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:38:12.592622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.400865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-12T18:38:12.592786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.793851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e406661a-f276-443f-b6ae-0ae40d308f3f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-12T18:38:12.592918Z","caller":"traceutil/trace.go:171","msg":"trace[1296995067] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e406661a-f276-443f-b6ae-0ae40d308f3f; range_end:; response_count:1; response_revision:1837; }","duration":"258.971065ms","start":"2026-04-12T18:38:12.333904Z","end":"2026-04-12T18:38:12.592875Z","steps":["trace[1296995067] 'range keys from in-memory index tree' (duration: 258.599565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:38:12.592949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.844768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-12T18:38:12.592922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.540159ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:38:12.593055Z","caller":"traceutil/trace.go:171","msg":"trace[2123154075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1837; }","duration":"148.994521ms","start":"2026-04-12T18:38:12.444038Z","end":"2026-04-12T18:38:12.593032Z","steps":["trace[2123154075] 'range keys from in-memory index tree' (duration: 148.565942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:38:12.593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.113811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-04-12T18:38:12.593141Z","caller":"traceutil/trace.go:171","msg":"trace[2043035018] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1837; }","duration":"226.738763ms","start":"2026-04-12T18:38:12.366323Z","end":"2026-04-12T18:38:12.593061Z","steps":["trace[2043035018] 'range keys from in-memory index tree' (duration: 226.508768ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:38:12.593182Z","caller":"traceutil/trace.go:171","msg":"trace[1125893958] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1837; }","duration":"256.382848ms","start":"2026-04-12T18:38:12.336767Z","end":"2026-04-12T18:38:12.59315Z","steps":["trace[1125893958] 'range keys from in-memory index tree' (duration: 255.744473ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:38:12.59278Z","caller":"traceutil/trace.go:171","msg":"trace[2093616748] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1837; }","duration":"214.65263ms","start":"2026-04-12T18:38:12.378095Z","end":"2026-04-12T18:38:12.592748Z","steps":["trace[2093616748] 'count revisions from in-memory index tree' (duration: 214.233111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:38:13.011016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.900825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T18:38:13.011265Z","caller":"traceutil/trace.go:171","msg":"trace[1650053527] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"365.173514ms","start":"2026-04-12T18:38:12.64607Z","end":"2026-04-12T18:38:13.011243Z","steps":["trace[1650053527] 'process raft request' (duration: 104.419181ms)","trace[1650053527] 'compare' (duration: 259.771382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:38:13.011342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:38:12.646047Z","time spent":"365.261605ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:38:13.0117Z","caller":"traceutil/trace.go:171","msg":"trace[1698893515] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"362.788942ms","start":"2026-04-12T18:38:12.648854Z","end":"2026-04-12T18:38:13.011643Z","steps":["trace[1698893515] 'process raft request' (duration: 362.546906ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:38:13.011871Z","caller":"traceutil/trace.go:171","msg":"trace[1289546152] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"362.823273ms","start":"2026-04-12T18:38:12.649034Z","end":"2026-04-12T18:38:13.011857Z","steps":["trace[1289546152] 'process raft request' (duration: 362.536027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:38:13.0119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:38:12.648822Z","time spent":"362.960506ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:38:13.011964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:38:12.648996Z","time spent":"362.923504ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:38:13.012277Z","caller":"traceutil/trace.go:171","msg":"trace[1485458145] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"362.074098ms","start":"2026-04-12T18:38:12.650189Z","end":"2026-04-12T18:38:13.012263Z","steps":["trace[1485458145] 'process raft request' (duration: 361.903594ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:38:13.012315Z","caller":"traceutil/trace.go:171","msg":"trace[1563618952] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"362.813844ms","start":"2026-04-12T18:38:12.64949Z","end":"2026-04-12T18:38:13.012304Z","steps":["trace[1563618952] 'process raft request' (duration: 362.295192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:38:13.012366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:38:12.650179Z","time spent":"362.142709ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:38:13.012375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:38:12.649475Z","time spent":"362.869805ms","remote":"127.0.0.1:54062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:38:13.01225Z","caller":"traceutil/trace.go:171","msg":"trace[1502734546] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1908; }","duration":"317.546899ms","start":"2026-04-12T18:38:12.694654Z","end":"2026-04-12T18:38:13.012201Z","steps":["trace[1502734546] 'read index received' (duration: 55.928436ms)","trace[1502734546] 'applied index is now lower than readState.Index' (duration: 261.617083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:38:13.013105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.421148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:38:13.013161Z","caller":"traceutil/trace.go:171","msg":"trace[1678243438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1844; }","duration":"318.54914ms","start":"2026-04-12T18:38:12.694598Z","end":"2026-04-12T18:38:13.013147Z","steps":["trace[1678243438] 'agreement among raft nodes before linearized reading' (duration: 317.707922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:38:13.013195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:38:12.694572Z","time spent":"318.616622ms","remote":"127.0.0.1:53870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T18:38:13.02348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.619575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-12T18:38:13.023535Z","caller":"traceutil/trace.go:171","msg":"trace[1016471971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1845; }","duration":"200.700896ms","start":"2026-04-12T18:38:12.82281Z","end":"2026-04-12T18:38:13.023511Z","steps":["trace[1016471971] 'agreement among raft nodes before linearized reading' (duration: 200.554943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:38:13.023722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.512849ms","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-04-12T18:38:13.023754Z","caller":"traceutil/trace.go:171","msg":"trace[2054085353] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"238.58205ms","start":"2026-04-12T18:38:12.785163Z","end":"2026-04-12T18:38:13.023745Z","steps":["trace[2054085353] 'agreement among raft nodes before linearized reading' (duration: 238.500129ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:38:25.257762Z","caller":"traceutil/trace.go:171","msg":"trace[394675448] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"141.784605ms","start":"2026-04-12T18:38:25.115946Z","end":"2026-04-12T18:38:25.25773Z","steps":["trace[394675448] 'process raft request' (duration: 141.53764ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:38:25.493184Z","caller":"traceutil/trace.go:171","msg":"trace[468816161] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"230.993076ms","start":"2026-04-12T18:38:25.262158Z","end":"2026-04-12T18:38:25.493151Z","steps":["trace[468816161] 'process raft request' (duration: 206.609615ms)","trace[468816161] 'compare' (duration: 24.129506ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:38:25.493213Z","caller":"traceutil/trace.go:171","msg":"trace[706474969] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"229.797739ms","start":"2026-04-12T18:38:25.263371Z","end":"2026-04-12T18:38:25.493169Z","steps":["trace[706474969] 'process raft request' (duration: 229.672147ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:40:31.483348Z","caller":"traceutil/trace.go:171","msg":"trace[1961047556] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"143.468353ms","start":"2026-04-12T18:40:31.339852Z","end":"2026-04-12T18:40:31.48332Z","steps":["trace[1961047556] 'process raft request' (duration: 143.286319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:44:25.448409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.996443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T18:44:25.448572Z","caller":"traceutil/trace.go:171","msg":"trace[1262203042] linearizableReadLoop","detail":"{readStateIndex:4997; appliedIndex:4995; }","duration":"110.404727ms","start":"2026-04-12T18:44:25.338154Z","end":"2026-04-12T18:44:25.448559Z","steps":["trace[1262203042] 'read index received' (duration: 3.192958ms)","trace[1262203042] 'applied index is now lower than readState.Index' (duration: 107.210789ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:44:25.448693Z","caller":"traceutil/trace.go:171","msg":"trace[1167674990] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"115.796232ms","start":"2026-04-12T18:44:25.332886Z","end":"2026-04-12T18:44:25.448683Z","steps":["trace[1167674990] 'process raft request' (duration: 115.620978ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:25.448909Z","caller":"traceutil/trace.go:171","msg":"trace[488302401] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"118.172202ms","start":"2026-04-12T18:44:25.3307Z","end":"2026-04-12T18:44:25.448872Z","steps":["trace[488302401] 'process raft request' (duration: 10.632716ms)","trace[488302401] 'compare' (duration: 106.84607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:44:25.449139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.957739ms","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-04-12T18:44:25.449218Z","caller":"traceutil/trace.go:171","msg":"trace[840049670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4817; }","duration":"111.092781ms","start":"2026-04-12T18:44:25.338109Z","end":"2026-04-12T18:44:25.449202Z","steps":["trace[840049670] 'agreement among raft nodes before linearized reading' (duration: 110.840416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:44:30.489793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.74817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cs24m.18a5b04bf49b67e5\" ","response":"range_response_count:1 size:962"} {"level":"warn","ts":"2026-04-12T18:44:30.489839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.798234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1a0a311-825e-49e3-804e-8b84404b922a\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-12T18:44:30.489909Z","caller":"traceutil/trace.go:171","msg":"trace[189352655] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cs24m.18a5b04bf49b67e5; range_end:; response_count:1; response_revision:5011; }","duration":"123.911683ms","start":"2026-04-12T18:44:30.365975Z","end":"2026-04-12T18:44:30.489886Z","steps":["trace[189352655] 'range keys from in-memory index tree' (duration: 123.542635ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:30.489919Z","caller":"traceutil/trace.go:171","msg":"trace[1797831987] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1a0a311-825e-49e3-804e-8b84404b922a; range_end:; response_count:1; response_revision:5011; }","duration":"125.940807ms","start":"2026-04-12T18:44:30.363957Z","end":"2026-04-12T18:44:30.489898Z","steps":["trace[1797831987] 'range keys from in-memory index tree' (duration: 125.602459ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:30.59269Z","caller":"traceutil/trace.go:171","msg":"trace[1883258246] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"221.410955ms","start":"2026-04-12T18:44:30.371257Z","end":"2026-04-12T18:44:30.592668Z","steps":["trace[1883258246] 'process raft request' (duration: 221.299363ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:30.690453Z","caller":"traceutil/trace.go:171","msg":"trace[2042254951] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"192.660654ms","start":"2026-04-12T18:44:30.497759Z","end":"2026-04-12T18:44:30.69042Z","steps":["trace[2042254951] 'process raft request' (duration: 192.493061ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:30.690487Z","caller":"traceutil/trace.go:171","msg":"trace[1570763256] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"197.730142ms","start":"2026-04-12T18:44:30.492726Z","end":"2026-04-12T18:44:30.690456Z","steps":["trace[1570763256] 'process raft request' (duration: 197.380845ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:30.701423Z","caller":"traceutil/trace.go:171","msg":"trace[1078145039] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"132.032716ms","start":"2026-04-12T18:44:30.56937Z","end":"2026-04-12T18:44:30.701403Z","steps":["trace[1078145039] 'process raft request' (duration: 121.033362ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:39.063826Z","caller":"traceutil/trace.go:171","msg":"trace[737178275] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"112.215715ms","start":"2026-04-12T18:44:38.951572Z","end":"2026-04-12T18:44:39.063788Z","steps":["trace[737178275] 'process raft request' (duration: 111.98119ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:41.691764Z","caller":"traceutil/trace.go:171","msg":"trace[47737911] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"120.322158ms","start":"2026-04-12T18:44:41.571416Z","end":"2026-04-12T18:44:41.691739Z","steps":["trace[47737911] 'process raft request' (duration: 105.540114ms)","trace[47737911] 'compare' (duration: 14.544889ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:44:41.691933Z","caller":"traceutil/trace.go:171","msg":"trace[270936789] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"119.397808ms","start":"2026-04-12T18:44:41.57252Z","end":"2026-04-12T18:44:41.691918Z","steps":["trace[270936789] 'process raft request' (duration: 119.147813ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:41.692071Z","caller":"traceutil/trace.go:171","msg":"trace[488447315] linearizableReadLoop","detail":"{readStateIndex:5436; appliedIndex:5434; }","duration":"119.215604ms","start":"2026-04-12T18:44:41.572846Z","end":"2026-04-12T18:44:41.692062Z","steps":["trace[488447315] 'read index received' (duration: 104.082273ms)","trace[488447315] 'applied index is now lower than readState.Index' (duration: 15.131721ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:44:41.692184Z","caller":"traceutil/trace.go:171","msg":"trace[299458067] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"119.032689ms","start":"2026-04-12T18:44:41.573114Z","end":"2026-04-12T18:44:41.692146Z","steps":["trace[299458067] 'process raft request' (duration: 118.757063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:44:41.692261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.406298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130149"} {"level":"info","ts":"2026-04-12T18:44:41.692291Z","caller":"traceutil/trace.go:171","msg":"trace[719756185] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5243; }","duration":"119.468589ms","start":"2026-04-12T18:44:41.572814Z","end":"2026-04-12T18:44:41.692283Z","steps":["trace[719756185] 'agreement among raft nodes before linearized reading' (duration: 119.280155ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:50.70078Z","caller":"traceutil/trace.go:171","msg":"trace[394153030] linearizableReadLoop","detail":"{readStateIndex:5623; appliedIndex:5622; }","duration":"193.734757ms","start":"2026-04-12T18:44:50.507014Z","end":"2026-04-12T18:44:50.700748Z","steps":["trace[394153030] 'read index received' (duration: 187.996375ms)","trace[394153030] 'applied index is now lower than readState.Index' (duration: 5.737102ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:44:50.700959Z","caller":"traceutil/trace.go:171","msg":"trace[1480368830] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"201.4064ms","start":"2026-04-12T18:44:50.499486Z","end":"2026-04-12T18:44:50.700893Z","steps":["trace[1480368830] 'process raft request' (duration: 195.436153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:44:50.701203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.162545ms","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-04-12T18:44:50.701247Z","caller":"traceutil/trace.go:171","msg":"trace[665927161] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5427; }","duration":"194.254618ms","start":"2026-04-12T18:44:50.506981Z","end":"2026-04-12T18:44:50.701236Z","steps":["trace[665927161] 'agreement among raft nodes before linearized reading' (duration: 193.962192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:44:50.701273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.433945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:44:50.70135Z","caller":"traceutil/trace.go:171","msg":"trace[227866273] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5427; }","duration":"164.548677ms","start":"2026-04-12T18:44:50.536783Z","end":"2026-04-12T18:44:50.701331Z","steps":["trace[227866273] 'agreement among raft nodes before linearized reading' (duration: 164.413884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:44:52.95755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.806603ms","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-04-12T18:44:52.957665Z","caller":"traceutil/trace.go:171","msg":"trace[1068897465] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5461; }","duration":"189.966406ms","start":"2026-04-12T18:44:52.767673Z","end":"2026-04-12T18:44:52.957639Z","steps":["trace[1068897465] 'range keys from in-memory index tree' (duration: 189.595388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:44:52.957711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.903902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-12T18:44:52.957973Z","caller":"traceutil/trace.go:171","msg":"trace[2073960571] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"157.60356ms","start":"2026-04-12T18:44:52.80034Z","end":"2026-04-12T18:44:52.957943Z","steps":["trace[2073960571] 'process raft request' (duration: 155.875403ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:44:52.958559Z","caller":"traceutil/trace.go:171","msg":"trace[1632367263] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5461; }","duration":"196.178649ms","start":"2026-04-12T18:44:52.761751Z","end":"2026-04-12T18:44:52.95793Z","steps":["trace[1632367263] 'range keys from in-memory index tree' (duration: 195.712648ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:45:56.935973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-04-12T18:45:57.029463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"90.970584ms","hash":3760335876,"current-db-size-bytes":57360384,"current-db-size":"57 MB","current-db-size-in-use-bytes":33783808,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-12T18:45:57.029533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3760335876,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-04-12T18:50:56.94366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6052} {"level":"info","ts":"2026-04-12T18:50:57.047199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6052,"took":"101.258098ms","hash":1161965874,"current-db-size-bytes":57360384,"current-db-size":"57 MB","current-db-size-in-use-bytes":23195648,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-12T18:50:57.047305Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1161965874,"revision":6052,"compact-revision":2978} {"level":"info","ts":"2026-04-12T18:50:58.679375Z","caller":"traceutil/trace.go:171","msg":"trace[873159852] linearizableReadLoop","detail":"{readStateIndex:8551; appliedIndex:8550; }","duration":"331.086445ms","start":"2026-04-12T18:50:58.348265Z","end":"2026-04-12T18:50:58.679351Z","steps":["trace[873159852] 'read index received' (duration: 330.993192ms)","trace[873159852] 'applied index is now lower than readState.Index' (duration: 91.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:50:58.67945Z","caller":"traceutil/trace.go:171","msg":"trace[1440820925] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"332.989875ms","start":"2026-04-12T18:50:58.346417Z","end":"2026-04-12T18:50:58.679406Z","steps":["trace[1440820925] 'process raft request' (duration: 332.78143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:50:58.679605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.33386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v9v4s\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-12T18:50:58.679654Z","caller":"traceutil/trace.go:171","msg":"trace[736146835] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v9v4s; range_end:; response_count:1; response_revision:8264; }","duration":"331.445822ms","start":"2026-04-12T18:50:58.348192Z","end":"2026-04-12T18:50:58.679638Z","steps":["trace[736146835] 'agreement among raft nodes before linearized reading' (duration: 331.300099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:50:58.679651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:50:58.346395Z","time spent":"333.144228ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:50:58.679686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:50:58.348169Z","time spent":"331.508944ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6475,"request content":"key:\"/registry/pods/openstack/keepalived-v9v4s\" "} {"level":"info","ts":"2026-04-12T18:50:58.767808Z","caller":"traceutil/trace.go:171","msg":"trace[1655524455] transaction","detail":"{read_only:false; response_revision:8265; number_of_response:1; }","duration":"408.21978ms","start":"2026-04-12T18:50:58.359569Z","end":"2026-04-12T18:50:58.767789Z","steps":["trace[1655524455] 'process raft request' (duration: 407.993276ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:50:58.767843Z","caller":"traceutil/trace.go:171","msg":"trace[1079447474] transaction","detail":"{read_only:false; response_revision:8266; number_of_response:1; }","duration":"405.993273ms","start":"2026-04-12T18:50:58.361824Z","end":"2026-04-12T18:50:58.767818Z","steps":["trace[1079447474] 'process raft request' (duration: 405.889861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:50:58.767911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:50:58.359552Z","time spent":"408.295383ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:50:58.767986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:50:58.361818Z","time spent":"406.090955ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:50:58.767956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.216411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:50:58.768121Z","caller":"traceutil/trace.go:171","msg":"trace[2113010290] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8267; }","duration":"285.448846ms","start":"2026-04-12T18:50:58.482646Z","end":"2026-04-12T18:50:58.768095Z","steps":["trace[2113010290] 'agreement among raft nodes before linearized reading' (duration: 285.203221ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:50:58.7681Z","caller":"traceutil/trace.go:171","msg":"trace[1057318220] transaction","detail":"{read_only:false; response_revision:8267; number_of_response:1; }","duration":"400.109138ms","start":"2026-04-12T18:50:58.367947Z","end":"2026-04-12T18:50:58.768056Z","steps":["trace[1057318220] 'process raft request' (duration: 399.802812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:50:58.768647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:50:58.367937Z","time spent":"400.614039ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:51:09.511137Z","caller":"traceutil/trace.go:171","msg":"trace[641040754] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"111.71957ms","start":"2026-04-12T18:51:09.399381Z","end":"2026-04-12T18:51:09.5111Z","steps":["trace[641040754] 'process raft request' (duration: 103.115567ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:51:11.037001Z","caller":"traceutil/trace.go:171","msg":"trace[211185351] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"226.804282ms","start":"2026-04-12T18:51:10.810161Z","end":"2026-04-12T18:51:11.036965Z","steps":["trace[211185351] 'process raft request' (duration: 226.621368ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:51:11.145889Z","caller":"traceutil/trace.go:171","msg":"trace[803719141] linearizableReadLoop","detail":"{readStateIndex:8623; appliedIndex:8620; }","duration":"317.966925ms","start":"2026-04-12T18:51:10.827903Z","end":"2026-04-12T18:51:11.145869Z","steps":["trace[803719141] 'read index received' (duration: 207.326588ms)","trace[803719141] 'applied index is now lower than readState.Index' (duration: 110.639177ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:51:11.145919Z","caller":"traceutil/trace.go:171","msg":"trace[752475114] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"334.203751ms","start":"2026-04-12T18:51:10.811682Z","end":"2026-04-12T18:51:11.145885Z","steps":["trace[752475114] 'process raft request' (duration: 293.700281ms)","trace[752475114] 'compare' (duration: 40.312126ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:51:11.146043Z","caller":"traceutil/trace.go:171","msg":"trace[636637841] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"323.264249ms","start":"2026-04-12T18:51:10.822666Z","end":"2026-04-12T18:51:11.14593Z","steps":["trace[636637841] 'process raft request' (duration: 323.141536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:51:11.146188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.273072ms","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-04-12T18:51:11.146251Z","caller":"traceutil/trace.go:171","msg":"trace[1815290180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8332; }","duration":"318.400605ms","start":"2026-04-12T18:51:10.827838Z","end":"2026-04-12T18:51:11.146239Z","steps":["trace[1815290180] 'agreement among raft nodes before linearized reading' (duration: 318.16272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:51:11.146286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:51:10.82781Z","time spent":"318.467456ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T18:51:11.146291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:51:10.822648Z","time spent":"323.498103ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:51:11.146346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.108977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v9v4s\" ","response":"range_response_count:1 size:6451"} {"level":"warn","ts":"2026-04-12T18:51:11.146404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.982147ms","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:7"} {"level":"info","ts":"2026-04-12T18:51:11.146415Z","caller":"traceutil/trace.go:171","msg":"trace[1988452622] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v9v4s; range_end:; response_count:1; response_revision:8332; }","duration":"317.215029ms","start":"2026-04-12T18:51:10.829186Z","end":"2026-04-12T18:51:11.146401Z","steps":["trace[1988452622] 'agreement among raft nodes before linearized reading' (duration: 317.004165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:51:11.146087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:51:10.811657Z","time spent":"334.341963ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:51:11.146457Z","caller":"traceutil/trace.go:171","msg":"trace[986034643] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8332; }","duration":"105.079079ms","start":"2026-04-12T18:51:11.041365Z","end":"2026-04-12T18:51:11.146444Z","steps":["trace[986034643] 'agreement among raft nodes before linearized reading' (duration: 104.941966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:51:11.146463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:51:10.829171Z","time spent":"317.28387ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6475,"request content":"key:\"/registry/pods/openstack/keepalived-v9v4s\" "} {"level":"info","ts":"2026-04-12T18:51:39.713443Z","caller":"traceutil/trace.go:171","msg":"trace[1107784080] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"230.020687ms","start":"2026-04-12T18:51:39.483398Z","end":"2026-04-12T18:51:39.713418Z","steps":["trace[1107784080] 'process raft request' (duration: 229.876644ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:51:39.713648Z","caller":"traceutil/trace.go:171","msg":"trace[1748768165] linearizableReadLoop","detail":"{readStateIndex:8835; appliedIndex:8835; }","duration":"227.668307ms","start":"2026-04-12T18:51:39.485942Z","end":"2026-04-12T18:51:39.713611Z","steps":["trace[1748768165] 'read index received' (duration: 227.655547ms)","trace[1748768165] 'applied index is now lower than readState.Index' (duration: 10.16µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:51:39.713873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.901543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-12T18:51:39.713964Z","caller":"traceutil/trace.go:171","msg":"trace[757076358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8537; }","duration":"228.052486ms","start":"2026-04-12T18:51:39.485897Z","end":"2026-04-12T18:51:39.71395Z","steps":["trace[757076358] 'agreement among raft nodes before linearized reading' (duration: 227.81247ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:51:39.891659Z","caller":"traceutil/trace.go:171","msg":"trace[198895036] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"170.915444ms","start":"2026-04-12T18:51:39.720718Z","end":"2026-04-12T18:51:39.891634Z","steps":["trace[198895036] 'process raft request' (duration: 170.752831ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:51:39.892602Z","caller":"traceutil/trace.go:171","msg":"trace[1219006789] linearizableReadLoop","detail":"{readStateIndex:8837; appliedIndex:8835; }","duration":"152.245028ms","start":"2026-04-12T18:51:39.740336Z","end":"2026-04-12T18:51:39.892581Z","steps":["trace[1219006789] 'read index received' (duration: 151.085294ms)","trace[1219006789] 'applied index is now lower than readState.Index' (duration: 1.158654ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:51:39.89263Z","caller":"traceutil/trace.go:171","msg":"trace[1834953302] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"171.787281ms","start":"2026-04-12T18:51:39.720825Z","end":"2026-04-12T18:51:39.892613Z","steps":["trace[1834953302] 'process raft request' (duration: 171.675069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:51:39.892735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.38165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:51:39.892775Z","caller":"traceutil/trace.go:171","msg":"trace[945283510] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8539; }","duration":"152.464693ms","start":"2026-04-12T18:51:39.740302Z","end":"2026-04-12T18:51:39.892766Z","steps":["trace[945283510] 'agreement among raft nodes before linearized reading' (duration: 152.380101ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:51:52.224847Z","caller":"traceutil/trace.go:171","msg":"trace[1228273726] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"259.430141ms","start":"2026-04-12T18:51:51.965369Z","end":"2026-04-12T18:51:52.224799Z","steps":["trace[1228273726] 'process raft request' (duration: 259.059952ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:51:53.339217Z","caller":"traceutil/trace.go:171","msg":"trace[1783816613] linearizableReadLoop","detail":"{readStateIndex:8957; appliedIndex:8956; }","duration":"162.571617ms","start":"2026-04-12T18:51:53.176623Z","end":"2026-04-12T18:51:53.339195Z","steps":["trace[1783816613] 'read index received' (duration: 135.033814ms)","trace[1783816613] 'applied index is now lower than readState.Index' (duration: 27.536553ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:51:53.339409Z","caller":"traceutil/trace.go:171","msg":"trace[2147373925] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"163.714331ms","start":"2026-04-12T18:51:53.175657Z","end":"2026-04-12T18:51:53.339371Z","steps":["trace[2147373925] 'process raft request' (duration: 135.983024ms)","trace[2147373925] 'compare' (duration: 27.411771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:51:53.339584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.934015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-8nrfr\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-04-12T18:51:53.339676Z","caller":"traceutil/trace.go:171","msg":"trace[1946515549] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-8nrfr; range_end:; response_count:1; response_revision:8654; }","duration":"163.094219ms","start":"2026-04-12T18:51:53.176561Z","end":"2026-04-12T18:51:53.339655Z","steps":["trace[1946515549] 'agreement among raft nodes before linearized reading' (duration: 162.813362ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:51:53.386821Z","caller":"traceutil/trace.go:171","msg":"trace[1726117604] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"196.340773ms","start":"2026-04-12T18:51:53.190457Z","end":"2026-04-12T18:51:53.386798Z","steps":["trace[1726117604] 'process raft request' (duration: 196.21159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:51:53.396988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.575604ms","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-04-12T18:51:53.397107Z","caller":"traceutil/trace.go:171","msg":"trace[441452442] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8657; }","duration":"151.699907ms","start":"2026-04-12T18:51:53.245357Z","end":"2026-04-12T18:51:53.397057Z","steps":["trace[441452442] 'agreement among raft nodes before linearized reading' (duration: 151.498103ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:52:17.471292Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002417.wal"} {"level":"warn","ts":"2026-04-12T18:53:09.900081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.75472ms","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-04-12T18:53:09.9002Z","caller":"traceutil/trace.go:171","msg":"trace[1756025476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9398; }","duration":"102.954263ms","start":"2026-04-12T18:53:09.797222Z","end":"2026-04-12T18:53:09.900176Z","steps":["trace[1756025476] 'range keys from in-memory index tree' (duration: 102.471252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:53:10.223219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.899626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T18:53:10.223268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.430096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T18:53:10.223316Z","caller":"traceutil/trace.go:171","msg":"trace[177026377] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:9399; }","duration":"164.033468ms","start":"2026-04-12T18:53:10.059263Z","end":"2026-04-12T18:53:10.223296Z","steps":["trace[177026377] 'count revisions from in-memory index tree' (duration: 163.747382ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:53:10.223332Z","caller":"traceutil/trace.go:171","msg":"trace[1098623688] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:9399; }","duration":"205.549808ms","start":"2026-04-12T18:53:10.017769Z","end":"2026-04-12T18:53:10.223319Z","steps":["trace[1098623688] 'count revisions from in-memory index tree' (duration: 205.330295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:53:10.223813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.08553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-12T18:53:10.223954Z","caller":"traceutil/trace.go:171","msg":"trace[1109368226] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:9399; }","duration":"173.237203ms","start":"2026-04-12T18:53:10.050654Z","end":"2026-04-12T18:53:10.223892Z","steps":["trace[1109368226] 'range keys from in-memory index tree' (duration: 172.848715ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:53:46.658573Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a11c20306510c1f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-12T18:53:46.663472Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-12T18:53:46.663639Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-12T18:55:56.953653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8259} {"level":"info","ts":"2026-04-12T18:55:57.039572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8259,"took":"83.41813ms","hash":476054755,"current-db-size-bytes":57360384,"current-db-size":"57 MB","current-db-size-in-use-bytes":26050560,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-12T18:55:57.039654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":476054755,"revision":8259,"compact-revision":6052} {"level":"info","ts":"2026-04-12T18:56:35.298967Z","caller":"traceutil/trace.go:171","msg":"trace[37662499] transaction","detail":"{read_only:false; response_revision:11141; number_of_response:1; }","duration":"104.046068ms","start":"2026-04-12T18:56:35.194878Z","end":"2026-04-12T18:56:35.298924Z","steps":["trace[37662499] 'process raft request' (duration: 103.370684ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:56:57.675031Z","caller":"traceutil/trace.go:171","msg":"trace[1401251879] linearizableReadLoop","detail":"{readStateIndex:11643; appliedIndex:11642; }","duration":"102.186028ms","start":"2026-04-12T18:56:57.572816Z","end":"2026-04-12T18:56:57.675002Z","steps":["trace[1401251879] 'read index received' (duration: 102.000165ms)","trace[1401251879] 'applied index is now lower than readState.Index' (duration: 181.053µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:56:57.675076Z","caller":"traceutil/trace.go:171","msg":"trace[2094825814] transaction","detail":"{read_only:false; response_revision:11265; number_of_response:1; }","duration":"239.116415ms","start":"2026-04-12T18:56:57.435926Z","end":"2026-04-12T18:56:57.675042Z","steps":["trace[2094825814] 'process raft request' (duration: 238.896641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:56:57.675246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.285201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:56:57.675306Z","caller":"traceutil/trace.go:171","msg":"trace[2070520420] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11265; }","duration":"102.383432ms","start":"2026-04-12T18:56:57.572912Z","end":"2026-04-12T18:56:57.675295Z","steps":["trace[2070520420] 'agreement among raft nodes before linearized reading' (duration: 102.27488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:56:57.67535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.317189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T18:56:57.675307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.434863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:56:57.67541Z","caller":"traceutil/trace.go:171","msg":"trace[1378873187] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11265; }","duration":"101.436012ms","start":"2026-04-12T18:56:57.573963Z","end":"2026-04-12T18:56:57.675399Z","steps":["trace[1378873187] 'agreement among raft nodes before linearized reading' (duration: 101.318619ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:56:57.675447Z","caller":"traceutil/trace.go:171","msg":"trace[1815400037] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11265; }","duration":"102.632778ms","start":"2026-04-12T18:56:57.572786Z","end":"2026-04-12T18:56:57.675418Z","steps":["trace[1815400037] 'agreement among raft nodes before linearized reading' (duration: 102.373893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:56:57.675525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.579275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:56:57.675603Z","caller":"traceutil/trace.go:171","msg":"trace[1612238839] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11265; }","duration":"101.659186ms","start":"2026-04-12T18:56:57.573922Z","end":"2026-04-12T18:56:57.675581Z","steps":["trace[1612238839] 'agreement among raft nodes before linearized reading' (duration: 101.543284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:56:57.675695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.028796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-04-12T18:56:57.675745Z","caller":"traceutil/trace.go:171","msg":"trace[1656078923] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11265; }","duration":"102.099387ms","start":"2026-04-12T18:56:57.573634Z","end":"2026-04-12T18:56:57.675733Z","steps":["trace[1656078923] 'agreement among raft nodes before linearized reading' (duration: 101.969524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:56:57.676171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.178198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-04-12T18:56:57.67622Z","caller":"traceutil/trace.go:171","msg":"trace[235733402] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11265; }","duration":"102.28083ms","start":"2026-04-12T18:56:57.573929Z","end":"2026-04-12T18:56:57.676209Z","steps":["trace[235733402] 'agreement among raft nodes before linearized reading' (duration: 101.712108ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:00:56.959518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10738} {"level":"info","ts":"2026-04-12T19:00:57.049829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10738,"took":"86.953004ms","hash":607435396,"current-db-size-bytes":57360384,"current-db-size":"57 MB","current-db-size-in-use-bytes":27119616,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-12T19:00:57.049895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":607435396,"revision":10738,"compact-revision":8259} {"level":"info","ts":"2026-04-12T19:03:09.718512Z","caller":"traceutil/trace.go:171","msg":"trace[1905105108] linearizableReadLoop","detail":"{readStateIndex:14243; appliedIndex:14242; }","duration":"122.056494ms","start":"2026-04-12T19:03:09.596431Z","end":"2026-04-12T19:03:09.718488Z","steps":["trace[1905105108] 'read index received' (duration: 121.83735ms)","trace[1905105108] 'applied index is now lower than readState.Index' (duration: 218.004µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:03:09.71857Z","caller":"traceutil/trace.go:171","msg":"trace[584582785] transaction","detail":"{read_only:false; response_revision:13778; number_of_response:1; }","duration":"124.897704ms","start":"2026-04-12T19:03:09.593632Z","end":"2026-04-12T19:03:09.71853Z","steps":["trace[584582785] 'process raft request' (duration: 124.70524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:03:09.718753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.538564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-12T19:03:09.718801Z","caller":"traceutil/trace.go:171","msg":"trace[434771789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13778; }","duration":"121.622655ms","start":"2026-04-12T19:03:09.597166Z","end":"2026-04-12T19:03:09.718788Z","steps":["trace[434771789] 'agreement among raft nodes before linearized reading' (duration: 121.464212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:03:09.718815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.35011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-12T19:03:09.718904Z","caller":"traceutil/trace.go:171","msg":"trace[37236557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13778; }","duration":"122.479504ms","start":"2026-04-12T19:03:09.596406Z","end":"2026-04-12T19:03:09.718886Z","steps":["trace[37236557] 'agreement among raft nodes before linearized reading' (duration: 122.205768ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:03:09.835627Z","caller":"traceutil/trace.go:171","msg":"trace[1307050331] transaction","detail":"{read_only:false; response_revision:13779; number_of_response:1; }","duration":"113.044324ms","start":"2026-04-12T19:03:09.722566Z","end":"2026-04-12T19:03:09.83561Z","steps":["trace[1307050331] 'process raft request' (duration: 112.885381ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:03:09.836718Z","caller":"traceutil/trace.go:171","msg":"trace[57383826] transaction","detail":"{read_only:false; response_revision:13780; number_of_response:1; }","duration":"113.463584ms","start":"2026-04-12T19:03:09.723246Z","end":"2026-04-12T19:03:09.836709Z","steps":["trace[57383826] 'process raft request' (duration: 113.28896ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:03:09.836775Z","caller":"traceutil/trace.go:171","msg":"trace[1525812463] transaction","detail":"{read_only:false; response_revision:13781; number_of_response:1; }","duration":"112.153005ms","start":"2026-04-12T19:03:09.724588Z","end":"2026-04-12T19:03:09.836741Z","steps":["trace[1525812463] 'process raft request' (duration: 112.067264ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:03:17.888986Z","caller":"traceutil/trace.go:171","msg":"trace[464772990] linearizableReadLoop","detail":"{readStateIndex:14286; appliedIndex:14285; }","duration":"106.871773ms","start":"2026-04-12T19:03:17.782066Z","end":"2026-04-12T19:03:17.888938Z","steps":["trace[464772990] 'read index received' (duration: 106.646658ms)","trace[464772990] 'applied index is now lower than readState.Index' (duration: 223.475µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:03:17.889353Z","caller":"traceutil/trace.go:171","msg":"trace[1015272345] transaction","detail":"{read_only:false; response_revision:13819; number_of_response:1; }","duration":"130.179256ms","start":"2026-04-12T19:03:17.759152Z","end":"2026-04-12T19:03:17.889332Z","steps":["trace[1015272345] 'process raft request' (duration: 129.595044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:03:17.889343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.267511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T19:03:17.889443Z","caller":"traceutil/trace.go:171","msg":"trace[1857955660] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13819; }","duration":"107.411484ms","start":"2026-04-12T19:03:17.782006Z","end":"2026-04-12T19:03:17.889418Z","steps":["trace[1857955660] 'agreement among raft nodes before linearized reading' (duration: 107.259231ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:03:26.201473Z","caller":"traceutil/trace.go:171","msg":"trace[2062303287] transaction","detail":"{read_only:false; response_revision:13876; number_of_response:1; }","duration":"269.057959ms","start":"2026-04-12T19:03:25.932381Z","end":"2026-04-12T19:03:26.201439Z","steps":["trace[2062303287] 'process raft request' (duration: 268.880825ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:03:26.201504Z","caller":"traceutil/trace.go:171","msg":"trace[2129291045] linearizableReadLoop","detail":"{readStateIndex:14346; appliedIndex:14346; }","duration":"268.475577ms","start":"2026-04-12T19:03:25.932997Z","end":"2026-04-12T19:03:26.201472Z","steps":["trace[2129291045] 'read index received' (duration: 268.462896ms)","trace[2129291045] 'applied index is now lower than readState.Index' (duration: 9.63µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:03:26.201796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.764422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-12T19:03:26.201841Z","caller":"traceutil/trace.go:171","msg":"trace[101190364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13876; }","duration":"268.855844ms","start":"2026-04-12T19:03:25.932974Z","end":"2026-04-12T19:03:26.20183Z","steps":["trace[101190364] 'agreement among raft nodes before linearized reading' (duration: 268.642149ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:03:26.223891Z","caller":"traceutil/trace.go:171","msg":"trace[592168981] transaction","detail":"{read_only:false; response_revision:13877; number_of_response:1; }","duration":"289.972411ms","start":"2026-04-12T19:03:25.933896Z","end":"2026-04-12T19:03:26.223868Z","steps":["trace[592168981] 'process raft request' (duration: 289.706175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:03:26.224236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.756367ms","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-04-12T19:03:26.224308Z","caller":"traceutil/trace.go:171","msg":"trace[708859519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13880; }","duration":"290.849579ms","start":"2026-04-12T19:03:25.933443Z","end":"2026-04-12T19:03:26.224293Z","steps":["trace[708859519] 'agreement among raft nodes before linearized reading' (duration: 290.650375ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:05:03.974544Z","caller":"traceutil/trace.go:171","msg":"trace[1856812648] transaction","detail":"{read_only:false; response_revision:14955; number_of_response:1; }","duration":"132.968276ms","start":"2026-04-12T19:05:03.841548Z","end":"2026-04-12T19:05:03.974516Z","steps":["trace[1856812648] 'process raft request' (duration: 132.729021ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:05:03.974839Z","caller":"traceutil/trace.go:171","msg":"trace[1978636218] linearizableReadLoop","detail":"{readStateIndex:15458; appliedIndex:15458; }","duration":"106.504636ms","start":"2026-04-12T19:05:03.868302Z","end":"2026-04-12T19:05:03.974807Z","steps":["trace[1978636218] 'read index received' (duration: 106.492625ms)","trace[1978636218] 'applied index is now lower than readState.Index' (duration: 9.921µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:05:03.975086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.754911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v9v4s\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-12T19:05:03.975184Z","caller":"traceutil/trace.go:171","msg":"trace[964883876] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v9v4s; range_end:; response_count:1; response_revision:14955; }","duration":"106.889814ms","start":"2026-04-12T19:05:03.868272Z","end":"2026-04-12T19:05:03.975162Z","steps":["trace[964883876] 'agreement among raft nodes before linearized reading' (duration: 106.627169ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:05:56.966042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12816} {"level":"info","ts":"2026-04-12T19:05:57.04803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12816,"took":"77.288047ms","hash":3947954805,"current-db-size-bytes":57360384,"current-db-size":"57 MB","current-db-size-in-use-bytes":30531584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-12T19:05:57.048156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3947954805,"revision":12816,"compact-revision":10738} {"level":"info","ts":"2026-04-12T19:10:56.972237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15311} {"level":"info","ts":"2026-04-12T19:10:57.073481Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15311,"took":"97.441623ms","hash":3384520573,"current-db-size-bytes":57360384,"current-db-size":"57 MB","current-db-size-in-use-bytes":31617024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-12T19:10:57.073532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3384520573,"revision":15311,"compact-revision":12816} {"level":"warn","ts":"2026-04-12T19:11:04.845054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.805564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:11:04.845161Z","caller":"traceutil/trace.go:171","msg":"trace[490718096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17626; }","duration":"150.981517ms","start":"2026-04-12T19:11:04.69416Z","end":"2026-04-12T19:11:04.845141Z","steps":["trace[490718096] 'range keys from in-memory index tree' (duration: 150.679891ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:11:05.380074Z","caller":"traceutil/trace.go:171","msg":"trace[1639303067] transaction","detail":"{read_only:false; response_revision:17627; number_of_response:1; }","duration":"227.150161ms","start":"2026-04-12T19:11:05.152881Z","end":"2026-04-12T19:11:05.380031Z","steps":["trace[1639303067] 'process raft request' (duration: 226.870954ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:11:05.752012Z","caller":"traceutil/trace.go:171","msg":"trace[1246242092] transaction","detail":"{read_only:false; response_revision:17628; number_of_response:1; }","duration":"433.750405ms","start":"2026-04-12T19:11:05.318238Z","end":"2026-04-12T19:11:05.751988Z","steps":["trace[1246242092] 'process raft request' (duration: 433.524101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:11:05.752155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:11:05.318217Z","time spent":"433.861447ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:14:34.700645Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a11c20306510c1f7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-12T19:14:34.705767Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-12T19:14:34.705897Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-12T19:15:56.980487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17554} {"level":"info","ts":"2026-04-12T19:15:57.080385Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17554,"took":"96.957353ms","hash":1932390589,"current-db-size-bytes":57360384,"current-db-size":"57 MB","current-db-size-in-use-bytes":33959936,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-12T19:15:57.081311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1932390589,"revision":17554,"compact-revision":15311} {"level":"warn","ts":"2026-04-12T19:16:44.532958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.246253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-12T19:16:44.533076Z","caller":"traceutil/trace.go:171","msg":"trace[392708367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20277; }","duration":"167.391846ms","start":"2026-04-12T19:16:44.365664Z","end":"2026-04-12T19:16:44.533056Z","steps":["trace[392708367] 'range keys from in-memory index tree' (duration: 167.041478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:16:44.533418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.445539ms","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-04-12T19:16:44.533507Z","caller":"traceutil/trace.go:171","msg":"trace[1873770573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20277; }","duration":"160.580383ms","start":"2026-04-12T19:16:44.372906Z","end":"2026-04-12T19:16:44.533486Z","steps":["trace[1873770573] 'range keys from in-memory index tree' (duration: 160.239424ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:16:44.651531Z","caller":"traceutil/trace.go:171","msg":"trace[2048934186] transaction","detail":"{read_only:false; response_revision:20278; number_of_response:1; }","duration":"114.531967ms","start":"2026-04-12T19:16:44.536972Z","end":"2026-04-12T19:16:44.651503Z","steps":["trace[2048934186] 'process raft request' (duration: 114.367533ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:16:44.66788Z","caller":"traceutil/trace.go:171","msg":"trace[1941012554] transaction","detail":"{read_only:false; response_revision:20279; number_of_response:1; }","duration":"130.299841ms","start":"2026-04-12T19:16:44.53755Z","end":"2026-04-12T19:16:44.66785Z","steps":["trace[1941012554] 'process raft request' (duration: 130.143147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:16:44.954588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.419594ms","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-04-12T19:16:44.954676Z","caller":"traceutil/trace.go:171","msg":"trace[1724043340] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20279; }","duration":"113.600647ms","start":"2026-04-12T19:16:44.841055Z","end":"2026-04-12T19:16:44.954656Z","steps":["trace[1724043340] 'count revisions from in-memory index tree' (duration: 113.327931ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:16:45.240023Z","caller":"traceutil/trace.go:171","msg":"trace[1915243449] linearizableReadLoop","detail":"{readStateIndex:20959; appliedIndex:20958; }","duration":"116.615461ms","start":"2026-04-12T19:16:45.12338Z","end":"2026-04-12T19:16:45.239996Z","steps":["trace[1915243449] 'read index received' (duration: 116.56423ms)","trace[1915243449] 'applied index is now lower than readState.Index' (duration: 49.681µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:16:45.24011Z","caller":"traceutil/trace.go:171","msg":"trace[927665678] transaction","detail":"{read_only:false; response_revision:20280; number_of_response:1; }","duration":"132.573559ms","start":"2026-04-12T19:16:45.107499Z","end":"2026-04-12T19:16:45.240073Z","steps":["trace[927665678] 'process raft request' (duration: 132.313374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:16:45.240305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.852715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:16:45.240434Z","caller":"traceutil/trace.go:171","msg":"trace[1043193309] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:20280; }","duration":"117.04585ms","start":"2026-04-12T19:16:45.123326Z","end":"2026-04-12T19:16:45.240372Z","steps":["trace[1043193309] 'agreement among raft nodes before linearized reading' (duration: 116.770844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:16:45.538208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.248789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:16:45.538415Z","caller":"traceutil/trace.go:171","msg":"trace[594975742] transaction","detail":"{read_only:false; response_revision:20283; number_of_response:1; }","duration":"328.228533ms","start":"2026-04-12T19:16:45.210169Z","end":"2026-04-12T19:16:45.538397Z","steps":["trace[594975742] 'process raft request' (duration: 328.179472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:16:45.538479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:16:45.210136Z","time spent":"328.311105ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:16:45.538551Z","caller":"traceutil/trace.go:171","msg":"trace[153694662] transaction","detail":"{read_only:false; response_revision:20281; number_of_response:1; }","duration":"363.796338ms","start":"2026-04-12T19:16:45.174744Z","end":"2026-04-12T19:16:45.538541Z","steps":["trace[153694662] 'process raft request' (duration: 241.12998ms)","trace[153694662] 'compare' (duration: 121.960924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:16:45.538596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:16:45.174724Z","time spent":"363.847269ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:16:45.538687Z","caller":"traceutil/trace.go:171","msg":"trace[643016296] transaction","detail":"{read_only:false; response_revision:20282; number_of_response:1; }","duration":"362.878958ms","start":"2026-04-12T19:16:45.1758Z","end":"2026-04-12T19:16:45.538678Z","steps":["trace[643016296] 'process raft request' (duration: 362.49443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:16:45.538747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:16:45.175777Z","time spent":"362.941949ms","remote":"127.0.0.1:54152","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-04-12T19:16:45.545032Z","caller":"traceutil/trace.go:171","msg":"trace[241266887] transaction","detail":"{read_only:false; response_revision:20284; number_of_response:1; }","duration":"100.04921ms","start":"2026-04-12T19:16:45.444963Z","end":"2026-04-12T19:16:45.545012Z","steps":["trace[241266887] 'process raft request' (duration: 99.952988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:16:45.780161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.304456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:16:45.780443Z","caller":"traceutil/trace.go:171","msg":"trace[1008994383] transaction","detail":"{read_only:false; response_revision:20285; number_of_response:1; }","duration":"220.774608ms","start":"2026-04-12T19:16:45.559599Z","end":"2026-04-12T19:16:45.780374Z","steps":["trace[1008994383] 'process raft request' (duration: 86.147585ms)","trace[1008994383] 'compare' (duration: 134.034019ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:16:45.930573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.286194ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-12T19:16:45.930686Z","caller":"traceutil/trace.go:171","msg":"trace[2113752923] linearizableReadLoop","detail":"{readStateIndex:20965; appliedIndex:20963; }","duration":"330.487652ms","start":"2026-04-12T19:16:45.600176Z","end":"2026-04-12T19:16:45.930664Z","steps":["trace[2113752923] 'read index received' (duration: 45.590617ms)","trace[2113752923] 'applied index is now lower than readState.Index' (duration: 284.895355ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:16:45.930927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.734837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:16:45.930923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.636593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:16:45.931013Z","caller":"traceutil/trace.go:171","msg":"trace[1126012975] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:20285; }","duration":"330.88548ms","start":"2026-04-12T19:16:45.600105Z","end":"2026-04-12T19:16:45.930991Z","steps":["trace[1126012975] 'agreement among raft nodes before linearized reading' (duration: 330.722886ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:16:45.931054Z","caller":"traceutil/trace.go:171","msg":"trace[192072106] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:20285; }","duration":"237.085422ms","start":"2026-04-12T19:16:45.693955Z","end":"2026-04-12T19:16:45.93104Z","steps":["trace[192072106] 'agreement among raft nodes before linearized reading' (duration: 236.913868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:16:45.931071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:16:45.600074Z","time spent":"330.980352ms","remote":"127.0.0.1:34074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-12T19:20:39.790911Z","caller":"traceutil/trace.go:171","msg":"trace[1029399763] transaction","detail":"{read_only:false; response_revision:22696; number_of_response:1; }","duration":"232.390814ms","start":"2026-04-12T19:20:39.558496Z","end":"2026-04-12T19:20:39.790887Z","steps":["trace[1029399763] 'process raft request' (duration: 232.20309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:20:40.068429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.231924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:20:40.068745Z","caller":"traceutil/trace.go:171","msg":"trace[1377206684] linearizableReadLoop","detail":"{readStateIndex:23449; appliedIndex:23448; }","duration":"277.874717ms","start":"2026-04-12T19:20:39.790845Z","end":"2026-04-12T19:20:40.068719Z","steps":["trace[1377206684] 'read index received' (duration: 24.136892ms)","trace[1377206684] 'applied index is now lower than readState.Index' (duration: 253.736065ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:20:40.069093Z","caller":"traceutil/trace.go:171","msg":"trace[248715044] transaction","detail":"{read_only:false; response_revision:22698; number_of_response:1; }","duration":"119.211655ms","start":"2026-04-12T19:20:39.949497Z","end":"2026-04-12T19:20:40.068709Z","steps":["trace[248715044] 'process raft request' (duration: 119.088952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:20:40.069107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.270212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:20:40.069202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.883379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-04-12T19:20:40.069228Z","caller":"traceutil/trace.go:171","msg":"trace[1272831674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22698; }","duration":"276.477717ms","start":"2026-04-12T19:20:39.792721Z","end":"2026-04-12T19:20:40.069199Z","steps":["trace[1272831674] 'agreement among raft nodes before linearized reading' (duration: 276.244902ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:20:40.069277Z","caller":"traceutil/trace.go:171","msg":"trace[631366203] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:22698; }","duration":"313.00412ms","start":"2026-04-12T19:20:39.756255Z","end":"2026-04-12T19:20:40.069259Z","steps":["trace[631366203] 'agreement among raft nodes before linearized reading' (duration: 312.692524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:20:40.069328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:20:39.756233Z","time spent":"313.079692ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":586,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-12T19:20:40.069317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.257505ms","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-04-12T19:20:40.069424Z","caller":"traceutil/trace.go:171","msg":"trace[1472799003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22698; }","duration":"118.365588ms","start":"2026-04-12T19:20:39.951013Z","end":"2026-04-12T19:20:40.069379Z","steps":["trace[1472799003] 'agreement among raft nodes before linearized reading' (duration: 117.971099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:20:40.069768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.045482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-12T19:20:40.071652Z","caller":"traceutil/trace.go:171","msg":"trace[134156181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22698; }","duration":"120.010873ms","start":"2026-04-12T19:20:39.951617Z","end":"2026-04-12T19:20:40.071628Z","steps":["trace[134156181] 'agreement among raft nodes before linearized reading' (duration: 117.344316ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:20:40.068813Z","caller":"traceutil/trace.go:171","msg":"trace[1635926738] transaction","detail":"{read_only:false; response_revision:22697; number_of_response:1; }","duration":"294.456109ms","start":"2026-04-12T19:20:39.774251Z","end":"2026-04-12T19:20:40.068707Z","steps":["trace[1635926738] 'process raft request' (duration: 40.823486ms)","trace[1635926738] 'compare' (duration: 253.051241ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:20:56.986839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20029} {"level":"info","ts":"2026-04-12T19:20:57.098157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20029,"took":"107.712792ms","hash":3528277276,"current-db-size-bytes":57360384,"current-db-size":"57 MB","current-db-size-in-use-bytes":40288256,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-04-12T19:20:57.098234Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3528277276,"revision":20029,"compact-revision":17554} {"level":"info","ts":"2026-04-12T19:24:21.390921Z","caller":"traceutil/trace.go:171","msg":"trace[202787682] linearizableReadLoop","detail":"{readStateIndex:25703; appliedIndex:25702; }","duration":"311.292269ms","start":"2026-04-12T19:24:21.079602Z","end":"2026-04-12T19:24:21.390894Z","steps":["trace[202787682] 'read index received' (duration: 311.103575ms)","trace[202787682] 'applied index is now lower than readState.Index' (duration: 187.544µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:24:21.390987Z","caller":"traceutil/trace.go:171","msg":"trace[1721801233] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"320.258519ms","start":"2026-04-12T19:24:21.070696Z","end":"2026-04-12T19:24:21.390955Z","steps":["trace[1721801233] 'process raft request' (duration: 319.938483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:24:21.39118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.564606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-12T19:24:21.391197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.070669Z","time spent":"320.393132ms","remote":"127.0.0.1:54152","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-04-12T19:24:21.391229Z","caller":"traceutil/trace.go:171","msg":"trace[1530600084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24893; }","duration":"311.658687ms","start":"2026-04-12T19:24:21.079556Z","end":"2026-04-12T19:24:21.391215Z","steps":["trace[1530600084] 'agreement among raft nodes before linearized reading' (duration: 311.501134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:24:21.391266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.079538Z","time spent":"311.719989ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-12T19:24:21.634271Z","caller":"traceutil/trace.go:171","msg":"trace[953695369] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"369.277069ms","start":"2026-04-12T19:24:21.264962Z","end":"2026-04-12T19:24:21.634239Z","steps":["trace[953695369] 'process raft request' (duration: 369.177507ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:24:21.634296Z","caller":"traceutil/trace.go:171","msg":"trace[426170344] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"371.357423ms","start":"2026-04-12T19:24:21.262903Z","end":"2026-04-12T19:24:21.634261Z","steps":["trace[426170344] 'process raft request' (duration: 371.186209ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:24:21.634269Z","caller":"traceutil/trace.go:171","msg":"trace[1716716664] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"522.532168ms","start":"2026-04-12T19:24:21.111674Z","end":"2026-04-12T19:24:21.634207Z","steps":["trace[1716716664] 'process raft request' (duration: 512.884253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:24:21.634462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.264923Z","time spent":"369.441102ms","remote":"127.0.0.1:54152","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-04-12T19:24:21.634484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.262872Z","time spent":"371.514195ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T19:24:21.634589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.11165Z","time spent":"522.819563ms","remote":"127.0.0.1:54152","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-04-12T19:24:21.634872Z","caller":"traceutil/trace.go:171","msg":"trace[1673012210] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"347.777962ms","start":"2026-04-12T19:24:21.287053Z","end":"2026-04-12T19:24:21.634831Z","steps":["trace[1673012210] 'process raft request' (duration: 347.451176ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:24:21.634876Z","caller":"traceutil/trace.go:171","msg":"trace[311065909] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"350.148993ms","start":"2026-04-12T19:24:21.284654Z","end":"2026-04-12T19:24:21.634803Z","steps":["trace[311065909] 'process raft request' (duration: 349.52587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:24:21.635042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.287038Z","time spent":"347.911226ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T19:24:21.635148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.28461Z","time spent":"350.393578ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:24:21.63529Z","caller":"traceutil/trace.go:171","msg":"trace[1787748560] linearizableReadLoop","detail":"{readStateIndex:25708; appliedIndex:25703; }","duration":"244.255968ms","start":"2026-04-12T19:24:21.391017Z","end":"2026-04-12T19:24:21.635273Z","steps":["trace[1787748560] 'read index received' (duration: 233.628873ms)","trace[1787748560] 'applied index is now lower than readState.Index' (duration: 10.625755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:24:21.635484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.288008ms","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-04-12T19:24:21.635548Z","caller":"traceutil/trace.go:171","msg":"trace[1900429720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24898; }","duration":"367.367679ms","start":"2026-04-12T19:24:21.268152Z","end":"2026-04-12T19:24:21.635519Z","steps":["trace[1900429720] 'agreement among raft nodes before linearized reading' (duration: 367.170684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:24:21.635588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.898911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-12T19:24:21.635635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.26814Z","time spent":"367.478322ms","remote":"127.0.0.1:54152","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":"info","ts":"2026-04-12T19:24:21.63564Z","caller":"traceutil/trace.go:171","msg":"trace[744642142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24898; }","duration":"368.992883ms","start":"2026-04-12T19:24:21.266634Z","end":"2026-04-12T19:24:21.635627Z","steps":["trace[744642142] 'agreement among raft nodes before linearized reading' (duration: 368.728966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:24:21.63568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.266612Z","time spent":"369.059804ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T19:24:21.635686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.867765ms","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-04-12T19:24:21.635765Z","caller":"traceutil/trace.go:171","msg":"trace[1210569631] 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:24898; }","duration":"546.955616ms","start":"2026-04-12T19:24:21.088787Z","end":"2026-04-12T19:24:21.635742Z","steps":["trace[1210569631] 'agreement among raft nodes before linearized reading' (duration: 546.729322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:24:21.635817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:24:21.08876Z","time spent":"547.046318ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-12T19:24:21.662733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.450593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:24:21.662726Z","caller":"traceutil/trace.go:171","msg":"trace[1872852154] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"266.232014ms","start":"2026-04-12T19:24:21.396457Z","end":"2026-04-12T19:24:21.662689Z","steps":["trace[1872852154] 'process raft request' (duration: 266.04513ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:24:21.6628Z","caller":"traceutil/trace.go:171","msg":"trace[1010041962] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:24899; }","duration":"270.554745ms","start":"2026-04-12T19:24:21.392229Z","end":"2026-04-12T19:24:21.662783Z","steps":["trace[1010041962] 'agreement among raft nodes before linearized reading' (duration: 270.388152ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:24:42.956705Z","caller":"traceutil/trace.go:171","msg":"trace[1291504011] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"131.163961ms","start":"2026-04-12T19:24:42.825505Z","end":"2026-04-12T19:24:42.956669Z","steps":["trace[1291504011] 'process raft request' (duration: 64.479367ms)","trace[1291504011] 'compare' (duration: 66.53154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:25:18.040365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.068536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:25:18.040721Z","caller":"traceutil/trace.go:171","msg":"trace[1829254536] linearizableReadLoop","detail":"{readStateIndex:26288; appliedIndex:26287; }","duration":"109.748466ms","start":"2026-04-12T19:25:17.930953Z","end":"2026-04-12T19:25:18.040702Z","steps":["trace[1829254536] 'read index received' (duration: 5.17335ms)","trace[1829254536] 'applied index is now lower than readState.Index' (duration: 104.573586ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:25:18.040826Z","caller":"traceutil/trace.go:171","msg":"trace[13837259] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"124.063649ms","start":"2026-04-12T19:25:17.916681Z","end":"2026-04-12T19:25:18.040745Z","steps":["trace[13837259] 'process raft request' (duration: 19.514613ms)","trace[13837259] 'compare' (duration: 103.837812ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:25:18.041241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.280108ms","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":"info","ts":"2026-04-12T19:25:18.041333Z","caller":"traceutil/trace.go:171","msg":"trace[1632047253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25466; }","duration":"110.423381ms","start":"2026-04-12T19:25:17.930886Z","end":"2026-04-12T19:25:18.041309Z","steps":["trace[1632047253] 'agreement among raft nodes before linearized reading' (duration: 109.95097ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:25:18.198708Z","caller":"traceutil/trace.go:171","msg":"trace[2017430732] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"154.048246ms","start":"2026-04-12T19:25:18.044614Z","end":"2026-04-12T19:25:18.198662Z","steps":["trace[2017430732] 'process raft request' (duration: 121.909324ms)","trace[2017430732] 'compare' (duration: 31.948828ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:25:18.23351Z","caller":"traceutil/trace.go:171","msg":"trace[376014184] linearizableReadLoop","detail":"{readStateIndex:26291; appliedIndex:26288; }","duration":"169.074514ms","start":"2026-04-12T19:25:18.064404Z","end":"2026-04-12T19:25:18.233478Z","steps":["trace[376014184] 'read index received' (duration: 102.110964ms)","trace[376014184] 'applied index is now lower than readState.Index' (duration: 66.96192ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:25:18.233538Z","caller":"traceutil/trace.go:171","msg":"trace[1963117281] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"188.776902ms","start":"2026-04-12T19:25:18.044728Z","end":"2026-04-12T19:25:18.233505Z","steps":["trace[1963117281] 'process raft request' (duration: 188.483356ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:25:18.233659Z","caller":"traceutil/trace.go:171","msg":"trace[2125669882] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"187.675289ms","start":"2026-04-12T19:25:18.045955Z","end":"2026-04-12T19:25:18.23363Z","steps":["trace[2125669882] 'process raft request' (duration: 187.463894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:25:18.233768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.326419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:25:18.233829Z","caller":"traceutil/trace.go:171","msg":"trace[95206060] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25469; }","duration":"169.466642ms","start":"2026-04-12T19:25:18.064345Z","end":"2026-04-12T19:25:18.233812Z","steps":["trace[95206060] 'agreement among raft nodes before linearized reading' (duration: 169.303438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:25:18.233897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.066647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:25:18.234Z","caller":"traceutil/trace.go:171","msg":"trace[800211279] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25469; }","duration":"122.259061ms","start":"2026-04-12T19:25:18.111714Z","end":"2026-04-12T19:25:18.233973Z","steps":["trace[800211279] 'agreement among raft nodes before linearized reading' (duration: 122.032866ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:25:28.622855Z","caller":"traceutil/trace.go:171","msg":"trace[290726566] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"138.68291ms","start":"2026-04-12T19:25:28.48415Z","end":"2026-04-12T19:25:28.622833Z","steps":["trace[290726566] 'process raft request' (duration: 137.954895ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:25:29.031034Z","caller":"traceutil/trace.go:171","msg":"trace[1893051843] linearizableReadLoop","detail":"{readStateIndex:26372; appliedIndex:26371; }","duration":"155.821544ms","start":"2026-04-12T19:25:28.875181Z","end":"2026-04-12T19:25:29.031002Z","steps":["trace[1893051843] 'read index received' (duration: 155.594489ms)","trace[1893051843] 'applied index is now lower than readState.Index' (duration: 226.185µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:25:29.031268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.075519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-04-12T19:25:29.03131Z","caller":"traceutil/trace.go:171","msg":"trace[203451633] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25548; }","duration":"156.178691ms","start":"2026-04-12T19:25:28.87512Z","end":"2026-04-12T19:25:29.031299Z","steps":["trace[203451633] 'agreement among raft nodes before linearized reading' (duration: 155.987447ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:25:29.031627Z","caller":"traceutil/trace.go:171","msg":"trace[474455422] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"227.168266ms","start":"2026-04-12T19:25:28.804447Z","end":"2026-04-12T19:25:29.031616Z","steps":["trace[474455422] 'process raft request' (duration: 226.41412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:25:29.286901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.225752ms","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-04-12T19:25:29.286971Z","caller":"traceutil/trace.go:171","msg":"trace[1713023109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25548; }","duration":"106.335134ms","start":"2026-04-12T19:25:29.180619Z","end":"2026-04-12T19:25:29.286954Z","steps":["trace[1713023109] 'range keys from in-memory index tree' (duration: 106.048229ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:25:52.894803Z","caller":"traceutil/trace.go:171","msg":"trace[1633865212] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"155.116379ms","start":"2026-04-12T19:25:52.73966Z","end":"2026-04-12T19:25:52.894776Z","steps":["trace[1633865212] 'process raft request' (duration: 154.942855ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:25:56.992208Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22863} {"level":"info","ts":"2026-04-12T19:25:57.124395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22863,"took":"127.972204ms","hash":2145858727,"current-db-size-bytes":58626048,"current-db-size":"59 MB","current-db-size-in-use-bytes":35393536,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-12T19:25:57.124451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2145858727,"revision":22863,"compact-revision":20029} {"level":"info","ts":"2026-04-12T19:26:35.220982Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069ac.wal"} {"level":"info","ts":"2026-04-12T19:26:36.447685Z","caller":"traceutil/trace.go:171","msg":"trace[1299465546] linearizableReadLoop","detail":"{readStateIndex:27058; appliedIndex:27057; }","duration":"269.066894ms","start":"2026-04-12T19:26:36.178584Z","end":"2026-04-12T19:26:36.447651Z","steps":["trace[1299465546] 'read index received' (duration: 193.644675ms)","trace[1299465546] 'applied index is now lower than readState.Index' (duration: 75.421079ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:26:36.44793Z","caller":"traceutil/trace.go:171","msg":"trace[659393353] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"278.148216ms","start":"2026-04-12T19:26:36.16974Z","end":"2026-04-12T19:26:36.447889Z","steps":["trace[659393353] 'process raft request' (duration: 202.476922ms)","trace[659393353] 'compare' (duration: 75.295506ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:26:36.448132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.518013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:26:36.448241Z","caller":"traceutil/trace.go:171","msg":"trace[1302726374] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26217; }","duration":"270.720399ms","start":"2026-04-12T19:26:36.177497Z","end":"2026-04-12T19:26:36.448217Z","steps":["trace[1302726374] 'agreement among raft nodes before linearized reading' (duration: 270.527395ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:26:36.468248Z","caller":"traceutil/trace.go:171","msg":"trace[1303133099] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"275.403269ms","start":"2026-04-12T19:26:36.19282Z","end":"2026-04-12T19:26:36.468223Z","steps":["trace[1303133099] 'process raft request' (duration: 275.324387ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:26:36.468263Z","caller":"traceutil/trace.go:171","msg":"trace[1262122280] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"276.133684ms","start":"2026-04-12T19:26:36.192099Z","end":"2026-04-12T19:26:36.468233Z","steps":["trace[1262122280] 'process raft request' (duration: 275.9895ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:26:36.468261Z","caller":"traceutil/trace.go:171","msg":"trace[260217239] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"282.249774ms","start":"2026-04-12T19:26:36.185965Z","end":"2026-04-12T19:26:36.468215Z","steps":["trace[260217239] 'process raft request' (duration: 281.981267ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:26:36.468478Z","caller":"traceutil/trace.go:171","msg":"trace[110448560] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"150.017401ms","start":"2026-04-12T19:26:36.318449Z","end":"2026-04-12T19:26:36.468466Z","steps":["trace[110448560] 'process raft request' (duration: 149.733135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:26:36.468717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.182225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-04-12T19:26:36.468767Z","caller":"traceutil/trace.go:171","msg":"trace[1658971618] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26221; }","duration":"200.300027ms","start":"2026-04-12T19:26:36.268455Z","end":"2026-04-12T19:26:36.468755Z","steps":["trace[1658971618] 'agreement among raft nodes before linearized reading' (duration: 200.121663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:07.513802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.952081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-12T19:27:07.513986Z","caller":"traceutil/trace.go:171","msg":"trace[878677035] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:26450; }","duration":"160.207276ms","start":"2026-04-12T19:27:07.353758Z","end":"2026-04-12T19:27:07.513965Z","steps":["trace[878677035] 'count revisions from in-memory index tree' (duration: 159.851128ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:07.585325Z","caller":"traceutil/trace.go:171","msg":"trace[36335600] transaction","detail":"{read_only:false; response_revision:26451; number_of_response:1; }","duration":"115.979708ms","start":"2026-04-12T19:27:07.469293Z","end":"2026-04-12T19:27:07.585273Z","steps":["trace[36335600] 'process raft request' (duration: 115.765104ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:08.267217Z","caller":"traceutil/trace.go:171","msg":"trace[1603165332] transaction","detail":"{read_only:false; response_revision:26453; number_of_response:1; }","duration":"105.837644ms","start":"2026-04-12T19:27:08.161344Z","end":"2026-04-12T19:27:08.267181Z","steps":["trace[1603165332] 'process raft request' (duration: 105.556008ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:08.46048Z","caller":"traceutil/trace.go:171","msg":"trace[365140788] linearizableReadLoop","detail":"{readStateIndex:27302; appliedIndex:27301; }","duration":"179.502446ms","start":"2026-04-12T19:27:08.280949Z","end":"2026-04-12T19:27:08.460451Z","steps":["trace[365140788] 'read index received' (duration: 145.649868ms)","trace[365140788] 'applied index is now lower than readState.Index' (duration: 33.851258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:27:08.460667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.688549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:27:08.460714Z","caller":"traceutil/trace.go:171","msg":"trace[1509235538] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26454; }","duration":"179.775141ms","start":"2026-04-12T19:27:08.280925Z","end":"2026-04-12T19:27:08.460701Z","steps":["trace[1509235538] 'agreement among raft nodes before linearized reading' (duration: 179.632008ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:08.460788Z","caller":"traceutil/trace.go:171","msg":"trace[474427748] transaction","detail":"{read_only:false; response_revision:26454; number_of_response:1; }","duration":"181.442197ms","start":"2026-04-12T19:27:08.27932Z","end":"2026-04-12T19:27:08.460762Z","steps":["trace[474427748] 'process raft request' (duration: 147.344635ms)","trace[474427748] 'compare' (duration: 33.653433ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:27:38.105093Z","caller":"traceutil/trace.go:171","msg":"trace[991716583] transaction","detail":"{read_only:false; response_revision:26671; number_of_response:1; }","duration":"132.956509ms","start":"2026-04-12T19:27:37.972084Z","end":"2026-04-12T19:27:38.10504Z","steps":["trace[991716583] 'process raft request' (duration: 132.771246ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.710287Z","caller":"traceutil/trace.go:171","msg":"trace[1773696814] linearizableReadLoop","detail":"{readStateIndex:27527; appliedIndex:27526; }","duration":"133.737895ms","start":"2026-04-12T19:27:38.576524Z","end":"2026-04-12T19:27:38.710262Z","steps":["trace[1773696814] 'read index received' (duration: 133.605582ms)","trace[1773696814] 'applied index is now lower than readState.Index' (duration: 130.793µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:27:38.710402Z","caller":"traceutil/trace.go:171","msg":"trace[215607278] transaction","detail":"{read_only:false; response_revision:26673; number_of_response:1; }","duration":"135.88802ms","start":"2026-04-12T19:27:38.57448Z","end":"2026-04-12T19:27:38.710368Z","steps":["trace[215607278] 'process raft request' (duration: 135.607744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.710554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.008302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:27:38.710626Z","caller":"traceutil/trace.go:171","msg":"trace[2001848402] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26673; }","duration":"134.122703ms","start":"2026-04-12T19:27:38.576486Z","end":"2026-04-12T19:27:38.710608Z","steps":["trace[2001848402] 'agreement among raft nodes before linearized reading' (duration: 133.96066ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.997302Z","caller":"traceutil/trace.go:171","msg":"trace[79662178] transaction","detail":"{read_only:false; response_revision:26674; number_of_response:1; }","duration":"338.921335ms","start":"2026-04-12T19:27:38.658353Z","end":"2026-04-12T19:27:38.997275Z","steps":["trace[79662178] 'process raft request' (duration: 323.827525ms)","trace[79662178] 'compare' (duration: 14.656011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:27:38.997449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:27:38.658332Z","time spent":"339.037597ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:27:38.997538Z","caller":"traceutil/trace.go:171","msg":"trace[851686053] transaction","detail":"{read_only:false; response_revision:26675; number_of_response:1; }","duration":"306.465657ms","start":"2026-04-12T19:27:38.691046Z","end":"2026-04-12T19:27:38.997512Z","steps":["trace[851686053] 'process raft request' (duration: 306.17614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.997697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:27:38.69102Z","time spent":"306.59003ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:27:38.997947Z","caller":"traceutil/trace.go:171","msg":"trace[1622009919] transaction","detail":"{read_only:false; response_revision:26679; number_of_response:1; }","duration":"281.468048ms","start":"2026-04-12T19:27:38.716458Z","end":"2026-04-12T19:27:38.997926Z","steps":["trace[1622009919] 'process raft request' (duration: 281.431317ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.997969Z","caller":"traceutil/trace.go:171","msg":"trace[1714060435] transaction","detail":"{read_only:false; response_revision:26676; number_of_response:1; }","duration":"291.090812ms","start":"2026-04-12T19:27:38.706866Z","end":"2026-04-12T19:27:38.997956Z","steps":["trace[1714060435] 'process raft request' (duration: 290.56503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.799592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:27:38.998088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.951965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:27:38.998033Z","caller":"traceutil/trace.go:171","msg":"trace[1818478749] transaction","detail":"{read_only:false; response_revision:26677; number_of_response:1; }","duration":"286.333731ms","start":"2026-04-12T19:27:38.71166Z","end":"2026-04-12T19:27:38.997993Z","steps":["trace[1818478749] 'process raft request' (duration: 286.047725ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.998056Z","caller":"traceutil/trace.go:171","msg":"trace[1129927217] transaction","detail":"{read_only:false; response_revision:26678; number_of_response:1; }","duration":"283.974331ms","start":"2026-04-12T19:27:38.714041Z","end":"2026-04-12T19:27:38.998015Z","steps":["trace[1129927217] 'process raft request' (duration: 283.763426ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.998107Z","caller":"traceutil/trace.go:171","msg":"trace[2029616913] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26679; }","duration":"362.883873ms","start":"2026-04-12T19:27:38.635214Z","end":"2026-04-12T19:27:38.998098Z","steps":["trace[2029616913] 'agreement among raft nodes before linearized reading' (duration: 362.796062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:27:38.635204Z","time spent":"362.936154ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-04-12T19:27:38.997991Z","caller":"traceutil/trace.go:171","msg":"trace[971360141] linearizableReadLoop","detail":"{readStateIndex:27530; appliedIndex:27527; }","duration":"287.511896ms","start":"2026-04-12T19:27:38.710468Z","end":"2026-04-12T19:27:38.99798Z","steps":["trace[971360141] 'read index received' (duration: 271.70221ms)","trace[971360141] 'applied index is now lower than readState.Index' (duration: 15.808516ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:27:38.998113Z","caller":"traceutil/trace.go:171","msg":"trace[836624741] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26679; }","duration":"362.988306ms","start":"2026-04-12T19:27:38.635117Z","end":"2026-04-12T19:27:38.998105Z","steps":["trace[836624741] 'agreement among raft nodes before linearized reading' (duration: 362.951355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.711348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:27:38.998319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.075601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:27:38.9984Z","caller":"traceutil/trace.go:171","msg":"trace[1688573848] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26679; }","duration":"282.194453ms","start":"2026-04-12T19:27:38.716188Z","end":"2026-04-12T19:27:38.998383Z","steps":["trace[1688573848] 'agreement among raft nodes before linearized reading' (duration: 282.05092ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.998407Z","caller":"traceutil/trace.go:171","msg":"trace[2060881512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26679; }","duration":"302.892711ms","start":"2026-04-12T19:27:38.695488Z","end":"2026-04-12T19:27:38.998381Z","steps":["trace[2060881512] 'agreement among raft nodes before linearized reading' (duration: 302.662457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.047374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:27:38.998455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:27:38.695476Z","time spent":"302.969043ms","remote":"127.0.0.1:53868","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-04-12T19:27:38.998481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.421588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:27:38.998472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.272037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:27:38.998494Z","caller":"traceutil/trace.go:171","msg":"trace[1369313582] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26679; }","duration":"219.182496ms","start":"2026-04-12T19:27:38.779288Z","end":"2026-04-12T19:27:38.99847Z","steps":["trace[1369313582] 'agreement among raft nodes before linearized reading' (duration: 219.029443ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.998512Z","caller":"traceutil/trace.go:171","msg":"trace[578977236] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26679; }","duration":"216.471399ms","start":"2026-04-12T19:27:38.782034Z","end":"2026-04-12T19:27:38.998505Z","steps":["trace[578977236] 'agreement among raft nodes before linearized reading' (duration: 216.430708ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.998528Z","caller":"traceutil/trace.go:171","msg":"trace[1127412615] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26679; }","duration":"217.45087ms","start":"2026-04-12T19:27:38.781063Z","end":"2026-04-12T19:27:38.998514Z","steps":["trace[1127412615] 'agreement among raft nodes before linearized reading' (duration: 217.178624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.470919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"warn","ts":"2026-04-12T19:27:38.998433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.228176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:27:38.998626Z","caller":"traceutil/trace.go:171","msg":"trace[1015373445] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26679; }","duration":"216.566981ms","start":"2026-04-12T19:27:38.782042Z","end":"2026-04-12T19:27:38.998609Z","steps":["trace[1015373445] 'agreement among raft nodes before linearized reading' (duration: 216.421008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:27:38.635111Z","time spent":"363.128208ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-04-12T19:27:38.998415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.115294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:27:38.998671Z","caller":"traceutil/trace.go:171","msg":"trace[1374746403] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26679; }","duration":"218.482012ms","start":"2026-04-12T19:27:38.780172Z","end":"2026-04-12T19:27:38.998654Z","steps":["trace[1374746403] 'agreement among raft nodes before linearized reading' (duration: 218.246637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.427351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:27:38.998726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.513476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-04-12T19:27:38.998741Z","caller":"traceutil/trace.go:171","msg":"trace[549779850] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26679; }","duration":"219.569695ms","start":"2026-04-12T19:27:38.779163Z","end":"2026-04-12T19:27:38.998732Z","steps":["trace[549779850] 'agreement among raft nodes before linearized reading' (duration: 219.434152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.133969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:27:38.998709Z","caller":"traceutil/trace.go:171","msg":"trace[96519183] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26679; }","duration":"218.470242ms","start":"2026-04-12T19:27:38.780227Z","end":"2026-04-12T19:27:38.998698Z","steps":["trace[96519183] 'agreement among raft nodes before linearized reading' (duration: 218.095424ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.998772Z","caller":"traceutil/trace.go:171","msg":"trace[1589294833] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26679; }","duration":"279.558057ms","start":"2026-04-12T19:27:38.719199Z","end":"2026-04-12T19:27:38.998757Z","steps":["trace[1589294833] 'agreement among raft nodes before linearized reading' (duration: 279.418304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.960264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:27:38.998839Z","caller":"traceutil/trace.go:171","msg":"trace[1862621093] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26679; }","duration":"363.799713ms","start":"2026-04-12T19:27:38.63501Z","end":"2026-04-12T19:27:38.99881Z","steps":["trace[1862621093] 'agreement among raft nodes before linearized reading' (duration: 363.118859ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:27:38.998905Z","caller":"traceutil/trace.go:171","msg":"trace[378078147] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26679; }","duration":"220.115357ms","start":"2026-04-12T19:27:38.778777Z","end":"2026-04-12T19:27:38.998892Z","steps":["trace[378078147] 'agreement among raft nodes before linearized reading' (duration: 219.982484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.99805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.265731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:27:38.998966Z","caller":"traceutil/trace.go:171","msg":"trace[616897286] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26679; }","duration":"364.208251ms","start":"2026-04-12T19:27:38.63475Z","end":"2026-04-12T19:27:38.998959Z","steps":["trace[616897286] 'agreement among raft nodes before linearized reading' (duration: 363.264812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:27:38.998999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:27:38.63474Z","time spent":"364.250692ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-04-12T19:27:38.999209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:27:38.634993Z","time spent":"363.936176ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5031,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-04-12T19:28:10.492967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.468284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:28:10.493201Z","caller":"traceutil/trace.go:171","msg":"trace[1189903338] transaction","detail":"{read_only:false; response_revision:26911; number_of_response:1; }","duration":"237.595556ms","start":"2026-04-12T19:28:10.255587Z","end":"2026-04-12T19:28:10.493182Z","steps":["trace[1189903338] 'process raft request' (duration: 237.513964ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:28:10.493395Z","caller":"traceutil/trace.go:171","msg":"trace[1736943460] transaction","detail":"{read_only:false; response_revision:26910; number_of_response:1; }","duration":"268.243057ms","start":"2026-04-12T19:28:10.225142Z","end":"2026-04-12T19:28:10.493385Z","steps":["trace[1736943460] 'process raft request' (duration: 122.259681ms)","trace[1736943460] 'compare' (duration: 145.335882ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:28:10.493627Z","caller":"traceutil/trace.go:171","msg":"trace[40432134] linearizableReadLoop","detail":"{readStateIndex:27772; appliedIndex:27771; }","duration":"238.749582ms","start":"2026-04-12T19:28:10.25483Z","end":"2026-04-12T19:28:10.493579Z","steps":["trace[40432134] 'read index received' (duration: 92.780218ms)","trace[40432134] 'applied index is now lower than readState.Index' (duration: 145.948324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:28:10.49377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.934296ms","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-04-12T19:28:10.493805Z","caller":"traceutil/trace.go:171","msg":"trace[1552589347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26911; }","duration":"238.996338ms","start":"2026-04-12T19:28:10.2548Z","end":"2026-04-12T19:28:10.493796Z","steps":["trace[1552589347] 'agreement among raft nodes before linearized reading' (duration: 238.879295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:10.493989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.462131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:28:10.494091Z","caller":"traceutil/trace.go:171","msg":"trace[1223741746] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26911; }","duration":"209.603154ms","start":"2026-04-12T19:28:10.284469Z","end":"2026-04-12T19:28:10.494072Z","steps":["trace[1223741746] 'agreement among raft nodes before linearized reading' (duration: 209.41978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:10.802512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.19895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:28:10.802634Z","caller":"traceutil/trace.go:171","msg":"trace[4654996] transaction","detail":"{read_only:false; response_revision:26912; number_of_response:1; }","duration":"304.857483ms","start":"2026-04-12T19:28:10.497755Z","end":"2026-04-12T19:28:10.802613Z","steps":["trace[4654996] 'process raft request' (duration: 174.425898ms)","trace[4654996] 'compare' (duration: 129.975946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:28:10.802851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:28:10.497738Z","time spent":"304.930455ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:28:10.857197Z","caller":"traceutil/trace.go:171","msg":"trace[11300696] transaction","detail":"{read_only:false; response_revision:26913; number_of_response:1; }","duration":"359.100222ms","start":"2026-04-12T19:28:10.498054Z","end":"2026-04-12T19:28:10.857155Z","steps":["trace[11300696] 'process raft request' (duration: 358.847107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:10.857297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:28:10.498032Z","time spent":"359.214725ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:28:10.857336Z","caller":"traceutil/trace.go:171","msg":"trace[663658810] linearizableReadLoop","detail":"{readStateIndex:27775; appliedIndex:27773; }","duration":"358.182422ms","start":"2026-04-12T19:28:10.499118Z","end":"2026-04-12T19:28:10.8573Z","steps":["trace[663658810] 'read index received' (duration: 173.081588ms)","trace[663658810] 'applied index is now lower than readState.Index' (duration: 185.098034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:28:10.85767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.5128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-12T19:28:10.857824Z","caller":"traceutil/trace.go:171","msg":"trace[488464711] transaction","detail":"{read_only:false; response_revision:26914; number_of_response:1; }","duration":"332.014669ms","start":"2026-04-12T19:28:10.525345Z","end":"2026-04-12T19:28:10.85736Z","steps":["trace[488464711] 'process raft request' (duration: 331.710972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:10.858084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:28:10.525323Z","time spent":"332.659773ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:28:10.858431Z","caller":"traceutil/trace.go:171","msg":"trace[1629616559] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26914; }","duration":"358.667704ms","start":"2026-04-12T19:28:10.49909Z","end":"2026-04-12T19:28:10.857757Z","steps":["trace[1629616559] 'agreement among raft nodes before linearized reading' (duration: 358.309075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:10.858499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:28:10.499083Z","time spent":"359.398629ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-12T19:28:10.86511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.757639ms","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-04-12T19:28:10.865205Z","caller":"traceutil/trace.go:171","msg":"trace[948382754] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26915; }","duration":"352.912772ms","start":"2026-04-12T19:28:10.512278Z","end":"2026-04-12T19:28:10.86519Z","steps":["trace[948382754] 'agreement among raft nodes before linearized reading' (duration: 352.690447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:10.865266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:28:10.512267Z","time spent":"352.985913ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-12T19:28:10.8655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.942014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:28:10.865567Z","caller":"traceutil/trace.go:171","msg":"trace[741716820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26915; }","duration":"171.059787ms","start":"2026-04-12T19:28:10.694497Z","end":"2026-04-12T19:28:10.865557Z","steps":["trace[741716820] 'agreement among raft nodes before linearized reading' (duration: 170.975055ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:28:42.288832Z","caller":"traceutil/trace.go:171","msg":"trace[2104498684] linearizableReadLoop","detail":"{readStateIndex:28012; appliedIndex:28011; }","duration":"267.585913ms","start":"2026-04-12T19:28:42.021224Z","end":"2026-04-12T19:28:42.288809Z","steps":["trace[2104498684] 'read index received' (duration: 267.318357ms)","trace[2104498684] 'applied index is now lower than readState.Index' (duration: 266.646µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:28:42.289159Z","caller":"traceutil/trace.go:171","msg":"trace[1476188290] transaction","detail":"{read_only:false; response_revision:27144; number_of_response:1; }","duration":"284.945681ms","start":"2026-04-12T19:28:42.004178Z","end":"2026-04-12T19:28:42.289124Z","steps":["trace[1476188290] 'process raft request' (duration: 284.46111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:42.289303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.040503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:28:42.28934Z","caller":"traceutil/trace.go:171","msg":"trace[1907839471] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:27144; }","duration":"268.145455ms","start":"2026-04-12T19:28:42.021184Z","end":"2026-04-12T19:28:42.28933Z","steps":["trace[1907839471] 'agreement among raft nodes before linearized reading' (duration: 267.974471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:42.578017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.852931ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:28:42.578201Z","caller":"traceutil/trace.go:171","msg":"trace[324275398] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:27144; }","duration":"211.059245ms","start":"2026-04-12T19:28:42.36711Z","end":"2026-04-12T19:28:42.578169Z","steps":["trace[324275398] 'range keys from in-memory index tree' (duration: 210.829381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:28:42.578173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.654069ms","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-04-12T19:28:42.578286Z","caller":"traceutil/trace.go:171","msg":"trace[1105009242] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:27144; }","duration":"271.809512ms","start":"2026-04-12T19:28:42.306446Z","end":"2026-04-12T19:28:42.578256Z","steps":["trace[1105009242] 'range keys from in-memory index tree' (duration: 271.398574ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:13.718149Z","caller":"traceutil/trace.go:171","msg":"trace[1193228823] transaction","detail":"{read_only:false; response_revision:27372; number_of_response:1; }","duration":"105.137659ms","start":"2026-04-12T19:29:13.612981Z","end":"2026-04-12T19:29:13.718119Z","steps":["trace[1193228823] 'process raft request' (duration: 104.920275ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:13.783443Z","caller":"traceutil/trace.go:171","msg":"trace[930572924] transaction","detail":"{read_only:false; response_revision:27373; number_of_response:1; }","duration":"169.431121ms","start":"2026-04-12T19:29:13.613983Z","end":"2026-04-12T19:29:13.783414Z","steps":["trace[930572924] 'process raft request' (duration: 168.876909ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:13.783743Z","caller":"traceutil/trace.go:171","msg":"trace[712737115] transaction","detail":"{read_only:false; response_revision:27374; number_of_response:1; }","duration":"169.637065ms","start":"2026-04-12T19:29:13.614049Z","end":"2026-04-12T19:29:13.783686Z","steps":["trace[712737115] 'process raft request' (duration: 168.994642ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:14.314195Z","caller":"traceutil/trace.go:171","msg":"trace[967095230] linearizableReadLoop","detail":"{readStateIndex:28250; appliedIndex:28249; }","duration":"187.559266ms","start":"2026-04-12T19:29:14.126605Z","end":"2026-04-12T19:29:14.314164Z","steps":["trace[967095230] 'read index received' (duration: 187.30298ms)","trace[967095230] 'applied index is now lower than readState.Index' (duration: 255.276µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:14.31428Z","caller":"traceutil/trace.go:171","msg":"trace[18293989] transaction","detail":"{read_only:false; response_revision:27376; number_of_response:1; }","duration":"220.902724ms","start":"2026-04-12T19:29:14.093328Z","end":"2026-04-12T19:29:14.314231Z","steps":["trace[18293989] 'process raft request' (duration: 220.648517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:14.314457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.827592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-12T19:29:14.314506Z","caller":"traceutil/trace.go:171","msg":"trace[3170795] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:27376; }","duration":"187.934844ms","start":"2026-04-12T19:29:14.126556Z","end":"2026-04-12T19:29:14.314491Z","steps":["trace[3170795] 'agreement among raft nodes before linearized reading' (duration: 187.762151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:14.314769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.781239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-blgf6.18a5b1cea3a2c48b\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-04-12T19:29:14.314887Z","caller":"traceutil/trace.go:171","msg":"trace[1115513682] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-blgf6.18a5b1cea3a2c48b; range_end:; response_count:1; response_revision:27376; }","duration":"143.924642ms","start":"2026-04-12T19:29:14.170937Z","end":"2026-04-12T19:29:14.314862Z","steps":["trace[1115513682] 'agreement among raft nodes before linearized reading' (duration: 143.544864ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:35.765992Z","caller":"traceutil/trace.go:171","msg":"trace[466433754] linearizableReadLoop","detail":"{readStateIndex:28413; appliedIndex:28412; }","duration":"104.272831ms","start":"2026-04-12T19:29:35.661699Z","end":"2026-04-12T19:29:35.765971Z","steps":["trace[466433754] 'read index received' (duration: 104.118748ms)","trace[466433754] 'applied index is now lower than readState.Index' (duration: 153.663µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:29:35.766124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.415613ms","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":"info","ts":"2026-04-12T19:29:35.766147Z","caller":"traceutil/trace.go:171","msg":"trace[1093048424] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:27533; }","duration":"104.519995ms","start":"2026-04-12T19:29:35.661622Z","end":"2026-04-12T19:29:35.766142Z","steps":["trace[1093048424] 'agreement among raft nodes before linearized reading' (duration: 104.449594ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:35.766157Z","caller":"traceutil/trace.go:171","msg":"trace[1419747299] transaction","detail":"{read_only:false; response_revision:27533; number_of_response:1; }","duration":"145.577696ms","start":"2026-04-12T19:29:35.620555Z","end":"2026-04-12T19:29:35.766133Z","steps":["trace[1419747299] 'process raft request' (duration: 145.27863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:36.019685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.065903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-12T19:29:36.019771Z","caller":"traceutil/trace.go:171","msg":"trace[1855801509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27533; }","duration":"126.189285ms","start":"2026-04-12T19:29:35.893561Z","end":"2026-04-12T19:29:36.01975Z","steps":["trace[1855801509] 'range keys from in-memory index tree' (duration: 125.902219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:36.019826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.73822ms","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-04-12T19:29:36.019948Z","caller":"traceutil/trace.go:171","msg":"trace[555097976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27533; }","duration":"187.916464ms","start":"2026-04-12T19:29:35.832002Z","end":"2026-04-12T19:29:36.019918Z","steps":["trace[555097976] 'range keys from in-memory index tree' (duration: 187.554896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:36.020006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.730113ms","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":"info","ts":"2026-04-12T19:29:36.020107Z","caller":"traceutil/trace.go:171","msg":"trace[2078923357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27533; }","duration":"131.856525ms","start":"2026-04-12T19:29:35.888214Z","end":"2026-04-12T19:29:36.020071Z","steps":["trace[2078923357] 'range keys from in-memory index tree' (duration: 131.577809ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:36.17163Z","caller":"traceutil/trace.go:171","msg":"trace[1034818391] transaction","detail":"{read_only:false; response_revision:27534; number_of_response:1; }","duration":"146.69852ms","start":"2026-04-12T19:29:36.024895Z","end":"2026-04-12T19:29:36.171594Z","steps":["trace[1034818391] 'process raft request' (duration: 146.487776ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:36.336743Z","caller":"traceutil/trace.go:171","msg":"trace[1121185026] linearizableReadLoop","detail":"{readStateIndex:28416; appliedIndex:28414; }","duration":"152.372789ms","start":"2026-04-12T19:29:36.184332Z","end":"2026-04-12T19:29:36.336705Z","steps":["trace[1121185026] 'read index received' (duration: 151.945301ms)","trace[1121185026] 'applied index is now lower than readState.Index' (duration: 425.338µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:36.336777Z","caller":"traceutil/trace.go:171","msg":"trace[79104532] transaction","detail":"{read_only:false; response_revision:27535; number_of_response:1; }","duration":"311.769279ms","start":"2026-04-12T19:29:36.024957Z","end":"2026-04-12T19:29:36.336727Z","steps":["trace[79104532] 'process raft request' (duration: 311.454272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:36.337029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:36.024876Z","time spent":"311.986074ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T19:29:36.337101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.686717ms","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-04-12T19:29:36.337177Z","caller":"traceutil/trace.go:171","msg":"trace[45055630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27536; }","duration":"152.874701ms","start":"2026-04-12T19:29:36.184278Z","end":"2026-04-12T19:29:36.337152Z","steps":["trace[45055630] 'agreement among raft nodes before linearized reading' (duration: 152.566404ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:36.337184Z","caller":"traceutil/trace.go:171","msg":"trace[1812100455] transaction","detail":"{read_only:false; response_revision:27536; number_of_response:1; }","duration":"311.511074ms","start":"2026-04-12T19:29:36.025638Z","end":"2026-04-12T19:29:36.337149Z","steps":["trace[1812100455] 'process raft request' (duration: 311.003943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:36.337346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:36.025608Z","time spent":"311.648657ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T19:29:36.788666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.473389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:29:36.788856Z","caller":"traceutil/trace.go:171","msg":"trace[88437955] transaction","detail":"{read_only:false; response_revision:27538; number_of_response:1; }","duration":"277.38977ms","start":"2026-04-12T19:29:36.511444Z","end":"2026-04-12T19:29:36.788833Z","steps":["trace[88437955] 'process raft request' (duration: 135.615305ms)","trace[88437955] 'compare' (duration: 141.070171ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:37.471594Z","caller":"traceutil/trace.go:171","msg":"trace[1575830931] linearizableReadLoop","detail":"{readStateIndex:28421; appliedIndex:28420; }","duration":"167.094533ms","start":"2026-04-12T19:29:37.304474Z","end":"2026-04-12T19:29:37.471569Z","steps":["trace[1575830931] 'read index received' (duration: 166.821607ms)","trace[1575830931] 'applied index is now lower than readState.Index' (duration: 271.586µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:37.471714Z","caller":"traceutil/trace.go:171","msg":"trace[1584644906] transaction","detail":"{read_only:false; response_revision:27541; number_of_response:1; }","duration":"215.830886ms","start":"2026-04-12T19:29:37.255848Z","end":"2026-04-12T19:29:37.471679Z","steps":["trace[1584644906] 'process raft request' (duration: 215.53017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:37.4718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.294096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:37.471875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.883927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:37.471905Z","caller":"traceutil/trace.go:171","msg":"trace[1737656477] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27541; }","duration":"167.47789ms","start":"2026-04-12T19:29:37.304405Z","end":"2026-04-12T19:29:37.471883Z","steps":["trace[1737656477] 'agreement among raft nodes before linearized reading' (duration: 167.293326ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:37.471948Z","caller":"traceutil/trace.go:171","msg":"trace[97951935] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27541; }","duration":"166.997959ms","start":"2026-04-12T19:29:37.304934Z","end":"2026-04-12T19:29:37.471932Z","steps":["trace[97951935] 'agreement among raft nodes before linearized reading' (duration: 166.856076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:37.471945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.197292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:37.472001Z","caller":"traceutil/trace.go:171","msg":"trace[1128011418] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27541; }","duration":"165.256003ms","start":"2026-04-12T19:29:37.30673Z","end":"2026-04-12T19:29:37.471986Z","steps":["trace[1128011418] 'agreement among raft nodes before linearized reading' (duration: 165.157711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:37.472105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.776454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:37.472133Z","caller":"traceutil/trace.go:171","msg":"trace[393476777] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27541; }","duration":"164.828054ms","start":"2026-04-12T19:29:37.307299Z","end":"2026-04-12T19:29:37.472127Z","steps":["trace[393476777] 'agreement among raft nodes before linearized reading' (duration: 164.785053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:37.472135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.391487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:37.47212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.475263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"warn","ts":"2026-04-12T19:29:37.472236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.917411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:37.472322Z","caller":"traceutil/trace.go:171","msg":"trace[439904563] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27541; }","duration":"136.719129ms","start":"2026-04-12T19:29:37.335577Z","end":"2026-04-12T19:29:37.472296Z","steps":["trace[439904563] 'agreement among raft nodes before linearized reading' (duration: 136.361071ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:37.472533Z","caller":"traceutil/trace.go:171","msg":"trace[501495391] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27541; }","duration":"111.142555ms","start":"2026-04-12T19:29:37.361186Z","end":"2026-04-12T19:29:37.472329Z","steps":["trace[501495391] 'agreement among raft nodes before linearized reading' (duration: 110.678006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:37.472159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.843675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:37.472603Z","caller":"traceutil/trace.go:171","msg":"trace[1594459167] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27541; }","duration":"165.361446ms","start":"2026-04-12T19:29:37.30723Z","end":"2026-04-12T19:29:37.472591Z","steps":["trace[1594459167] 'agreement among raft nodes before linearized reading' (duration: 164.901296ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:37.472159Z","caller":"traceutil/trace.go:171","msg":"trace[222430896] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27541; }","duration":"166.415238ms","start":"2026-04-12T19:29:37.305737Z","end":"2026-04-12T19:29:37.472152Z","steps":["trace[222430896] 'agreement among raft nodes before linearized reading' (duration: 166.372097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:37.4722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.310773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:37.472692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.952326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:37.472793Z","caller":"traceutil/trace.go:171","msg":"trace[894279254] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27541; }","duration":"165.11707ms","start":"2026-04-12T19:29:37.307655Z","end":"2026-04-12T19:29:37.472772Z","steps":["trace[894279254] 'agreement among raft nodes before linearized reading' (duration: 164.18772ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:37.472767Z","caller":"traceutil/trace.go:171","msg":"trace[1111129688] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27541; }","duration":"164.875535ms","start":"2026-04-12T19:29:37.307853Z","end":"2026-04-12T19:29:37.472729Z","steps":["trace[1111129688] 'agreement among raft nodes before linearized reading' (duration: 164.328983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:37.473206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.469639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:37.47328Z","caller":"traceutil/trace.go:171","msg":"trace[2049009815] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27541; }","duration":"166.572252ms","start":"2026-04-12T19:29:37.306689Z","end":"2026-04-12T19:29:37.473261Z","steps":["trace[2049009815] 'agreement among raft nodes before linearized reading' (duration: 165.255383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:37.473424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.564825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:37.473549Z","caller":"traceutil/trace.go:171","msg":"trace[1066056996] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27541; }","duration":"111.733458ms","start":"2026-04-12T19:29:37.36179Z","end":"2026-04-12T19:29:37.473524Z","steps":["trace[1066056996] 'agreement among raft nodes before linearized reading' (duration: 110.510342ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:41.180686Z","caller":"traceutil/trace.go:171","msg":"trace[1717149513] transaction","detail":"{read_only:false; response_revision:27570; number_of_response:1; }","duration":"164.789124ms","start":"2026-04-12T19:29:41.015867Z","end":"2026-04-12T19:29:41.180656Z","steps":["trace[1717149513] 'process raft request' (duration: 120.695348ms)","trace[1717149513] 'compare' (duration: 43.79899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:29:41.550916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.014676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:29:41.551181Z","caller":"traceutil/trace.go:171","msg":"trace[1131813978] transaction","detail":"{read_only:false; response_revision:27571; number_of_response:1; }","duration":"299.453848ms","start":"2026-04-12T19:29:41.251707Z","end":"2026-04-12T19:29:41.551161Z","steps":["trace[1131813978] 'process raft request' (duration: 86.078735ms)","trace[1131813978] 'compare' (duration: 212.602997ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:41.551315Z","caller":"traceutil/trace.go:171","msg":"trace[88117075] transaction","detail":"{read_only:false; response_revision:27572; number_of_response:1; }","duration":"285.519183ms","start":"2026-04-12T19:29:41.265746Z","end":"2026-04-12T19:29:41.551265Z","steps":["trace[88117075] 'process raft request' (duration: 285.278858ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:41.771683Z","caller":"traceutil/trace.go:171","msg":"trace[518377761] transaction","detail":"{read_only:false; response_revision:27573; number_of_response:1; }","duration":"228.459134ms","start":"2026-04-12T19:29:41.543201Z","end":"2026-04-12T19:29:41.77166Z","steps":["trace[518377761] 'process raft request' (duration: 228.29835ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:41.785416Z","caller":"traceutil/trace.go:171","msg":"trace[404346122] transaction","detail":"{read_only:false; response_revision:27574; number_of_response:1; }","duration":"241.53574ms","start":"2026-04-12T19:29:41.543863Z","end":"2026-04-12T19:29:41.785398Z","steps":["trace[404346122] 'process raft request' (duration: 241.397368ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:41.785614Z","caller":"traceutil/trace.go:171","msg":"trace[301032621] transaction","detail":"{read_only:false; response_revision:27575; number_of_response:1; }","duration":"240.673333ms","start":"2026-04-12T19:29:41.544926Z","end":"2026-04-12T19:29:41.785599Z","steps":["trace[301032621] 'process raft request' (duration: 240.436428ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:41.785772Z","caller":"traceutil/trace.go:171","msg":"trace[406978715] transaction","detail":"{read_only:false; response_revision:27576; number_of_response:1; }","duration":"240.211673ms","start":"2026-04-12T19:29:41.54555Z","end":"2026-04-12T19:29:41.785762Z","steps":["trace[406978715] 'process raft request' (duration: 239.998549ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:41.785908Z","caller":"traceutil/trace.go:171","msg":"trace[2030741380] linearizableReadLoop","detail":"{readStateIndex:28457; appliedIndex:28453; }","duration":"234.774377ms","start":"2026-04-12T19:29:41.551126Z","end":"2026-04-12T19:29:41.785901Z","steps":["trace[2030741380] 'read index received' (duration: 220.429083ms)","trace[2030741380] 'applied index is now lower than readState.Index' (duration: 14.344234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:29:41.786062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.518702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:41.78607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.485621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:41.78609Z","caller":"traceutil/trace.go:171","msg":"trace[288843247] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27577; }","duration":"301.568633ms","start":"2026-04-12T19:29:41.484515Z","end":"2026-04-12T19:29:41.786084Z","steps":["trace[288843247] 'agreement among raft nodes before linearized reading' (duration: 301.504831ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:41.786097Z","caller":"traceutil/trace.go:171","msg":"trace[1369639807] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27577; }","duration":"301.536792ms","start":"2026-04-12T19:29:41.484552Z","end":"2026-04-12T19:29:41.786089Z","steps":["trace[1369639807] 'agreement among raft nodes before linearized reading' (duration: 301.420799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.484511Z","time spent":"301.599303ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-04-12T19:29:41.786144Z","caller":"traceutil/trace.go:171","msg":"trace[128778959] transaction","detail":"{read_only:false; response_revision:27577; number_of_response:1; }","duration":"230.816153ms","start":"2026-04-12T19:29:41.555321Z","end":"2026-04-12T19:29:41.786137Z","steps":["trace[128778959] 'process raft request' (duration: 230.410324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.450718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:41.786279Z","caller":"traceutil/trace.go:171","msg":"trace[1494655842] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27577; }","duration":"299.47441ms","start":"2026-04-12T19:29:41.486797Z","end":"2026-04-12T19:29:41.786272Z","steps":["trace[1494655842] 'agreement among raft nodes before linearized reading' (duration: 299.413278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.8728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:41.786302Z","caller":"traceutil/trace.go:171","msg":"trace[1974791573] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27577; }","duration":"301.902141ms","start":"2026-04-12T19:29:41.484394Z","end":"2026-04-12T19:29:41.786296Z","steps":["trace[1974791573] 'agreement among raft nodes before linearized reading' (duration: 301.817559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.484389Z","time spent":"301.931232ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5031,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-04-12T19:29:41.786424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.298717ms","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-04-12T19:29:41.78645Z","caller":"traceutil/trace.go:171","msg":"trace[1155731737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27577; }","duration":"301.381129ms","start":"2026-04-12T19:29:41.485063Z","end":"2026-04-12T19:29:41.786444Z","steps":["trace[1155731737] 'agreement among raft nodes before linearized reading' (duration: 301.315188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.722687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:41.786473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.485042Z","time spent":"301.42534ms","remote":"127.0.0.1:54152","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-04-12T19:29:41.786501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.813359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:41.786524Z","caller":"traceutil/trace.go:171","msg":"trace[132579362] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27577; }","duration":"302.874721ms","start":"2026-04-12T19:29:41.483644Z","end":"2026-04-12T19:29:41.786519Z","steps":["trace[132579362] 'agreement among raft nodes before linearized reading' (duration: 302.84511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.78655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.483612Z","time spent":"302.930812ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-04-12T19:29:41.786432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.682187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:41.786603Z","caller":"traceutil/trace.go:171","msg":"trace[1754083769] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27577; }","duration":"302.887461ms","start":"2026-04-12T19:29:41.483707Z","end":"2026-04-12T19:29:41.786595Z","steps":["trace[1754083769] 'agreement among raft nodes before linearized reading' (duration: 302.709208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.95233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:41.786635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.483688Z","time spent":"302.939692ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-04-12T19:29:41.786647Z","caller":"traceutil/trace.go:171","msg":"trace[694373687] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27577; }","duration":"302.045133ms","start":"2026-04-12T19:29:41.484587Z","end":"2026-04-12T19:29:41.786632Z","steps":["trace[694373687] 'agreement among raft nodes before linearized reading' (duration: 301.91729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.204307ms","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-04-12T19:29:41.786478Z","caller":"traceutil/trace.go:171","msg":"trace[1581755482] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27577; }","duration":"302.768929ms","start":"2026-04-12T19:29:41.483703Z","end":"2026-04-12T19:29:41.786472Z","steps":["trace[1581755482] 'agreement among raft nodes before linearized reading' (duration: 302.740318ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:41.786713Z","caller":"traceutil/trace.go:171","msg":"trace[77495534] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:27577; }","duration":"219.281559ms","start":"2026-04-12T19:29:41.567422Z","end":"2026-04-12T19:29:41.786704Z","steps":["trace[77495534] 'agreement among raft nodes before linearized reading' (duration: 219.227678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.483687Z","time spent":"303.030674ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-04-12T19:29:41.786748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.667254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:41.786777Z","caller":"traceutil/trace.go:171","msg":"trace[848756738] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27577; }","duration":"301.711656ms","start":"2026-04-12T19:29:41.485059Z","end":"2026-04-12T19:29:41.78677Z","steps":["trace[848756738] 'agreement among raft nodes before linearized reading' (duration: 301.646615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.485053Z","time spent":"301.740377ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-04-12T19:29:41.786119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.484543Z","time spent":"301.570143ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5031,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-04-12T19:29:41.786928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.873009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:41.786949Z","caller":"traceutil/trace.go:171","msg":"trace[782796418] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27577; }","duration":"301.90125ms","start":"2026-04-12T19:29:41.485043Z","end":"2026-04-12T19:29:41.786944Z","steps":["trace[782796418] 'agreement among raft nodes before linearized reading' (duration: 301.844678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.786967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.485037Z","time spent":"301.92484ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-04-12T19:29:41.787031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.265027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:41.786688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.48458Z","time spent":"302.095624ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-04-12T19:29:41.787052Z","caller":"traceutil/trace.go:171","msg":"trace[1114841616] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27577; }","duration":"302.32622ms","start":"2026-04-12T19:29:41.48472Z","end":"2026-04-12T19:29:41.787046Z","steps":["trace[1114841616] 'agreement among raft nodes before linearized reading' (duration: 302.292958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:41.787071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.484696Z","time spent":"302.37059ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-04-12T19:29:42.093871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.840833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:29:42.094093Z","caller":"traceutil/trace.go:171","msg":"trace[1677965472] transaction","detail":"{read_only:false; response_revision:27578; number_of_response:1; }","duration":"303.654767ms","start":"2026-04-12T19:29:41.790403Z","end":"2026-04-12T19:29:42.094057Z","steps":["trace[1677965472] 'process raft request' (duration: 140.524168ms)","trace[1677965472] 'compare' (duration: 161.936014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:29:42.094344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:41.790392Z","time spent":"303.859861ms","remote":"127.0.0.1:54152","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-04-12T19:29:42.532732Z","caller":"traceutil/trace.go:171","msg":"trace[433809297] linearizableReadLoop","detail":"{readStateIndex:28460; appliedIndex:28459; }","duration":"174.351167ms","start":"2026-04-12T19:29:42.358335Z","end":"2026-04-12T19:29:42.532686Z","steps":["trace[433809297] 'read index received' (duration: 173.954359ms)","trace[433809297] 'applied index is now lower than readState.Index' (duration: 395.768µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:42.532775Z","caller":"traceutil/trace.go:171","msg":"trace[1206084694] transaction","detail":"{read_only:false; response_revision:27579; number_of_response:1; }","duration":"231.219332ms","start":"2026-04-12T19:29:42.301513Z","end":"2026-04-12T19:29:42.532732Z","steps":["trace[1206084694] 'process raft request' (duration: 230.893005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:42.533042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.928197ms","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-04-12T19:29:42.533107Z","caller":"traceutil/trace.go:171","msg":"trace[146651051] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:27579; }","duration":"174.028139ms","start":"2026-04-12T19:29:42.359069Z","end":"2026-04-12T19:29:42.533097Z","steps":["trace[146651051] 'agreement among raft nodes before linearized reading' (duration: 173.845686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:42.533124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.717774ms","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-04-12T19:29:42.533176Z","caller":"traceutil/trace.go:171","msg":"trace[289500516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27579; }","duration":"174.865418ms","start":"2026-04-12T19:29:42.358297Z","end":"2026-04-12T19:29:42.533162Z","steps":["trace[289500516] 'agreement among raft nodes before linearized reading' (duration: 174.591562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:42.533321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.839315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-12T19:29:42.533332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.016728ms","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":"info","ts":"2026-04-12T19:29:42.533382Z","caller":"traceutil/trace.go:171","msg":"trace[1392243074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27579; }","duration":"165.918407ms","start":"2026-04-12T19:29:42.367447Z","end":"2026-04-12T19:29:42.533365Z","steps":["trace[1392243074] 'agreement among raft nodes before linearized reading' (duration: 165.773894ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:42.533394Z","caller":"traceutil/trace.go:171","msg":"trace[808046942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27579; }","duration":"173.12232ms","start":"2026-04-12T19:29:42.360252Z","end":"2026-04-12T19:29:42.533374Z","steps":["trace[808046942] 'agreement among raft nodes before linearized reading' (duration: 172.965907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:42.798169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.833251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:29:42.798298Z","caller":"traceutil/trace.go:171","msg":"trace[49539560] transaction","detail":"{read_only:false; response_revision:27580; number_of_response:1; }","duration":"260.318218ms","start":"2026-04-12T19:29:42.537956Z","end":"2026-04-12T19:29:42.798274Z","steps":["trace[49539560] 'process raft request' (duration: 139.280692ms)","trace[49539560] 'compare' (duration: 120.704419ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:42.807093Z","caller":"traceutil/trace.go:171","msg":"trace[753085679] linearizableReadLoop","detail":"{readStateIndex:28463; appliedIndex:28461; }","duration":"107.616692ms","start":"2026-04-12T19:29:42.69946Z","end":"2026-04-12T19:29:42.807077Z","steps":["trace[753085679] 'read index received' (duration: 107.433918ms)","trace[753085679] 'applied index is now lower than readState.Index' (duration: 181.844µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:29:42.807406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.919438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:42.8078Z","caller":"traceutil/trace.go:171","msg":"trace[1495143954] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:27582; }","duration":"108.375348ms","start":"2026-04-12T19:29:42.699397Z","end":"2026-04-12T19:29:42.807773Z","steps":["trace[1495143954] 'agreement among raft nodes before linearized reading' (duration: 107.897798ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:42.807621Z","caller":"traceutil/trace.go:171","msg":"trace[385199094] transaction","detail":"{read_only:false; response_revision:27581; number_of_response:1; }","duration":"269.533214ms","start":"2026-04-12T19:29:42.538069Z","end":"2026-04-12T19:29:42.807603Z","steps":["trace[385199094] 'process raft request' (duration: 268.878631ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:42.807671Z","caller":"traceutil/trace.go:171","msg":"trace[1371173073] transaction","detail":"{read_only:false; response_revision:27582; number_of_response:1; }","duration":"269.412721ms","start":"2026-04-12T19:29:42.538251Z","end":"2026-04-12T19:29:42.807664Z","steps":["trace[1371173073] 'process raft request' (duration: 268.782218ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:42.978597Z","caller":"traceutil/trace.go:171","msg":"trace[971403837] transaction","detail":"{read_only:false; response_revision:27583; number_of_response:1; }","duration":"169.861501ms","start":"2026-04-12T19:29:42.808682Z","end":"2026-04-12T19:29:42.978544Z","steps":["trace[971403837] 'process raft request' (duration: 129.899974ms)","trace[971403837] 'compare' (duration: 39.682921ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:43.691057Z","caller":"traceutil/trace.go:171","msg":"trace[1422916940] linearizableReadLoop","detail":"{readStateIndex:28466; appliedIndex:28464; }","duration":"120.376402ms","start":"2026-04-12T19:29:43.57066Z","end":"2026-04-12T19:29:43.691037Z","steps":["trace[1422916940] 'read index received' (duration: 67.46382ms)","trace[1422916940] 'applied index is now lower than readState.Index' (duration: 52.911542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:29:43.691199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.027563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-12T19:29:43.691209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.550866ms","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-04-12T19:29:43.691229Z","caller":"traceutil/trace.go:171","msg":"trace[1175046521] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:27585; }","duration":"119.103085ms","start":"2026-04-12T19:29:43.572118Z","end":"2026-04-12T19:29:43.691221Z","steps":["trace[1175046521] 'agreement among raft nodes before linearized reading' (duration: 119.030793ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:43.691232Z","caller":"traceutil/trace.go:171","msg":"trace[1974631108] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:27585; }","duration":"120.601507ms","start":"2026-04-12T19:29:43.570624Z","end":"2026-04-12T19:29:43.691225Z","steps":["trace[1974631108] 'agreement among raft nodes before linearized reading' (duration: 120.503045ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:43.691106Z","caller":"traceutil/trace.go:171","msg":"trace[623547364] transaction","detail":"{read_only:false; response_revision:27585; number_of_response:1; }","duration":"133.50863ms","start":"2026-04-12T19:29:43.557563Z","end":"2026-04-12T19:29:43.691071Z","steps":["trace[623547364] 'process raft request' (duration: 133.358977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:43.896719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.67067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:43.896794Z","caller":"traceutil/trace.go:171","msg":"trace[1466943564] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27590; }","duration":"105.794473ms","start":"2026-04-12T19:29:43.790983Z","end":"2026-04-12T19:29:43.896777Z","steps":["trace[1466943564] 'agreement among raft nodes before linearized reading' (duration: 105.65823ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:43.8971Z","caller":"traceutil/trace.go:171","msg":"trace[1699758287] transaction","detail":"{read_only:false; response_revision:27587; number_of_response:1; }","duration":"105.407135ms","start":"2026-04-12T19:29:43.791674Z","end":"2026-04-12T19:29:43.897081Z","steps":["trace[1699758287] 'process raft request' (duration: 104.71129ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:43.897192Z","caller":"traceutil/trace.go:171","msg":"trace[171515133] transaction","detail":"{read_only:false; response_revision:27590; number_of_response:1; }","duration":"104.012495ms","start":"2026-04-12T19:29:43.793155Z","end":"2026-04-12T19:29:43.897167Z","steps":["trace[171515133] 'process raft request' (duration: 103.435833ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:43.897291Z","caller":"traceutil/trace.go:171","msg":"trace[841078187] transaction","detail":"{read_only:false; response_revision:27588; number_of_response:1; }","duration":"105.299893ms","start":"2026-04-12T19:29:43.791984Z","end":"2026-04-12T19:29:43.897284Z","steps":["trace[841078187] 'process raft request' (duration: 104.516926ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:43.897366Z","caller":"traceutil/trace.go:171","msg":"trace[450456558] transaction","detail":"{read_only:false; response_revision:27589; number_of_response:1; }","duration":"105.289312ms","start":"2026-04-12T19:29:43.792066Z","end":"2026-04-12T19:29:43.897355Z","steps":["trace[450456558] 'process raft request' (duration: 104.471405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:43.897811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.836721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:43.897886Z","caller":"traceutil/trace.go:171","msg":"trace[1626544149] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27590; }","duration":"103.929103ms","start":"2026-04-12T19:29:43.793941Z","end":"2026-04-12T19:29:43.89787Z","steps":["trace[1626544149] 'agreement among raft nodes before linearized reading' (duration: 103.647007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:43.89791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.449994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:43.897946Z","caller":"traceutil/trace.go:171","msg":"trace[248268191] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27590; }","duration":"103.495054ms","start":"2026-04-12T19:29:43.794441Z","end":"2026-04-12T19:29:43.897936Z","steps":["trace[248268191] 'agreement among raft nodes before linearized reading' (duration: 103.441023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:43.898072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.61998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:43.899008Z","caller":"traceutil/trace.go:171","msg":"trace[103332881] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27590; }","duration":"106.564729ms","start":"2026-04-12T19:29:43.792431Z","end":"2026-04-12T19:29:43.898996Z","steps":["trace[103332881] 'agreement among raft nodes before linearized reading' (duration: 105.591739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:43.898107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.556508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:43.899078Z","caller":"traceutil/trace.go:171","msg":"trace[1709663408] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27590; }","duration":"106.515488ms","start":"2026-04-12T19:29:43.792532Z","end":"2026-04-12T19:29:43.899047Z","steps":["trace[1709663408] 'agreement among raft nodes before linearized reading' (duration: 105.495417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:43.89819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.496505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:43.899187Z","caller":"traceutil/trace.go:171","msg":"trace[658886059] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27590; }","duration":"105.487566ms","start":"2026-04-12T19:29:43.793678Z","end":"2026-04-12T19:29:43.899166Z","steps":["trace[658886059] 'agreement among raft nodes before linearized reading' (duration: 104.458304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:43.898235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.290793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:43.89927Z","caller":"traceutil/trace.go:171","msg":"trace[1159197843] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27590; }","duration":"107.330645ms","start":"2026-04-12T19:29:43.791927Z","end":"2026-04-12T19:29:43.899258Z","steps":["trace[1159197843] 'agreement among raft nodes before linearized reading' (duration: 106.293253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:43.898275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.721159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:43.899308Z","caller":"traceutil/trace.go:171","msg":"trace[1462204629] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27590; }","duration":"105.65283ms","start":"2026-04-12T19:29:43.793646Z","end":"2026-04-12T19:29:43.899299Z","steps":["trace[1462204629] 'agreement among raft nodes before linearized reading' (duration: 103.715228ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:45.107409Z","caller":"traceutil/trace.go:171","msg":"trace[1811732424] transaction","detail":"{read_only:false; response_revision:27597; number_of_response:1; }","duration":"139.304494ms","start":"2026-04-12T19:29:44.968078Z","end":"2026-04-12T19:29:45.107382Z","steps":["trace[1811732424] 'process raft request' (duration: 139.14196ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:45.170205Z","caller":"traceutil/trace.go:171","msg":"trace[1801828334] transaction","detail":"{read_only:false; response_revision:27598; number_of_response:1; }","duration":"186.880243ms","start":"2026-04-12T19:29:44.983286Z","end":"2026-04-12T19:29:45.170166Z","steps":["trace[1801828334] 'process raft request' (duration: 186.661678ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:47.802569Z","caller":"traceutil/trace.go:171","msg":"trace[539221024] transaction","detail":"{read_only:false; response_revision:27615; number_of_response:1; }","duration":"100.702115ms","start":"2026-04-12T19:29:47.701823Z","end":"2026-04-12T19:29:47.802525Z","steps":["trace[539221024] 'process raft request' (duration: 55.270842ms)","trace[539221024] 'compare' (duration: 44.841131ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:48.672497Z","caller":"traceutil/trace.go:171","msg":"trace[921213488] transaction","detail":"{read_only:false; response_revision:27622; number_of_response:1; }","duration":"112.487375ms","start":"2026-04-12T19:29:48.55999Z","end":"2026-04-12T19:29:48.672477Z","steps":["trace[921213488] 'process raft request' (duration: 112.371382ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:50.183883Z","caller":"traceutil/trace.go:171","msg":"trace[2013908988] transaction","detail":"{read_only:false; response_revision:27631; number_of_response:1; }","duration":"267.998492ms","start":"2026-04-12T19:29:49.915845Z","end":"2026-04-12T19:29:50.183843Z","steps":["trace[2013908988] 'process raft request' (duration: 267.746307ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:50.184176Z","caller":"traceutil/trace.go:171","msg":"trace[928109217] transaction","detail":"{read_only:false; response_revision:27632; number_of_response:1; }","duration":"265.893067ms","start":"2026-04-12T19:29:49.918251Z","end":"2026-04-12T19:29:50.184144Z","steps":["trace[928109217] 'process raft request' (duration: 265.54343ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:50.184233Z","caller":"traceutil/trace.go:171","msg":"trace[96321891] linearizableReadLoop","detail":"{readStateIndex:28513; appliedIndex:28511; }","duration":"266.121121ms","start":"2026-04-12T19:29:49.918084Z","end":"2026-04-12T19:29:50.184205Z","steps":["trace[96321891] 'read index received' (duration: 3.554125ms)","trace[96321891] 'applied index is now lower than readState.Index' (duration: 262.565126ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:29:50.184324Z","caller":"traceutil/trace.go:171","msg":"trace[703055886] transaction","detail":"{read_only:false; response_revision:27634; number_of_response:1; }","duration":"264.293192ms","start":"2026-04-12T19:29:49.919999Z","end":"2026-04-12T19:29:50.184292Z","steps":["trace[703055886] 'process raft request' (duration: 264.16099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:50.184471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.364386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:50.184405Z","caller":"traceutil/trace.go:171","msg":"trace[520514534] transaction","detail":"{read_only:false; response_revision:27633; number_of_response:1; }","duration":"264.603509ms","start":"2026-04-12T19:29:49.919758Z","end":"2026-04-12T19:29:50.184362Z","steps":["trace[520514534] 'process raft request' (duration: 264.278572ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:50.18452Z","caller":"traceutil/trace.go:171","msg":"trace[2059684257] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27634; }","duration":"266.468319ms","start":"2026-04-12T19:29:49.918039Z","end":"2026-04-12T19:29:50.184508Z","steps":["trace[2059684257] 'agreement among raft nodes before linearized reading' (duration: 266.368567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:50.184528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.124229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:50.184558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.037668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:50.18456Z","caller":"traceutil/trace.go:171","msg":"trace[1428082537] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27634; }","duration":"264.16444ms","start":"2026-04-12T19:29:49.920386Z","end":"2026-04-12T19:29:50.18455Z","steps":["trace[1428082537] 'agreement among raft nodes before linearized reading' (duration: 264.125859ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:50.184583Z","caller":"traceutil/trace.go:171","msg":"trace[271830583] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27634; }","duration":"264.071278ms","start":"2026-04-12T19:29:49.920504Z","end":"2026-04-12T19:29:50.184576Z","steps":["trace[271830583] 'agreement among raft nodes before linearized reading' (duration: 264.037017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:50.184649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.522428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:50.184668Z","caller":"traceutil/trace.go:171","msg":"trace[1212304669] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27634; }","duration":"264.563178ms","start":"2026-04-12T19:29:49.9201Z","end":"2026-04-12T19:29:50.184663Z","steps":["trace[1212304669] 'agreement among raft nodes before linearized reading' (duration: 264.530477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:50.18472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.342504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:50.184746Z","caller":"traceutil/trace.go:171","msg":"trace[659489714] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27634; }","duration":"264.382585ms","start":"2026-04-12T19:29:49.920357Z","end":"2026-04-12T19:29:50.18474Z","steps":["trace[659489714] 'agreement among raft nodes before linearized reading' (duration: 264.270632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:50.184772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.494957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:50.184796Z","caller":"traceutil/trace.go:171","msg":"trace[1125819384] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27634; }","duration":"264.570289ms","start":"2026-04-12T19:29:49.92022Z","end":"2026-04-12T19:29:50.184791Z","steps":["trace[1125819384] 'agreement among raft nodes before linearized reading' (duration: 264.497427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:50.184489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.978116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:50.184843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.247663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:50.184835Z","caller":"traceutil/trace.go:171","msg":"trace[406158607] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27634; }","duration":"264.349084ms","start":"2026-04-12T19:29:49.920474Z","end":"2026-04-12T19:29:50.184823Z","steps":["trace[406158607] 'agreement among raft nodes before linearized reading' (duration: 263.971636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:50.184528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.943195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-12T19:29:50.18449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.383256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:50.184867Z","caller":"traceutil/trace.go:171","msg":"trace[468709739] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27634; }","duration":"265.288943ms","start":"2026-04-12T19:29:49.919572Z","end":"2026-04-12T19:29:50.184861Z","steps":["trace[468709739] 'agreement among raft nodes before linearized reading' (duration: 265.225082ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:50.184877Z","caller":"traceutil/trace.go:171","msg":"trace[1570657166] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27634; }","duration":"264.303123ms","start":"2026-04-12T19:29:49.920567Z","end":"2026-04-12T19:29:50.184871Z","steps":["trace[1570657166] 'agreement among raft nodes before linearized reading' (duration: 263.945446ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:50.184894Z","caller":"traceutil/trace.go:171","msg":"trace[857882934] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27634; }","duration":"265.838146ms","start":"2026-04-12T19:29:49.919043Z","end":"2026-04-12T19:29:50.184881Z","steps":["trace[857882934] 'agreement among raft nodes before linearized reading' (duration: 265.396506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:52.522872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.63132ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:52.523024Z","caller":"traceutil/trace.go:171","msg":"trace[977197306] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:27646; }","duration":"156.780104ms","start":"2026-04-12T19:29:52.366208Z","end":"2026-04-12T19:29:52.522988Z","steps":["trace[977197306] 'range keys from in-memory index tree' (duration: 156.5992ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:52.523433Z","caller":"traceutil/trace.go:171","msg":"trace[1809463889] transaction","detail":"{read_only:false; response_revision:27648; number_of_response:1; }","duration":"331.322504ms","start":"2026-04-12T19:29:52.192092Z","end":"2026-04-12T19:29:52.523415Z","steps":["trace[1809463889] 'process raft request' (duration: 331.235922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:52.523562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.192078Z","time spent":"331.414696ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T19:29:52.523601Z","caller":"traceutil/trace.go:171","msg":"trace[279115128] linearizableReadLoop","detail":"{readStateIndex:28532; appliedIndex:28529; }","duration":"237.28347ms","start":"2026-04-12T19:29:52.286279Z","end":"2026-04-12T19:29:52.523563Z","steps":["trace[279115128] 'read index received' (duration: 137.752491ms)","trace[279115128] 'applied index is now lower than readState.Index' (duration: 99.529349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:29:52.523661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.659924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:52.523679Z","caller":"traceutil/trace.go:171","msg":"trace[1008459853] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27649; }","duration":"333.698215ms","start":"2026-04-12T19:29:52.189975Z","end":"2026-04-12T19:29:52.523673Z","steps":["trace[1008459853] 'agreement among raft nodes before linearized reading' (duration: 333.648834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:52.523671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.130613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:52.523438Z","caller":"traceutil/trace.go:171","msg":"trace[1427251807] transaction","detail":"{read_only:false; response_revision:27647; number_of_response:1; }","duration":"333.332607ms","start":"2026-04-12T19:29:52.190079Z","end":"2026-04-12T19:29:52.523411Z","steps":["trace[1427251807] 'process raft request' (duration: 234.010722ms)","trace[1427251807] 'compare' (duration: 98.378035ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:29:52.523711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.677324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:52.523726Z","caller":"traceutil/trace.go:171","msg":"trace[2112339938] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27649; }","duration":"333.705955ms","start":"2026-04-12T19:29:52.190016Z","end":"2026-04-12T19:29:52.523722Z","steps":["trace[2112339938] 'agreement among raft nodes before linearized reading' (duration: 333.685374ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:29:52.523723Z","caller":"traceutil/trace.go:171","msg":"trace[998553597] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27649; }","duration":"333.198764ms","start":"2026-04-12T19:29:52.190507Z","end":"2026-04-12T19:29:52.523706Z","steps":["trace[998553597] 'agreement among raft nodes before linearized reading' (duration: 333.089512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:52.523689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.511478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:29:52.523752Z","caller":"traceutil/trace.go:171","msg":"trace[335328022] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27649; }","duration":"331.63237ms","start":"2026-04-12T19:29:52.192115Z","end":"2026-04-12T19:29:52.523748Z","steps":["trace[335328022] 'agreement among raft nodes before linearized reading' (duration: 331.460657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:52.523752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.190062Z","time spent":"333.664504ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T19:29:52.523756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.190496Z","time spent":"333.253825ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-04-12T19:29:52.523785Z","caller":"traceutil/trace.go:171","msg":"trace[1137151771] transaction","detail":"{read_only:false; response_revision:27649; number_of_response:1; }","duration":"331.463377ms","start":"2026-04-12T19:29:52.192309Z","end":"2026-04-12T19:29:52.523772Z","steps":["trace[1137151771] 'process raft request' (duration: 331.076969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:52.523737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.19001Z","time spent":"333.725015ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-04-12T19:29:52.523828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.231345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:29:52.523847Z","caller":"traceutil/trace.go:171","msg":"trace[977633482] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27649; }","duration":"333.264746ms","start":"2026-04-12T19:29:52.190575Z","end":"2026-04-12T19:29:52.52384Z","steps":["trace[977633482] 'agreement among raft nodes before linearized reading' (duration: 333.233505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:52.523866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.190566Z","time spent":"333.294456ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-04-12T19:29:52.523765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.192095Z","time spent":"331.665831ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5031,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-04-12T19:29:52.523694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.189966Z","time spent":"333.724025ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-04-12T19:29:52.523872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.192297Z","time spent":"331.530519ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T19:29:52.523922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.366848ms","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-04-12T19:29:52.52394Z","caller":"traceutil/trace.go:171","msg":"trace[834544850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27649; }","duration":"317.397419ms","start":"2026-04-12T19:29:52.206537Z","end":"2026-04-12T19:29:52.523935Z","steps":["trace[834544850] 'agreement among raft nodes before linearized reading' (duration: 317.350538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:29:52.523954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T19:29:52.206528Z","time spent":"317.423729ms","remote":"127.0.0.1:54152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-12T19:29:54.070463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.346458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:29:54.070578Z","caller":"traceutil/trace.go:171","msg":"trace[392354404] transaction","detail":"{read_only:false; response_revision:27658; number_of_response:1; }","duration":"137.462114ms","start":"2026-04-12T19:29:53.933092Z","end":"2026-04-12T19:29:54.070554Z","steps":["trace[392354404] 'process raft request' (duration: 27.939282ms)","trace[392354404] 'compare' (duration: 109.074273ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:30:04.680525Z","caller":"traceutil/trace.go:171","msg":"trace[1341794934] transaction","detail":"{read_only:false; response_revision:27750; number_of_response:1; }","duration":"119.235158ms","start":"2026-04-12T19:30:04.561256Z","end":"2026-04-12T19:30:04.680491Z","steps":["trace[1341794934] 'process raft request' (duration: 119.002794ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:30:04.68061Z","caller":"traceutil/trace.go:171","msg":"trace[925356094] transaction","detail":"{read_only:false; response_revision:27751; number_of_response:1; }","duration":"119.175657ms","start":"2026-04-12T19:30:04.561376Z","end":"2026-04-12T19:30:04.680552Z","steps":["trace[925356094] 'process raft request' (duration: 119.018213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:30:04.893826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.07364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-12T19:30:04.893929Z","caller":"traceutil/trace.go:171","msg":"trace[1072231173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27752; }","duration":"149.219673ms","start":"2026-04-12T19:30:04.744684Z","end":"2026-04-12T19:30:04.893904Z","steps":["trace[1072231173] 'range keys from in-memory index tree' (duration: 148.907796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:30:05.152502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.019646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-12T19:30:05.152671Z","caller":"traceutil/trace.go:171","msg":"trace[1408517403] transaction","detail":"{read_only:false; response_revision:27754; number_of_response:1; }","duration":"249.733154ms","start":"2026-04-12T19:30:04.902903Z","end":"2026-04-12T19:30:05.152636Z","steps":["trace[1408517403] 'process raft request' (duration: 144.476174ms)","trace[1408517403] 'compare' (duration: 104.652008ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:30:05.152768Z","caller":"traceutil/trace.go:171","msg":"trace[1699118528] transaction","detail":"{read_only:false; response_revision:27755; number_of_response:1; }","duration":"173.669972ms","start":"2026-04-12T19:30:04.979073Z","end":"2026-04-12T19:30:05.152743Z","steps":["trace[1699118528] 'process raft request' (duration: 173.56712ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:30:05.152814Z","caller":"traceutil/trace.go:171","msg":"trace[615504791] linearizableReadLoop","detail":"{readStateIndex:28639; appliedIndex:28638; }","duration":"178.575005ms","start":"2026-04-12T19:30:04.974202Z","end":"2026-04-12T19:30:05.152777Z","steps":["trace[615504791] 'read index received' (duration: 73.139891ms)","trace[615504791] 'applied index is now lower than readState.Index' (duration: 105.432194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:30:05.153265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.276335ms","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-04-12T19:30:05.153796Z","caller":"traceutil/trace.go:171","msg":"trace[904112028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27755; }","duration":"250.825978ms","start":"2026-04-12T19:30:04.90293Z","end":"2026-04-12T19:30:05.153756Z","steps":["trace[904112028] 'agreement among raft nodes before linearized reading' (duration: 250.224274ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:30:05.450911Z","caller":"traceutil/trace.go:171","msg":"trace[842958645] transaction","detail":"{read_only:false; response_revision:27757; number_of_response:1; }","duration":"193.605144ms","start":"2026-04-12T19:30:05.257247Z","end":"2026-04-12T19:30:05.450852Z","steps":["trace[842958645] 'process raft request' (duration: 117.538062ms)","trace[842958645] 'compare' (duration: 75.622453ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T19:30:05.734668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.380593ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-12T19:30:05.734964Z","caller":"traceutil/trace.go:171","msg":"trace[243897343] linearizableReadLoop","detail":"{readStateIndex:28644; appliedIndex:28642; }","duration":"131.892916ms","start":"2026-04-12T19:30:05.603052Z","end":"2026-04-12T19:30:05.734945Z","steps":["trace[243897343] 'read index received' (duration: 60.668386ms)","trace[243897343] 'applied index is now lower than readState.Index' (duration: 71.22309ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T19:30:05.735034Z","caller":"traceutil/trace.go:171","msg":"trace[34278194] transaction","detail":"{read_only:false; response_revision:27758; number_of_response:1; }","duration":"142.208836ms","start":"2026-04-12T19:30:05.592779Z","end":"2026-04-12T19:30:05.734988Z","steps":["trace[34278194] 'process raft request' (duration: 142.006761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:30:05.735135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.0613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:30:05.735218Z","caller":"traceutil/trace.go:171","msg":"trace[456761689] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27758; }","duration":"132.192922ms","start":"2026-04-12T19:30:05.603004Z","end":"2026-04-12T19:30:05.735197Z","steps":["trace[456761689] 'agreement among raft nodes before linearized reading' (duration: 132.037899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:30:07.025456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.491181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:30:07.025528Z","caller":"traceutil/trace.go:171","msg":"trace[418609290] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27771; }","duration":"126.612804ms","start":"2026-04-12T19:30:06.898901Z","end":"2026-04-12T19:30:07.025513Z","steps":["trace[418609290] 'range keys from in-memory index tree' (duration: 126.406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:30:07.025698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.619894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:30:07.025714Z","caller":"traceutil/trace.go:171","msg":"trace[1399794083] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27771; }","duration":"126.687235ms","start":"2026-04-12T19:30:06.89902Z","end":"2026-04-12T19:30:07.025708Z","steps":["trace[1399794083] 'range keys from in-memory index tree' (duration: 126.520652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:30:07.025798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.739357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-12T19:30:07.025811Z","caller":"traceutil/trace.go:171","msg":"trace[1826032760] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27771; }","duration":"126.791957ms","start":"2026-04-12T19:30:06.899014Z","end":"2026-04-12T19:30:07.025806Z","steps":["trace[1826032760] 'range keys from in-memory index tree' (duration: 126.589784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T19:30:07.025954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.963297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-12T19:30:07.025967Z","caller":"traceutil/trace.go:171","msg":"trace[1922202146] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27771; }","duration":"123.020758ms","start":"2026-04-12T19:30:06.902942Z","end":"2026-04-12T19:30:07.025963Z","steps":["trace[1922202146] 'range keys from in-memory index tree' (duration: 122.827884ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T19:30:56.997923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25908} {"level":"info","ts":"2026-04-12T19:30:57.143022Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25908,"took":"139.324874ms","hash":108898463,"current-db-size-bytes":58626048,"current-db-size":"59 MB","current-db-size-in-use-bytes":30334976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-12T19:30:57.143095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":108898463,"revision":25908,"compact-revision":22863} {"level":"info","ts":"2026-04-12T19:32:29.659179Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a11c20306510c1f7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-12T19:32:29.664392Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-12T19:32:29.664504Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-12T19:35:57.004669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28230} {"level":"info","ts":"2026-04-12T19:35:57.131741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28230,"took":"123.293796ms","hash":4154848995,"current-db-size-bytes":58626048,"current-db-size":"59 MB","current-db-size-in-use-bytes":34664448,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-12T19:35:57.131902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4154848995,"revision":28230,"compact-revision":25908} {"level":"info","ts":"2026-04-12T19:40:57.032863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30873} {"level":"info","ts":"2026-04-12T19:40:57.315003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30873,"took":"276.338033ms","hash":1986148459,"current-db-size-bytes":58626048,"current-db-size":"59 MB","current-db-size-in-use-bytes":30171136,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-12T19:40:57.315121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1986148459,"revision":30873,"compact-revision":28230} {"level":"info","ts":"2026-04-12T19:45:57.044776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33138} {"level":"info","ts":"2026-04-12T19:45:57.208049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33138,"took":"158.344697ms","hash":900252523,"current-db-size-bytes":59670528,"current-db-size":"60 MB","current-db-size-in-use-bytes":29753344,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-12T19:45:57.208239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":900252523,"revision":33138,"compact-revision":30873} {"level":"info","ts":"2026-04-12T19:50:57.058979Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35373} {"level":"info","ts":"2026-04-12T19:50:57.207239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35373,"took":"141.971329ms","hash":2431310640,"current-db-size-bytes":59670528,"current-db-size":"60 MB","current-db-size-in-use-bytes":31850496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-12T19:50:57.207308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2431310640,"revision":35373,"compact-revision":33138} {"level":"info","ts":"2026-04-12T19:53:25.678949Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a11c20306510c1f7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-12T19:53:25.684647Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-12T19:53:25.684787Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-12T19:55:57.066291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37668} {"level":"info","ts":"2026-04-12T19:55:57.191747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37668,"took":"119.398739ms","hash":2375404181,"current-db-size-bytes":59670528,"current-db-size":"60 MB","current-db-size-in-use-bytes":32223232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-12T19:55:57.191911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2375404181,"revision":37668,"compact-revision":35373}