{"level":"warn","ts":"2026-07-08T23:33:25.950871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T23:33:25.950977Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-07-08T23:33:25.951044Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T23:33:25.951051Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-08T23:33:25.951071Z","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-07-08T23:33:25.951714Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-07-08T23:33:25.951791Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-07-08T23:33:25.956299Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.294745ms"} {"level":"info","ts":"2026-07-08T23:33:25.963408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-07-08T23:33:25.963502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T23:33:25.963528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-07-08T23:33:25.963545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T23:33:25.963561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-07-08T23:33:25.963602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-07-08T23:33:25.969727Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T23:33:25.971226Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T23:33:25.972399Z","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-07-08T23:33:25.975386Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T23:33:25.975553Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T23:33:25.975856Z","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-07-08T23:33:25.975981Z","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-07-08T23:33:25.975998Z","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-07-08T23:33:25.978685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-07-08T23:33:25.978842Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-08T23:33:25.979809Z","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-07-08T23:33:25.979909Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-08T23:33:25.979947Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-08T23:33:25.980438Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T23:33:25.980472Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T23:33:26.764802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T23:33:26.764889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T23:33:26.764916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-07-08T23:33:26.764931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-07-08T23:33:26.764989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-08T23:33:26.765001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-07-08T23:33:26.765013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-08T23:33:26.766121Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T23:33:26.766205Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T23:33:26.76632Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:33:26.766342Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T23:33:26.76855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-07-08T23:33:26.770506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T23:33:26.777521Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:33:26.77761Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:33:26.777632Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T23:33:26.787376Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T23:33:26.787497Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T23:34:09.493073Z","caller":"traceutil/trace.go:171","msg":"trace[412571535] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"332.37877ms","start":"2026-07-08T23:34:09.160666Z","end":"2026-07-08T23:34:09.493045Z","steps":["trace[412571535] 'process raft request' (duration: 332.239847ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:34:09.493334Z","caller":"traceutil/trace.go:171","msg":"trace[697813344] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"114.238158ms","start":"2026-07-08T23:34:09.37907Z","end":"2026-07-08T23:34:09.493308Z","steps":["trace[697813344] 'read index received' (duration: 113.94475ms)","trace[697813344] 'applied index is now lower than readState.Index' (duration: 291.658µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:34:09.493584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.493614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:34:09.493696Z","caller":"traceutil/trace.go:171","msg":"trace[289428079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"114.647828ms","start":"2026-07-08T23:34:09.379036Z","end":"2026-07-08T23:34:09.493684Z","steps":["trace[289428079] 'agreement among raft nodes before linearized reading' (duration: 114.419283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:34:09.493703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:34:09.160635Z","time spent":"332.535805ms","remote":"127.0.0.1:48848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T23:34:09.838055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.744018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:34:09.838172Z","caller":"traceutil/trace.go:171","msg":"trace[950965988] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"334.128634ms","start":"2026-07-08T23:34:09.504027Z","end":"2026-07-08T23:34:09.838156Z","steps":["trace[950965988] 'read index received' (duration: 33.845392ms)","trace[950965988] 'applied index is now lower than readState.Index' (duration: 300.282072ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:34:09.838207Z","caller":"traceutil/trace.go:171","msg":"trace[726940315] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"340.002699ms","start":"2026-07-08T23:34:09.498179Z","end":"2026-07-08T23:34:09.838181Z","steps":["trace[726940315] 'process raft request' (duration: 39.807839ms)","trace[726940315] 'compare' (duration: 299.595824ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:34:09.838335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:34:09.498153Z","time spent":"340.113721ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:34:09.838381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.146041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p5kl6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-08T23:34:09.838421Z","caller":"traceutil/trace.go:171","msg":"trace[1852925294] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p5kl6; range_end:; response_count:1; response_revision:591; }","duration":"296.212482ms","start":"2026-07-08T23:34:09.542199Z","end":"2026-07-08T23:34:09.838412Z","steps":["trace[1852925294] 'agreement among raft nodes before linearized reading' (duration: 296.059259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:34:09.83841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.32783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:34:09.838548Z","caller":"traceutil/trace.go:171","msg":"trace[1844884280] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:591; }","duration":"334.518114ms","start":"2026-07-08T23:34:09.503999Z","end":"2026-07-08T23:34:09.838517Z","steps":["trace[1844884280] 'agreement among raft nodes before linearized reading' (duration: 334.222707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:34:09.838606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:34:09.503983Z","time spent":"334.612256ms","remote":"127.0.0.1:48978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-07-08T23:34:09.982503Z","caller":"traceutil/trace.go:171","msg":"trace[1653432047] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"131.352479ms","start":"2026-07-08T23:34:09.851121Z","end":"2026-07-08T23:34:09.982474Z","steps":["trace[1653432047] 'process raft request' (duration: 131.250937ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:34:09.982576Z","caller":"traceutil/trace.go:171","msg":"trace[1647642471] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"140.093464ms","start":"2026-07-08T23:34:09.84245Z","end":"2026-07-08T23:34:09.982543Z","steps":["trace[1647642471] 'process raft request' (duration: 139.750295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:34:24.992716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.632234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:34:24.992855Z","caller":"traceutil/trace.go:171","msg":"trace[973380571] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"250.559537ms","start":"2026-07-08T23:34:24.74227Z","end":"2026-07-08T23:34:24.99283Z","steps":["trace[973380571] 'process raft request' (duration: 89.702337ms)","trace[973380571] 'compare' (duration: 160.48036ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:35:42.417906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.153654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:35:42.418025Z","caller":"traceutil/trace.go:171","msg":"trace[1414759666] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1891; }","duration":"374.99894ms","start":"2026-07-08T23:35:42.043009Z","end":"2026-07-08T23:35:42.418007Z","steps":["trace[1414759666] 'read index received' (duration: 53.63154ms)","trace[1414759666] 'applied index is now lower than readState.Index' (duration: 321.36542ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:35:42.418054Z","caller":"traceutil/trace.go:171","msg":"trace[326936746] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"383.433047ms","start":"2026-07-08T23:35:42.034594Z","end":"2026-07-08T23:35:42.418027Z","steps":["trace[326936746] 'process raft request' (duration: 62.033886ms)","trace[326936746] 'compare' (duration: 320.973189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:35:42.418192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:35:42.034564Z","time spent":"383.554789ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T23:35:42.41822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.214385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49cd4673-af8b-4e65-9271-5fdd8ceec1f5\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-08T23:35:42.418256Z","caller":"traceutil/trace.go:171","msg":"trace[1049105570] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49cd4673-af8b-4e65-9271-5fdd8ceec1f5; range_end:; response_count:1; response_revision:1815; }","duration":"375.281897ms","start":"2026-07-08T23:35:42.042962Z","end":"2026-07-08T23:35:42.418244Z","steps":["trace[1049105570] 'agreement among raft nodes before linearized reading' (duration: 375.133443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:35:42.418289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T23:35:42.042944Z","time spent":"375.337268ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49cd4673-af8b-4e65-9271-5fdd8ceec1f5\" "} {"level":"warn","ts":"2026-07-08T23:35:42.418479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.895757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-08T23:35:42.418557Z","caller":"traceutil/trace.go:171","msg":"trace[166907176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1815; }","duration":"115.02443ms","start":"2026-07-08T23:35:42.303509Z","end":"2026-07-08T23:35:42.418534Z","steps":["trace[166907176] 'agreement among raft nodes before linearized reading' (duration: 114.826605ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:35:42.600144Z","caller":"traceutil/trace.go:171","msg":"trace[585599284] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"173.721433ms","start":"2026-07-08T23:35:42.426388Z","end":"2026-07-08T23:35:42.60011Z","steps":["trace[585599284] 'process raft request' (duration: 173.58481ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:35:42.600207Z","caller":"traceutil/trace.go:171","msg":"trace[948331079] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"175.734513ms","start":"2026-07-08T23:35:42.424428Z","end":"2026-07-08T23:35:42.600163Z","steps":["trace[948331079] 'process raft request' (duration: 175.360474ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:35:42.779516Z","caller":"traceutil/trace.go:171","msg":"trace[1556988415] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"174.384841ms","start":"2026-07-08T23:35:42.605086Z","end":"2026-07-08T23:35:42.779471Z","steps":["trace[1556988415] 'process raft request' (duration: 109.403012ms)","trace[1556988415] 'compare' (duration: 64.782384ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:26.936989Z","caller":"traceutil/trace.go:171","msg":"trace[45837988] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"121.868834ms","start":"2026-07-08T23:36:26.81509Z","end":"2026-07-08T23:36:26.936959Z","steps":["trace[45837988] 'process raft request' (duration: 121.71689ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:27.213756Z","caller":"traceutil/trace.go:171","msg":"trace[1723054744] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"100.555908ms","start":"2026-07-08T23:36:27.113168Z","end":"2026-07-08T23:36:27.213724Z","steps":["trace[1723054744] 'read index received' (duration: 61.46047ms)","trace[1723054744] 'applied index is now lower than readState.Index' (duration: 39.094008ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:36:27.213804Z","caller":"traceutil/trace.go:171","msg":"trace[1855735638] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"118.203025ms","start":"2026-07-08T23:36:27.095568Z","end":"2026-07-08T23:36:27.213771Z","steps":["trace[1855735638] 'process raft request' (duration: 79.032955ms)","trace[1855735638] 'compare' (duration: 38.994766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:36:27.213978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.788183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-08T23:36:27.214051Z","caller":"traceutil/trace.go:171","msg":"trace[189321382] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2116; }","duration":"100.892206ms","start":"2026-07-08T23:36:27.113124Z","end":"2026-07-08T23:36:27.214017Z","steps":["trace[189321382] 'agreement among raft nodes before linearized reading' (duration: 100.709392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:36:27.830759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.344517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-08T23:36:27.830868Z","caller":"traceutil/trace.go:171","msg":"trace[2132725077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2117; }","duration":"177.508272ms","start":"2026-07-08T23:36:27.653336Z","end":"2026-07-08T23:36:27.830844Z","steps":["trace[2132725077] 'range keys from in-memory index tree' (duration: 177.101692ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:36:33.091311Z","caller":"traceutil/trace.go:171","msg":"trace[313696872] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"125.04707ms","start":"2026-07-08T23:36:32.966226Z","end":"2026-07-08T23:36:33.091273Z","steps":["trace[313696872] 'process raft request' (duration: 124.687032ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:40:37.574702Z","caller":"traceutil/trace.go:171","msg":"trace[802071249] transaction","detail":"{read_only:false; response_revision:3831; number_of_response:1; }","duration":"108.286686ms","start":"2026-07-08T23:40:37.466388Z","end":"2026-07-08T23:40:37.574675Z","steps":["trace[802071249] 'process raft request' (duration: 108.140463ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:39.250712Z","caller":"traceutil/trace.go:171","msg":"trace[1638325124] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"115.146565ms","start":"2026-07-08T23:42:39.135527Z","end":"2026-07-08T23:42:39.250673Z","steps":["trace[1638325124] 'process raft request' (duration: 115.046963ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:43.379224Z","caller":"traceutil/trace.go:171","msg":"trace[172365050] linearizableReadLoop","detail":"{readStateIndex:5446; appliedIndex:5445; }","duration":"127.111488ms","start":"2026-07-08T23:42:43.252085Z","end":"2026-07-08T23:42:43.379196Z","steps":["trace[172365050] 'read index received' (duration: 126.834541ms)","trace[172365050] 'applied index is now lower than readState.Index' (duration: 275.957µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:42:43.379376Z","caller":"traceutil/trace.go:171","msg":"trace[897460538] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"184.793069ms","start":"2026-07-08T23:42:43.194528Z","end":"2026-07-08T23:42:43.379321Z","steps":["trace[897460538] 'process raft request' (duration: 184.466011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:42:43.379525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.422205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b4e5e5a-4bd0-45d4-94e5-a094126b8dd6\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-08T23:42:43.3796Z","caller":"traceutil/trace.go:171","msg":"trace[2139783256] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0b4e5e5a-4bd0-45d4-94e5-a094126b8dd6; range_end:; response_count:1; response_revision:5237; }","duration":"127.536528ms","start":"2026-07-08T23:42:43.252039Z","end":"2026-07-08T23:42:43.379575Z","steps":["trace[2139783256] 'agreement among raft nodes before linearized reading' (duration: 127.304833ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:43.524089Z","caller":"traceutil/trace.go:171","msg":"trace[542027103] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"136.730543ms","start":"2026-07-08T23:42:43.387321Z","end":"2026-07-08T23:42:43.524052Z","steps":["trace[542027103] 'process raft request' (duration: 136.445566ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:43.529894Z","caller":"traceutil/trace.go:171","msg":"trace[375757712] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"138.656371ms","start":"2026-07-08T23:42:43.391209Z","end":"2026-07-08T23:42:43.529865Z","steps":["trace[375757712] 'process raft request' (duration: 138.521668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:42:43.904448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.359922ms","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-07-08T23:42:43.904614Z","caller":"traceutil/trace.go:171","msg":"trace[805070942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5241; }","duration":"111.597839ms","start":"2026-07-08T23:42:43.79298Z","end":"2026-07-08T23:42:43.904578Z","steps":["trace[805070942] 'range keys from in-memory index tree' (duration: 111.028214ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:56.911218Z","caller":"traceutil/trace.go:171","msg":"trace[1313603532] linearizableReadLoop","detail":"{readStateIndex:5709; appliedIndex:5708; }","duration":"263.934921ms","start":"2026-07-08T23:42:56.647226Z","end":"2026-07-08T23:42:56.911161Z","steps":["trace[1313603532] 'read index received' (duration: 228.554658ms)","trace[1313603532] 'applied index is now lower than readState.Index' (duration: 35.378693ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:42:56.911603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.358243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:42:56.911837Z","caller":"traceutil/trace.go:171","msg":"trace[500533857] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"264.982138ms","start":"2026-07-08T23:42:56.64656Z","end":"2026-07-08T23:42:56.911542Z","steps":["trace[500533857] 'process raft request' (duration: 229.225425ms)","trace[500533857] 'compare' (duration: 35.207449ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:42:56.91313Z","caller":"traceutil/trace.go:171","msg":"trace[916934754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5491; }","duration":"264.681181ms","start":"2026-07-08T23:42:56.647218Z","end":"2026-07-08T23:42:56.911899Z","steps":["trace[916934754] 'agreement among raft nodes before linearized reading' (duration: 264.23031ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:57.169639Z","caller":"traceutil/trace.go:171","msg":"trace[1669487660] linearizableReadLoop","detail":"{readStateIndex:5710; appliedIndex:5709; }","duration":"127.115043ms","start":"2026-07-08T23:42:57.042492Z","end":"2026-07-08T23:42:57.169607Z","steps":["trace[1669487660] 'read index received' (duration: 27.838959ms)","trace[1669487660] 'applied index is now lower than readState.Index' (duration: 99.275074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:42:57.170005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.494684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-08T23:42:57.169989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.488782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T23:42:57.170034Z","caller":"traceutil/trace.go:171","msg":"trace[1383000600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5492; }","duration":"125.596786ms","start":"2026-07-08T23:42:57.04443Z","end":"2026-07-08T23:42:57.170027Z","steps":["trace[1383000600] 'agreement among raft nodes before linearized reading' (duration: 125.457302ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:57.170058Z","caller":"traceutil/trace.go:171","msg":"trace[654051208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5492; }","duration":"127.612925ms","start":"2026-07-08T23:42:57.042427Z","end":"2026-07-08T23:42:57.17004Z","steps":["trace[654051208] 'agreement among raft nodes before linearized reading' (duration: 127.331828ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:42:57.17012Z","caller":"traceutil/trace.go:171","msg":"trace[489926988] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"252.610185ms","start":"2026-07-08T23:42:56.917463Z","end":"2026-07-08T23:42:57.170073Z","steps":["trace[489926988] 'process raft request' (duration: 152.84968ms)","trace[489926988] 'compare' (duration: 98.898854ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:43:26.799309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2958} {"level":"info","ts":"2026-07-08T23:43:26.872989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2958,"took":"71.920935ms","hash":1819554512,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":32190464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T23:43:26.87308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1819554512,"revision":2958,"compact-revision":-1} {"level":"info","ts":"2026-07-08T23:43:57.861127Z","caller":"traceutil/trace.go:171","msg":"trace[751361958] transaction","detail":"{read_only:false; response_revision:6164; number_of_response:1; }","duration":"136.094692ms","start":"2026-07-08T23:43:57.725003Z","end":"2026-07-08T23:43:57.861098Z","steps":["trace[751361958] 'process raft request' (duration: 135.791394ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:48:26.80696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5878} {"level":"info","ts":"2026-07-08T23:48:26.886841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5878,"took":"78.312186ms","hash":216461094,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":24842240,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-08T23:48:26.886901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":216461094,"revision":5878,"compact-revision":2958} {"level":"info","ts":"2026-07-08T23:49:11.223648Z","caller":"traceutil/trace.go:171","msg":"trace[10375736] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"153.001385ms","start":"2026-07-08T23:49:11.070613Z","end":"2026-07-08T23:49:11.223614Z","steps":["trace[10375736] 'process raft request' (duration: 152.695778ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:49:11.406344Z","caller":"traceutil/trace.go:171","msg":"trace[1868262549] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"177.565271ms","start":"2026-07-08T23:49:11.228721Z","end":"2026-07-08T23:49:11.406287Z","steps":["trace[1868262549] 'process raft request' (duration: 164.288168ms)","trace[1868262549] 'compare' (duration: 12.993666ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:49:11.406472Z","caller":"traceutil/trace.go:171","msg":"trace[306242057] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"177.544881ms","start":"2026-07-08T23:49:11.2288Z","end":"2026-07-08T23:49:11.406345Z","steps":["trace[306242057] 'process raft request' (duration: 177.423458ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:49:11.406557Z","caller":"traceutil/trace.go:171","msg":"trace[707477432] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"177.694105ms","start":"2026-07-08T23:49:11.228831Z","end":"2026-07-08T23:49:11.406525Z","steps":["trace[707477432] 'process raft request' (duration: 177.455739ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:49:22.974128Z","caller":"traceutil/trace.go:171","msg":"trace[772029696] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"126.457315ms","start":"2026-07-08T23:49:22.847648Z","end":"2026-07-08T23:49:22.974105Z","steps":["trace[772029696] 'process raft request' (duration: 126.314772ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:49:23.169328Z","caller":"traceutil/trace.go:171","msg":"trace[1654816669] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"187.620062ms","start":"2026-07-08T23:49:22.981671Z","end":"2026-07-08T23:49:23.169291Z","steps":["trace[1654816669] 'process raft request' (duration: 132.933232ms)","trace[1654816669] 'compare' (duration: 54.536886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:49:23.999789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.020253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-08T23:49:23.999885Z","caller":"traceutil/trace.go:171","msg":"trace[908318527] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8553; }","duration":"105.156826ms","start":"2026-07-08T23:49:23.894706Z","end":"2026-07-08T23:49:23.999863Z","steps":["trace[908318527] 'range keys from in-memory index tree' (duration: 104.777327ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:49:24.571105Z","caller":"traceutil/trace.go:171","msg":"trace[1556445432] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"120.554732ms","start":"2026-07-08T23:49:24.450521Z","end":"2026-07-08T23:49:24.571076Z","steps":["trace[1556445432] 'process raft request' (duration: 120.357597ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:49:52.839959Z","caller":"traceutil/trace.go:171","msg":"trace[274277224] linearizableReadLoop","detail":"{readStateIndex:9083; appliedIndex:9082; }","duration":"138.880937ms","start":"2026-07-08T23:49:52.701041Z","end":"2026-07-08T23:49:52.839922Z","steps":["trace[274277224] 'read index received' (duration: 138.690203ms)","trace[274277224] 'applied index is now lower than readState.Index' (duration: 189.724µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:49:52.840002Z","caller":"traceutil/trace.go:171","msg":"trace[1618663662] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"160.589374ms","start":"2026-07-08T23:49:52.679387Z","end":"2026-07-08T23:49:52.839976Z","steps":["trace[1618663662] 'process raft request' (duration: 160.376799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:49:52.840146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.075062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:49:52.840172Z","caller":"traceutil/trace.go:171","msg":"trace[178852907] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8760; }","duration":"139.184414ms","start":"2026-07-08T23:49:52.70098Z","end":"2026-07-08T23:49:52.840164Z","steps":["trace[178852907] 'agreement among raft nodes before linearized reading' (duration: 139.103842ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:50:05.320687Z","caller":"traceutil/trace.go:171","msg":"trace[680428981] transaction","detail":"{read_only:false; response_revision:8867; number_of_response:1; }","duration":"129.177323ms","start":"2026-07-08T23:50:05.191445Z","end":"2026-07-08T23:50:05.320622Z","steps":["trace[680428981] 'process raft request' (duration: 107.571817ms)","trace[680428981] 'compare' (duration: 21.3875ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:50:05.320727Z","caller":"traceutil/trace.go:171","msg":"trace[814036610] linearizableReadLoop","detail":"{readStateIndex:9196; appliedIndex:9195; }","duration":"109.38568ms","start":"2026-07-08T23:50:05.2113Z","end":"2026-07-08T23:50:05.320686Z","steps":["trace[814036610] 'read index received' (duration: 87.788655ms)","trace[814036610] 'applied index is now lower than readState.Index' (duration: 21.593125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:50:05.32099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.608055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T23:50:05.3212Z","caller":"traceutil/trace.go:171","msg":"trace[172168067] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8868; }","duration":"109.928053ms","start":"2026-07-08T23:50:05.21125Z","end":"2026-07-08T23:50:05.321178Z","steps":["trace[172168067] 'agreement among raft nodes before linearized reading' (duration: 109.562004ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:50:05.890457Z","caller":"traceutil/trace.go:171","msg":"trace[1979450134] transaction","detail":"{read_only:false; response_revision:8872; number_of_response:1; }","duration":"127.367437ms","start":"2026-07-08T23:50:05.763062Z","end":"2026-07-08T23:50:05.89043Z","steps":["trace[1979450134] 'process raft request' (duration: 127.205503ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:50:06.033701Z","caller":"traceutil/trace.go:171","msg":"trace[1894495107] linearizableReadLoop","detail":"{readStateIndex:9202; appliedIndex:9200; }","duration":"266.07154ms","start":"2026-07-08T23:50:05.76759Z","end":"2026-07-08T23:50:06.033662Z","steps":["trace[1894495107] 'read index received' (duration: 122.751025ms)","trace[1894495107] 'applied index is now lower than readState.Index' (duration: 143.319105ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:50:06.033793Z","caller":"traceutil/trace.go:171","msg":"trace[400518895] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"269.270238ms","start":"2026-07-08T23:50:05.764471Z","end":"2026-07-08T23:50:06.033741Z","steps":["trace[400518895] 'process raft request' (duration: 228.840395ms)","trace[400518895] 'compare' (duration: 40.171517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:50:06.034044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.410809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:50:06.034113Z","caller":"traceutil/trace.go:171","msg":"trace[434872270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8873; }","duration":"266.528871ms","start":"2026-07-08T23:50:05.767566Z","end":"2026-07-08T23:50:06.034095Z","steps":["trace[434872270] 'agreement among raft nodes before linearized reading' (duration: 266.257505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:50:07.059135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.808204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-07-08T23:50:07.059234Z","caller":"traceutil/trace.go:171","msg":"trace[1138993019] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8880; }","duration":"249.948948ms","start":"2026-07-08T23:50:06.809257Z","end":"2026-07-08T23:50:07.059205Z","steps":["trace[1138993019] 'range keys from in-memory index tree' (duration: 249.61458ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:50:07.059254Z","caller":"traceutil/trace.go:171","msg":"trace[460650737] transaction","detail":"{read_only:false; response_revision:8881; number_of_response:1; }","duration":"149.461214ms","start":"2026-07-08T23:50:06.909764Z","end":"2026-07-08T23:50:07.059225Z","steps":["trace[460650737] 'process raft request' (duration: 119.826984ms)","trace[460650737] 'compare' (duration: 29.231081ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:50:47.738654Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002579.wal"} {"level":"warn","ts":"2026-07-08T23:51:22.717307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.098804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T23:51:22.71746Z","caller":"traceutil/trace.go:171","msg":"trace[1823548110] linearizableReadLoop","detail":"{readStateIndex:9973; appliedIndex:9972; }","duration":"193.411864ms","start":"2026-07-08T23:51:22.524006Z","end":"2026-07-08T23:51:22.717418Z","steps":["trace[1823548110] 'read index received' (duration: 19.033952ms)","trace[1823548110] 'applied index is now lower than readState.Index' (duration: 174.376712ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:51:22.717624Z","caller":"traceutil/trace.go:171","msg":"trace[692873972] transaction","detail":"{read_only:false; response_revision:9625; number_of_response:1; }","duration":"289.199343ms","start":"2026-07-08T23:51:22.428348Z","end":"2026-07-08T23:51:22.717548Z","steps":["trace[692873972] 'process raft request' (duration: 114.75401ms)","trace[692873972] 'compare' (duration: 173.956001ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T23:51:22.717792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.12893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T23:51:22.717916Z","caller":"traceutil/trace.go:171","msg":"trace[2071375918] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:9625; }","duration":"180.358506ms","start":"2026-07-08T23:51:22.537533Z","end":"2026-07-08T23:51:22.717892Z","steps":["trace[2071375918] 'agreement among raft nodes before linearized reading' (duration: 180.1248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:51:22.718131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.12723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T23:51:22.718189Z","caller":"traceutil/trace.go:171","msg":"trace[133284499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9625; }","duration":"194.265034ms","start":"2026-07-08T23:51:22.523909Z","end":"2026-07-08T23:51:22.718174Z","steps":["trace[133284499] 'agreement among raft nodes before linearized reading' (duration: 193.666719ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:51:26.7487Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T23:51:26.755232Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-08T23:51:26.755383Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-08T23:53:26.817744Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8045} {"level":"info","ts":"2026-07-08T23:53:26.880312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8045,"took":"59.459865ms","hash":716563602,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":28434432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-08T23:53:26.880388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":716563602,"revision":8045,"compact-revision":5878} {"level":"info","ts":"2026-07-08T23:55:11.736208Z","caller":"traceutil/trace.go:171","msg":"trace[1253844347] linearizableReadLoop","detail":"{readStateIndex:11911; appliedIndex:11910; }","duration":"170.40612ms","start":"2026-07-08T23:55:11.565778Z","end":"2026-07-08T23:55:11.736184Z","steps":["trace[1253844347] 'read index received' (duration: 170.216216ms)","trace[1253844347] 'applied index is now lower than readState.Index' (duration: 188.134µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T23:55:11.736368Z","caller":"traceutil/trace.go:171","msg":"trace[1870320776] transaction","detail":"{read_only:false; response_revision:11506; number_of_response:1; }","duration":"175.78029ms","start":"2026-07-08T23:55:11.560578Z","end":"2026-07-08T23:55:11.736358Z","steps":["trace[1870320776] 'process raft request' (duration: 175.436312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T23:55:11.737007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.204379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T23:55:11.737049Z","caller":"traceutil/trace.go:171","msg":"trace[750752599] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:11506; }","duration":"171.295702ms","start":"2026-07-08T23:55:11.565742Z","end":"2026-07-08T23:55:11.737037Z","steps":["trace[750752599] 'agreement among raft nodes before linearized reading' (duration: 171.150308ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T23:58:26.825603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10517} {"level":"info","ts":"2026-07-08T23:58:26.904356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10517,"took":"75.899926ms","hash":176229172,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":27832320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-08T23:58:26.904427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":176229172,"revision":10517,"compact-revision":8045} {"level":"info","ts":"2026-07-09T00:01:03.737381Z","caller":"traceutil/trace.go:171","msg":"trace[955718081] transaction","detail":"{read_only:false; response_revision:13940; number_of_response:1; }","duration":"109.924026ms","start":"2026-07-09T00:01:03.627426Z","end":"2026-07-09T00:01:03.73735Z","steps":["trace[955718081] 'process raft request' (duration: 91.951006ms)","trace[955718081] 'compare' (duration: 17.673713ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:01:40.137352Z","caller":"traceutil/trace.go:171","msg":"trace[2067042252] transaction","detail":"{read_only:false; response_revision:14133; number_of_response:1; }","duration":"128.381346ms","start":"2026-07-09T00:01:40.008932Z","end":"2026-07-09T00:01:40.137313Z","steps":["trace[2067042252] 'process raft request' (duration: 128.13579ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:01:47.820935Z","caller":"traceutil/trace.go:171","msg":"trace[1025715392] transaction","detail":"{read_only:false; response_revision:14187; number_of_response:1; }","duration":"137.647863ms","start":"2026-07-09T00:01:47.683259Z","end":"2026-07-09T00:01:47.820907Z","steps":["trace[1025715392] 'process raft request' (duration: 137.472829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:01:48.858196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.052867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T00:01:48.858359Z","caller":"traceutil/trace.go:171","msg":"trace[2077595791] transaction","detail":"{read_only:false; response_revision:14197; number_of_response:1; }","duration":"250.225696ms","start":"2026-07-09T00:01:48.608102Z","end":"2026-07-09T00:01:48.858328Z","steps":["trace[2077595791] 'process raft request' (duration: 117.894261ms)","trace[2077595791] 'compare' (duration: 131.613737ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:01:58.755604Z","caller":"traceutil/trace.go:171","msg":"trace[346919371] transaction","detail":"{read_only:false; response_revision:14361; number_of_response:1; }","duration":"347.604465ms","start":"2026-07-09T00:01:58.407968Z","end":"2026-07-09T00:01:58.755572Z","steps":["trace[346919371] 'process raft request' (duration: 303.482827ms)","trace[346919371] 'compare' (duration: 43.936883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:01:58.755764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:01:58.407941Z","time spent":"347.749988ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:01:58.755955Z","caller":"traceutil/trace.go:171","msg":"trace[1798852006] transaction","detail":"{read_only:false; response_revision:14362; number_of_response:1; }","duration":"346.977579ms","start":"2026-07-09T00:01:58.408968Z","end":"2026-07-09T00:01:58.755945Z","steps":["trace[1798852006] 'process raft request' (duration: 346.559558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:01:58.756009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:01:58.40895Z","time spent":"347.03197ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:01:58.75664Z","caller":"traceutil/trace.go:171","msg":"trace[639198345] linearizableReadLoop","detail":"{readStateIndex:14865; appliedIndex:14862; }","duration":"291.753719ms","start":"2026-07-09T00:01:58.464838Z","end":"2026-07-09T00:01:58.756592Z","steps":["trace[639198345] 'read index received' (duration: 246.591075ms)","trace[639198345] 'applied index is now lower than readState.Index' (duration: 45.161344ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:01:58.756691Z","caller":"traceutil/trace.go:171","msg":"trace[1924550256] transaction","detail":"{read_only:false; response_revision:14363; number_of_response:1; }","duration":"347.555253ms","start":"2026-07-09T00:01:58.409077Z","end":"2026-07-09T00:01:58.756632Z","steps":["trace[1924550256] 'process raft request' (duration: 346.818175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:01:58.756934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:01:58.409048Z","time spent":"347.754648ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:01:58.757048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.157649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-09T00:01:58.757213Z","caller":"traceutil/trace.go:171","msg":"trace[497691987] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14363; }","duration":"292.394205ms","start":"2026-07-09T00:01:58.464764Z","end":"2026-07-09T00:01:58.757158Z","steps":["trace[497691987] 'agreement among raft nodes before linearized reading' (duration: 291.955584ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:03:26.83296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12770} {"level":"info","ts":"2026-07-09T00:03:26.911089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12770,"took":"75.35264ms","hash":3977234114,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":31166464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-09T00:03:26.911158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3977234114,"revision":12770,"compact-revision":10517} {"level":"info","ts":"2026-07-09T00:03:28.858631Z","caller":"traceutil/trace.go:171","msg":"trace[2115740322] linearizableReadLoop","detail":"{readStateIndex:15933; appliedIndex:15932; }","duration":"132.521316ms","start":"2026-07-09T00:03:28.726085Z","end":"2026-07-09T00:03:28.858607Z","steps":["trace[2115740322] 'read index received' (duration: 132.387153ms)","trace[2115740322] 'applied index is now lower than readState.Index' (duration: 132.983µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:03:28.858725Z","caller":"traceutil/trace.go:171","msg":"trace[1894590900] transaction","detail":"{read_only:false; response_revision:15399; number_of_response:1; }","duration":"144.430206ms","start":"2026-07-09T00:03:28.714239Z","end":"2026-07-09T00:03:28.858669Z","steps":["trace[1894590900] 'process raft request' (duration: 144.217521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:03:28.858834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.73099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T00:03:28.859035Z","caller":"traceutil/trace.go:171","msg":"trace[1599666128] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:15399; }","duration":"132.984517ms","start":"2026-07-09T00:03:28.726022Z","end":"2026-07-09T00:03:28.859006Z","steps":["trace[1599666128] 'agreement among raft nodes before linearized reading' (duration: 132.72063ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:03:29.021094Z","caller":"traceutil/trace.go:171","msg":"trace[235736790] linearizableReadLoop","detail":"{readStateIndex:15934; appliedIndex:15933; }","duration":"162.327654ms","start":"2026-07-09T00:03:28.858712Z","end":"2026-07-09T00:03:29.02104Z","steps":["trace[235736790] 'read index received' (duration: 160.539841ms)","trace[235736790] 'applied index is now lower than readState.Index' (duration: 1.785893ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:03:29.021127Z","caller":"traceutil/trace.go:171","msg":"trace[801319595] transaction","detail":"{read_only:false; response_revision:15400; number_of_response:1; }","duration":"209.495595ms","start":"2026-07-09T00:03:28.8116Z","end":"2026-07-09T00:03:29.021096Z","steps":["trace[801319595] 'process raft request' (duration: 207.64111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:03:29.021475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.141552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T00:03:29.021507Z","caller":"traceutil/trace.go:171","msg":"trace[206717337] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15400; }","duration":"124.188152ms","start":"2026-07-09T00:03:28.89731Z","end":"2026-07-09T00:03:29.021498Z","steps":["trace[206717337] 'agreement among raft nodes before linearized reading' (duration: 124.112381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:03:29.02153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.041729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ppntg\" ","response":"range_response_count:1 size:22121"} {"level":"warn","ts":"2026-07-09T00:03:29.021427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.102011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T00:03:29.0217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.336007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-09T00:03:29.021729Z","caller":"traceutil/trace.go:171","msg":"trace[1893180130] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15400; }","duration":"124.412989ms","start":"2026-07-09T00:03:28.897306Z","end":"2026-07-09T00:03:29.021719Z","steps":["trace[1893180130] 'agreement among raft nodes before linearized reading' (duration: 124.304546ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:03:29.021829Z","caller":"traceutil/trace.go:171","msg":"trace[1303084745] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15400; }","duration":"124.519782ms","start":"2026-07-09T00:03:28.897244Z","end":"2026-07-09T00:03:29.021763Z","steps":["trace[1303084745] 'agreement among raft nodes before linearized reading' (duration: 124.105131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:03:29.021872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.555282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T00:03:29.02192Z","caller":"traceutil/trace.go:171","msg":"trace[79657748] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15400; }","duration":"124.630194ms","start":"2026-07-09T00:03:28.89728Z","end":"2026-07-09T00:03:29.02191Z","steps":["trace[79657748] 'agreement among raft nodes before linearized reading' (duration: 124.527502ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:03:29.021614Z","caller":"traceutil/trace.go:171","msg":"trace[1269770747] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ppntg; range_end:; response_count:1; response_revision:15400; }","duration":"251.185114ms","start":"2026-07-09T00:03:28.770413Z","end":"2026-07-09T00:03:29.021598Z","steps":["trace[1269770747] 'agreement among raft nodes before linearized reading' (duration: 250.796123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:03:29.022444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.163181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ppntg\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-07-09T00:03:29.022543Z","caller":"traceutil/trace.go:171","msg":"trace[416176648] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ppntg; range_end:; response_count:1; response_revision:15400; }","duration":"246.284704ms","start":"2026-07-09T00:03:28.776244Z","end":"2026-07-09T00:03:29.022529Z","steps":["trace[416176648] 'agreement among raft nodes before linearized reading' (duration: 244.88866ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:08:26.841747Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15390} {"level":"info","ts":"2026-07-09T00:08:26.942989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15390,"took":"97.69015ms","hash":1740712038,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":32268288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-09T00:08:26.943083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1740712038,"revision":15390,"compact-revision":12770} {"level":"warn","ts":"2026-07-09T00:09:17.47989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.061188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:09:17.48001Z","caller":"traceutil/trace.go:171","msg":"trace[1540671077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18044; }","duration":"100.242492ms","start":"2026-07-09T00:09:17.379742Z","end":"2026-07-09T00:09:17.479984Z","steps":["trace[1540671077] 'agreement among raft nodes before linearized reading' (duration: 100.020927ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:09:17.480106Z","caller":"traceutil/trace.go:171","msg":"trace[486329859] transaction","detail":"{read_only:false; response_revision:18044; number_of_response:1; }","duration":"191.561198ms","start":"2026-07-09T00:09:17.288492Z","end":"2026-07-09T00:09:17.480053Z","steps":["trace[486329859] 'process raft request' (duration: 188.081593ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:09:21.514051Z","caller":"traceutil/trace.go:171","msg":"trace[168159809] transaction","detail":"{read_only:false; response_revision:18075; number_of_response:1; }","duration":"148.965428ms","start":"2026-07-09T00:09:21.365057Z","end":"2026-07-09T00:09:21.514022Z","steps":["trace[168159809] 'process raft request' (duration: 125.281371ms)","trace[168159809] 'compare' (duration: 23.477872ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:09:21.514177Z","caller":"traceutil/trace.go:171","msg":"trace[1869855503] linearizableReadLoop","detail":"{readStateIndex:18695; appliedIndex:18692; }","duration":"136.465844ms","start":"2026-07-09T00:09:21.377683Z","end":"2026-07-09T00:09:21.514149Z","steps":["trace[1869855503] 'read index received' (duration: 8.991668ms)","trace[1869855503] 'applied index is now lower than readState.Index' (duration: 127.472536ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:09:21.514396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.68616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:09:21.514445Z","caller":"traceutil/trace.go:171","msg":"trace[871713920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18076; }","duration":"136.796092ms","start":"2026-07-09T00:09:21.377638Z","end":"2026-07-09T00:09:21.514434Z","steps":["trace[871713920] 'agreement among raft nodes before linearized reading' (duration: 136.68808ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:09:21.514299Z","caller":"traceutil/trace.go:171","msg":"trace[939716779] transaction","detail":"{read_only:false; response_revision:18076; number_of_response:1; }","duration":"148.20011ms","start":"2026-07-09T00:09:21.366066Z","end":"2026-07-09T00:09:21.514266Z","steps":["trace[939716779] 'process raft request' (duration: 147.923094ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:09:22.08682Z","caller":"traceutil/trace.go:171","msg":"trace[2052550867] linearizableReadLoop","detail":"{readStateIndex:18697; appliedIndex:18696; }","duration":"169.377776ms","start":"2026-07-09T00:09:21.917417Z","end":"2026-07-09T00:09:22.086795Z","steps":["trace[2052550867] 'read index received' (duration: 169.14596ms)","trace[2052550867] 'applied index is now lower than readState.Index' (duration: 230.946µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:09:22.086886Z","caller":"traceutil/trace.go:171","msg":"trace[963027371] transaction","detail":"{read_only:false; response_revision:18078; number_of_response:1; }","duration":"191.2695ms","start":"2026-07-09T00:09:21.895569Z","end":"2026-07-09T00:09:22.086839Z","steps":["trace[963027371] 'process raft request' (duration: 191.075695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:09:22.086996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.55676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:09:22.087037Z","caller":"traceutil/trace.go:171","msg":"trace[211576438] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:18078; }","duration":"169.626252ms","start":"2026-07-09T00:09:21.917399Z","end":"2026-07-09T00:09:22.087025Z","steps":["trace[211576438] 'agreement among raft nodes before linearized reading' (duration: 169.51004ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:11:49.497296Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-09T00:11:49.50459Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-09T00:11:49.5047Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-09T00:13:26.859829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17615} {"level":"info","ts":"2026-07-09T00:13:27.022371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17615,"took":"158.53803ms","hash":248477812,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":35094528,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-09T00:13:27.022465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":248477812,"revision":17615,"compact-revision":15390} {"level":"info","ts":"2026-07-09T00:14:37.853097Z","caller":"traceutil/trace.go:171","msg":"trace[2043913930] transaction","detail":"{read_only:false; response_revision:20518; number_of_response:1; }","duration":"188.992471ms","start":"2026-07-09T00:14:37.664062Z","end":"2026-07-09T00:14:37.853054Z","steps":["trace[2043913930] 'process raft request' (duration: 177.616385ms)","trace[2043913930] 'compare' (duration: 10.808683ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:14:53.706129Z","caller":"traceutil/trace.go:171","msg":"trace[1869048937] transaction","detail":"{read_only:false; response_revision:20597; number_of_response:1; }","duration":"187.612678ms","start":"2026-07-09T00:14:53.518469Z","end":"2026-07-09T00:14:53.706081Z","steps":["trace[1869048937] 'process raft request' (duration: 145.486652ms)","trace[1869048937] 'compare' (duration: 41.936882ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:14:53.802804Z","caller":"traceutil/trace.go:171","msg":"trace[1485388440] transaction","detail":"{read_only:false; response_revision:20598; number_of_response:1; }","duration":"284.22858ms","start":"2026-07-09T00:14:53.51855Z","end":"2026-07-09T00:14:53.802778Z","steps":["trace[1485388440] 'process raft request' (duration: 284.064776ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:14:53.803046Z","caller":"traceutil/trace.go:171","msg":"trace[882377366] linearizableReadLoop","detail":"{readStateIndex:21302; appliedIndex:21300; }","duration":"256.324811ms","start":"2026-07-09T00:14:53.546679Z","end":"2026-07-09T00:14:53.803003Z","steps":["trace[882377366] 'read index received' (duration: 117.304305ms)","trace[882377366] 'applied index is now lower than readState.Index' (duration: 139.017206ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:14:53.803114Z","caller":"traceutil/trace.go:171","msg":"trace[2084882259] transaction","detail":"{read_only:false; response_revision:20599; number_of_response:1; }","duration":"221.010731ms","start":"2026-07-09T00:14:53.582066Z","end":"2026-07-09T00:14:53.803076Z","steps":["trace[2084882259] 'process raft request' (duration: 220.663333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:14:53.803355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.650618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-09T00:14:53.803408Z","caller":"traceutil/trace.go:171","msg":"trace[1267057127] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20599; }","duration":"256.767551ms","start":"2026-07-09T00:14:53.546622Z","end":"2026-07-09T00:14:53.80339Z","steps":["trace[1267057127] 'agreement among raft nodes before linearized reading' (duration: 256.530905ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:14:53.980115Z","caller":"traceutil/trace.go:171","msg":"trace[2014944124] transaction","detail":"{read_only:false; response_revision:20600; number_of_response:1; }","duration":"170.899011ms","start":"2026-07-09T00:14:53.809147Z","end":"2026-07-09T00:14:53.980046Z","steps":["trace[2014944124] 'process raft request' (duration: 126.596812ms)","trace[2014944124] 'compare' (duration: 43.50515ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:14:54.301938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.927316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:14:54.302166Z","caller":"traceutil/trace.go:171","msg":"trace[98388159] transaction","detail":"{read_only:false; response_revision:20601; number_of_response:1; }","duration":"388.643223ms","start":"2026-07-09T00:14:53.913489Z","end":"2026-07-09T00:14:54.302132Z","steps":["trace[98388159] 'process raft request' (duration: 177.348758ms)","trace[98388159] 'compare' (duration: 210.150847ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:14:54.30229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:14:53.913466Z","time spent":"388.759496ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:14:54.302501Z","caller":"traceutil/trace.go:171","msg":"trace[692981844] transaction","detail":"{read_only:false; response_revision:20602; number_of_response:1; }","duration":"312.658812ms","start":"2026-07-09T00:14:53.989824Z","end":"2026-07-09T00:14:54.302483Z","steps":["trace[692981844] 'process raft request' (duration: 312.338414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:14:54.302624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:14:53.989801Z","time spent":"312.745305ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:14:54.3025Z","caller":"traceutil/trace.go:171","msg":"trace[347862627] linearizableReadLoop","detail":"{readStateIndex:21305; appliedIndex:21304; }","duration":"322.611105ms","start":"2026-07-09T00:14:53.979845Z","end":"2026-07-09T00:14:54.302456Z","steps":["trace[347862627] 'read index received' (duration: 111.009754ms)","trace[347862627] 'applied index is now lower than readState.Index' (duration: 211.593131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:14:54.302888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.705219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-09T00:14:54.302997Z","caller":"traceutil/trace.go:171","msg":"trace[2103843414] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:20602; }","duration":"350.882325ms","start":"2026-07-09T00:14:53.952081Z","end":"2026-07-09T00:14:54.302964Z","steps":["trace[2103843414] 'agreement among raft nodes before linearized reading' (duration: 350.569666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:14:54.30306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:14:53.952028Z","time spent":"351.010187ms","remote":"127.0.0.1:48826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":158,"response size":32,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-09T00:14:54.303206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.686157ms","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-07-09T00:14:54.303256Z","caller":"traceutil/trace.go:171","msg":"trace[2019733315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20602; }","duration":"140.793859ms","start":"2026-07-09T00:14:54.162449Z","end":"2026-07-09T00:14:54.303243Z","steps":["trace[2019733315] 'agreement among raft nodes before linearized reading' (duration: 140.453901ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:14:54.636984Z","caller":"traceutil/trace.go:171","msg":"trace[1938234101] transaction","detail":"{read_only:false; response_revision:20604; number_of_response:1; }","duration":"129.828961ms","start":"2026-07-09T00:14:54.507112Z","end":"2026-07-09T00:14:54.636941Z","steps":["trace[1938234101] 'process raft request' (duration: 129.405291ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:18:26.865874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20109} {"level":"info","ts":"2026-07-09T00:18:26.979226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20109,"took":"108.41599ms","hash":2554922028,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":32440320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-09T00:18:26.979301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2554922028,"revision":20109,"compact-revision":17615} {"level":"info","ts":"2026-07-09T00:20:55.80523Z","caller":"traceutil/trace.go:171","msg":"trace[746008302] transaction","detail":"{read_only:false; response_revision:24440; number_of_response:1; }","duration":"113.129793ms","start":"2026-07-09T00:20:55.692076Z","end":"2026-07-09T00:20:55.805205Z","steps":["trace[746008302] 'process raft request' (duration: 93.902975ms)","trace[746008302] 'compare' (duration: 18.670545ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:20:55.805545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.403071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:20:55.805627Z","caller":"traceutil/trace.go:171","msg":"trace[190875166] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24440; }","duration":"102.523085ms","start":"2026-07-09T00:20:55.703085Z","end":"2026-07-09T00:20:55.805608Z","steps":["trace[190875166] 'agreement among raft nodes before linearized reading' (duration: 102.387221ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:20:55.805706Z","caller":"traceutil/trace.go:171","msg":"trace[1868781357] linearizableReadLoop","detail":"{readStateIndex:25249; appliedIndex:25248; }","duration":"102.041493ms","start":"2026-07-09T00:20:55.703114Z","end":"2026-07-09T00:20:55.805155Z","steps":["trace[1868781357] 'read index received' (duration: 82.893637ms)","trace[1868781357] 'applied index is now lower than readState.Index' (duration: 19.146866ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:20:55.820583Z","caller":"traceutil/trace.go:171","msg":"trace[549890189] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"102.463733ms","start":"2026-07-09T00:20:55.718079Z","end":"2026-07-09T00:20:55.820543Z","steps":["trace[549890189] 'process raft request' (duration: 102.193006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:20:55.820824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.478219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:20:55.820904Z","caller":"traceutil/trace.go:171","msg":"trace[1361964864] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24443; }","duration":"117.579611ms","start":"2026-07-09T00:20:55.703307Z","end":"2026-07-09T00:20:55.820887Z","steps":["trace[1361964864] 'agreement among raft nodes before linearized reading' (duration: 117.408907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:20:55.821168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.91427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:1 size:12668"} {"level":"info","ts":"2026-07-09T00:20:55.821217Z","caller":"traceutil/trace.go:171","msg":"trace[1485975171] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:1; response_revision:24443; }","duration":"117.975161ms","start":"2026-07-09T00:20:55.703231Z","end":"2026-07-09T00:20:55.821206Z","steps":["trace[1485975171] 'agreement among raft nodes before linearized reading' (duration: 117.664713ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:20:56.251553Z","caller":"traceutil/trace.go:171","msg":"trace[401357150] linearizableReadLoop","detail":"{readStateIndex:25256; appliedIndex:25255; }","duration":"192.487103ms","start":"2026-07-09T00:20:56.059042Z","end":"2026-07-09T00:20:56.251529Z","steps":["trace[401357150] 'read index received' (duration: 192.328709ms)","trace[401357150] 'applied index is now lower than readState.Index' (duration: 157.464µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:20:56.251899Z","caller":"traceutil/trace.go:171","msg":"trace[1892274622] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"247.269177ms","start":"2026-07-09T00:20:56.004617Z","end":"2026-07-09T00:20:56.251886Z","steps":["trace[1892274622] 'process raft request' (duration: 246.690423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:20:56.252183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.084175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:20:56.252266Z","caller":"traceutil/trace.go:171","msg":"trace[160588381] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24447; }","duration":"192.214828ms","start":"2026-07-09T00:20:56.060036Z","end":"2026-07-09T00:20:56.25225Z","steps":["trace[160588381] 'agreement among raft nodes before linearized reading' (duration: 192.066485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:20:56.252261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.636683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-09T00:20:56.252323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.036745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:1 size:12668"} {"level":"info","ts":"2026-07-09T00:20:56.252333Z","caller":"traceutil/trace.go:171","msg":"trace[150247672] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24447; }","duration":"131.756466ms","start":"2026-07-09T00:20:56.120563Z","end":"2026-07-09T00:20:56.25232Z","steps":["trace[150247672] 'agreement among raft nodes before linearized reading' (duration: 131.603432ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:20:56.252354Z","caller":"traceutil/trace.go:171","msg":"trace[1451103129] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:1; response_revision:24447; }","duration":"190.086906ms","start":"2026-07-09T00:20:56.062259Z","end":"2026-07-09T00:20:56.252346Z","steps":["trace[1451103129] 'agreement among raft nodes before linearized reading' (duration: 189.980473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:20:56.252206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.164941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:20:56.252525Z","caller":"traceutil/trace.go:171","msg":"trace[1983220519] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24447; }","duration":"193.508238ms","start":"2026-07-09T00:20:56.058996Z","end":"2026-07-09T00:20:56.252504Z","steps":["trace[1983220519] 'agreement among raft nodes before linearized reading' (duration: 193.17436ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:21:00.380595Z","caller":"traceutil/trace.go:171","msg":"trace[37421321] linearizableReadLoop","detail":"{readStateIndex:25285; appliedIndex:25284; }","duration":"111.309048ms","start":"2026-07-09T00:21:00.269266Z","end":"2026-07-09T00:21:00.380575Z","steps":["trace[37421321] 'read index received' (duration: 111.133094ms)","trace[37421321] 'applied index is now lower than readState.Index' (duration: 175.134µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:21:00.380695Z","caller":"traceutil/trace.go:171","msg":"trace[579505480] transaction","detail":"{read_only:false; response_revision:24475; number_of_response:1; }","duration":"112.215721ms","start":"2026-07-09T00:21:00.268436Z","end":"2026-07-09T00:21:00.380652Z","steps":["trace[579505480] 'process raft request' (duration: 111.980875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:21:00.380967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.691227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:1 size:12668"} {"level":"info","ts":"2026-07-09T00:21:00.381051Z","caller":"traceutil/trace.go:171","msg":"trace[2007553479] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:1; response_revision:24475; }","duration":"111.814241ms","start":"2026-07-09T00:21:00.269222Z","end":"2026-07-09T00:21:00.381036Z","steps":["trace[2007553479] 'agreement among raft nodes before linearized reading' (duration: 111.494373ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:21:00.58841Z","caller":"traceutil/trace.go:171","msg":"trace[578845621] linearizableReadLoop","detail":"{readStateIndex:25287; appliedIndex:25286; }","duration":"122.227834ms","start":"2026-07-09T00:21:00.466146Z","end":"2026-07-09T00:21:00.588374Z","steps":["trace[578845621] 'read index received' (duration: 89.694913ms)","trace[578845621] 'applied index is now lower than readState.Index' (duration: 32.531741ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:21:00.588786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.621294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T00:21:00.588872Z","caller":"traceutil/trace.go:171","msg":"trace[1945958791] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"177.702225ms","start":"2026-07-09T00:21:00.411144Z","end":"2026-07-09T00:21:00.588847Z","steps":["trace[1945958791] 'process raft request' (duration: 144.681702ms)","trace[1945958791] 'compare' (duration: 32.367527ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:21:00.588912Z","caller":"traceutil/trace.go:171","msg":"trace[656178189] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24477; }","duration":"122.801608ms","start":"2026-07-09T00:21:00.466093Z","end":"2026-07-09T00:21:00.588894Z","steps":["trace[656178189] 'agreement among raft nodes before linearized reading' (duration: 122.520032ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:14.954439Z","caller":"traceutil/trace.go:171","msg":"trace[749795807] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"104.179906ms","start":"2026-07-09T00:22:14.850229Z","end":"2026-07-09T00:22:14.954409Z","steps":["trace[749795807] 'process raft request' (duration: 103.977801ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:15.175037Z","caller":"traceutil/trace.go:171","msg":"trace[1779428833] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"189.109922ms","start":"2026-07-09T00:22:14.985892Z","end":"2026-07-09T00:22:15.175001Z","steps":["trace[1779428833] 'process raft request' (duration: 171.849142ms)","trace[1779428833] 'compare' (duration: 16.960212ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:15.364954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.022425ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T00:22:15.365104Z","caller":"traceutil/trace.go:171","msg":"trace[102219446] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26030; }","duration":"184.529491ms","start":"2026-07-09T00:22:15.180535Z","end":"2026-07-09T00:22:15.365064Z","steps":["trace[102219446] 'read index received' (duration: 59.562µs)","trace[102219446] 'applied index is now lower than readState.Index' (duration: 184.467869ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:15.36553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.945071ms","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-07-09T00:22:15.365595Z","caller":"traceutil/trace.go:171","msg":"trace[1533481442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25200; }","duration":"185.087134ms","start":"2026-07-09T00:22:15.180489Z","end":"2026-07-09T00:22:15.365576Z","steps":["trace[1533481442] 'agreement among raft nodes before linearized reading' (duration: 184.804377ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:15.916822Z","caller":"traceutil/trace.go:171","msg":"trace[42686798] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"141.040322ms","start":"2026-07-09T00:22:15.775741Z","end":"2026-07-09T00:22:15.916781Z","steps":["trace[42686798] 'process raft request' (duration: 140.360296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:16.387328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.431584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:22:16.387433Z","caller":"traceutil/trace.go:171","msg":"trace[1339071826] linearizableReadLoop","detail":"{readStateIndex:26036; appliedIndex:26035; }","duration":"383.205345ms","start":"2026-07-09T00:22:16.004211Z","end":"2026-07-09T00:22:16.387416Z","steps":["trace[1339071826] 'read index received' (duration: 86.483774ms)","trace[1339071826] 'applied index is now lower than readState.Index' (duration: 296.720511ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:16.387664Z","caller":"traceutil/trace.go:171","msg":"trace[475004084] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"393.295941ms","start":"2026-07-09T00:22:15.994359Z","end":"2026-07-09T00:22:16.387655Z","steps":["trace[475004084] 'process raft request' (duration: 96.442667ms)","trace[475004084] 'compare' (duration: 296.216319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:16.387705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:15.994335Z","time spent":"393.347902ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:16.387942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.731328ms","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-07-09T00:22:16.38797Z","caller":"traceutil/trace.go:171","msg":"trace[2118838990] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25205; }","duration":"383.793259ms","start":"2026-07-09T00:22:16.004167Z","end":"2026-07-09T00:22:16.387961Z","steps":["trace[2118838990] 'agreement among raft nodes before linearized reading' (duration: 383.676666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:16.387986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:16.004147Z","time spent":"383.836231ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-09T00:22:16.38818Z","caller":"traceutil/trace.go:171","msg":"trace[385687320] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"226.872022ms","start":"2026-07-09T00:22:16.161271Z","end":"2026-07-09T00:22:16.388143Z","steps":["trace[385687320] 'process raft request' (duration: 226.676667ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:16.407673Z","caller":"traceutil/trace.go:171","msg":"trace[2136369853] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"245.886703ms","start":"2026-07-09T00:22:16.161753Z","end":"2026-07-09T00:22:16.407639Z","steps":["trace[2136369853] 'process raft request' (duration: 245.672408ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:16.408001Z","caller":"traceutil/trace.go:171","msg":"trace[982052252] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"121.673701ms","start":"2026-07-09T00:22:16.286267Z","end":"2026-07-09T00:22:16.407941Z","steps":["trace[982052252] 'process raft request' (duration: 121.315872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:16.829349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.397072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:22:16.829614Z","caller":"traceutil/trace.go:171","msg":"trace[102039565] linearizableReadLoop","detail":"{readStateIndex:26040; appliedIndex:26039; }","duration":"420.542973ms","start":"2026-07-09T00:22:16.409044Z","end":"2026-07-09T00:22:16.829587Z","steps":["trace[102039565] 'read index received' (duration: 220.753912ms)","trace[102039565] 'applied index is now lower than readState.Index' (duration: 199.786171ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:16.8299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.81915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:16.830004Z","caller":"traceutil/trace.go:171","msg":"trace[1425899090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25209; }","duration":"420.955864ms","start":"2026-07-09T00:22:16.409021Z","end":"2026-07-09T00:22:16.829977Z","steps":["trace[1425899090] 'agreement among raft nodes before linearized reading' (duration: 420.753109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:16.830068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:16.409Z","time spent":"421.047335ms","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T00:22:16.830107Z","caller":"traceutil/trace.go:171","msg":"trace[1726932756] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"436.801959ms","start":"2026-07-09T00:22:16.392952Z","end":"2026-07-09T00:22:16.829754Z","steps":["trace[1726932756] 'process raft request' (duration: 236.863294ms)","trace[1726932756] 'compare' (duration: 199.168177ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:16.83031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:16.392928Z","time spent":"437.270301ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:16.842626Z","caller":"traceutil/trace.go:171","msg":"trace[1321194409] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"429.912092ms","start":"2026-07-09T00:22:16.412694Z","end":"2026-07-09T00:22:16.842606Z","steps":["trace[1321194409] 'process raft request' (duration: 429.719188ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:16.842732Z","caller":"traceutil/trace.go:171","msg":"trace[518863549] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"430.006954ms","start":"2026-07-09T00:22:16.412713Z","end":"2026-07-09T00:22:16.84272Z","steps":["trace[518863549] 'process raft request' (duration: 429.858811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:16.842781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:16.412692Z","time spent":"430.063526ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:16.842777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:16.41268Z","time spent":"430.036555ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:16.912556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.73045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-09T00:22:16.912669Z","caller":"traceutil/trace.go:171","msg":"trace[1142056909] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25212; }","duration":"277.926914ms","start":"2026-07-09T00:22:16.634718Z","end":"2026-07-09T00:22:16.912644Z","steps":["trace[1142056909] 'agreement among raft nodes before linearized reading' (duration: 277.610566ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:16.913087Z","caller":"traceutil/trace.go:171","msg":"trace[1362352567] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"211.685162ms","start":"2026-07-09T00:22:16.701378Z","end":"2026-07-09T00:22:16.913063Z","steps":["trace[1362352567] 'process raft request' (duration: 210.743578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:16.913474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.739942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T00:22:16.913555Z","caller":"traceutil/trace.go:171","msg":"trace[140101730] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25212; }","duration":"107.889916ms","start":"2026-07-09T00:22:16.805646Z","end":"2026-07-09T00:22:16.913536Z","steps":["trace[140101730] 'agreement among raft nodes before linearized reading' (duration: 107.730492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:17.082564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.009948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-09T00:22:17.082648Z","caller":"traceutil/trace.go:171","msg":"trace[394613151] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25212; }","duration":"122.142772ms","start":"2026-07-09T00:22:16.960482Z","end":"2026-07-09T00:22:17.082625Z","steps":["trace[394613151] 'range keys from in-memory index tree' (duration: 121.798423ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:17.225904Z","caller":"traceutil/trace.go:171","msg":"trace[695768272] linearizableReadLoop","detail":"{readStateIndex:26044; appliedIndex:26043; }","duration":"124.244414ms","start":"2026-07-09T00:22:17.101633Z","end":"2026-07-09T00:22:17.225877Z","steps":["trace[695768272] 'read index received' (duration: 124.07529ms)","trace[695768272] 'applied index is now lower than readState.Index' (duration: 168.304µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:17.226073Z","caller":"traceutil/trace.go:171","msg":"trace[1670325239] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"136.294708ms","start":"2026-07-09T00:22:17.089737Z","end":"2026-07-09T00:22:17.226032Z","steps":["trace[1670325239] 'process raft request' (duration: 135.984339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:17.226126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.47197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-09T00:22:17.226252Z","caller":"traceutil/trace.go:171","msg":"trace[830229477] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25213; }","duration":"124.643004ms","start":"2026-07-09T00:22:17.101596Z","end":"2026-07-09T00:22:17.226239Z","steps":["trace[830229477] 'agreement among raft nodes before linearized reading' (duration: 124.419328ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:17.783971Z","caller":"traceutil/trace.go:171","msg":"trace[558947044] linearizableReadLoop","detail":"{readStateIndex:26048; appliedIndex:26046; }","duration":"179.94096ms","start":"2026-07-09T00:22:17.604005Z","end":"2026-07-09T00:22:17.783946Z","steps":["trace[558947044] 'read index received' (duration: 90.829161ms)","trace[558947044] 'applied index is now lower than readState.Index' (duration: 89.110809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:17.784173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.789269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:17.784215Z","caller":"traceutil/trace.go:171","msg":"trace[360039303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25217; }","duration":"152.895291ms","start":"2026-07-09T00:22:17.631308Z","end":"2026-07-09T00:22:17.784203Z","steps":["trace[360039303] 'agreement among raft nodes before linearized reading' (duration: 152.798699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:17.784245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.210286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-09T00:22:17.784335Z","caller":"traceutil/trace.go:171","msg":"trace[594844025] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25217; }","duration":"180.35217ms","start":"2026-07-09T00:22:17.603962Z","end":"2026-07-09T00:22:17.784314Z","steps":["trace[594844025] 'agreement among raft nodes before linearized reading' (duration: 180.135085ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:17.784413Z","caller":"traceutil/trace.go:171","msg":"trace[1018336929] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"309.016069ms","start":"2026-07-09T00:22:17.475355Z","end":"2026-07-09T00:22:17.784371Z","steps":["trace[1018336929] 'process raft request' (duration: 219.45743ms)","trace[1018336929] 'compare' (duration: 88.910174ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:17.784536Z","caller":"traceutil/trace.go:171","msg":"trace[1182359298] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"223.93704ms","start":"2026-07-09T00:22:17.560586Z","end":"2026-07-09T00:22:17.784523Z","steps":["trace[1182359298] 'process raft request' (duration: 223.305955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:17.784598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:17.475349Z","time spent":"309.143912ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:18.108735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.00417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T00:22:18.108811Z","caller":"traceutil/trace.go:171","msg":"trace[1166361788] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25218; }","duration":"219.120443ms","start":"2026-07-09T00:22:17.889675Z","end":"2026-07-09T00:22:18.108796Z","steps":["trace[1166361788] 'count revisions from in-memory index tree' (duration: 218.917598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:18.109388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.610125ms","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-07-09T00:22:18.109744Z","caller":"traceutil/trace.go:171","msg":"trace[1674790282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25218; }","duration":"187.880512ms","start":"2026-07-09T00:22:17.921697Z","end":"2026-07-09T00:22:18.109578Z","steps":["trace[1674790282] 'range keys from in-memory index tree' (duration: 187.310738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:18.384896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.737397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:18.385022Z","caller":"traceutil/trace.go:171","msg":"trace[604782864] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25219; }","duration":"106.86009ms","start":"2026-07-09T00:22:18.278114Z","end":"2026-07-09T00:22:18.384974Z","steps":["trace[604782864] 'range keys from in-memory index tree' (duration: 106.641535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:18.666575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.894125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:22:18.666727Z","caller":"traceutil/trace.go:171","msg":"trace[1915031433] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"273.910896ms","start":"2026-07-09T00:22:18.392788Z","end":"2026-07-09T00:22:18.666699Z","steps":["trace[1915031433] 'process raft request' (duration: 126.777765ms)","trace[1915031433] 'compare' (duration: 146.720711ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:18.667592Z","caller":"traceutil/trace.go:171","msg":"trace[715332079] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"255.286123ms","start":"2026-07-09T00:22:18.412289Z","end":"2026-07-09T00:22:18.667576Z","steps":["trace[715332079] 'process raft request' (duration: 255.207131ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:18.667715Z","caller":"traceutil/trace.go:171","msg":"trace[1724869319] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"274.88742ms","start":"2026-07-09T00:22:18.392818Z","end":"2026-07-09T00:22:18.667705Z","steps":["trace[1724869319] 'process raft request' (duration: 274.514841ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:18.667795Z","caller":"traceutil/trace.go:171","msg":"trace[1999949929] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"263.432121ms","start":"2026-07-09T00:22:18.404355Z","end":"2026-07-09T00:22:18.667787Z","steps":["trace[1999949929] 'process raft request' (duration: 263.075262ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:18.667884Z","caller":"traceutil/trace.go:171","msg":"trace[581808647] linearizableReadLoop","detail":"{readStateIndex:26053; appliedIndex:26051; }","duration":"272.704886ms","start":"2026-07-09T00:22:18.39517Z","end":"2026-07-09T00:22:18.667875Z","steps":["trace[581808647] 'read index received' (duration: 124.408557ms)","trace[581808647] 'applied index is now lower than readState.Index' (duration: 148.295399ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:18.668073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.751729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:18.66811Z","caller":"traceutil/trace.go:171","msg":"trace[564365650] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25225; }","duration":"224.829781ms","start":"2026-07-09T00:22:18.443265Z","end":"2026-07-09T00:22:18.668095Z","steps":["trace[564365650] 'agreement among raft nodes before linearized reading' (duration: 224.76988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:18.668106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.90567ms","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-07-09T00:22:18.668184Z","caller":"traceutil/trace.go:171","msg":"trace[884093667] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"255.868226ms","start":"2026-07-09T00:22:18.412268Z","end":"2026-07-09T00:22:18.668137Z","steps":["trace[884093667] 'process raft request' (duration: 255.270242ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:18.668225Z","caller":"traceutil/trace.go:171","msg":"trace[378720280] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25225; }","duration":"273.045734ms","start":"2026-07-09T00:22:18.395157Z","end":"2026-07-09T00:22:18.668202Z","steps":["trace[378720280] 'agreement among raft nodes before linearized reading' (duration: 272.838939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:18.668312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.472583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:18.668349Z","caller":"traceutil/trace.go:171","msg":"trace[261415408] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25225; }","duration":"201.532455ms","start":"2026-07-09T00:22:18.466804Z","end":"2026-07-09T00:22:18.668336Z","steps":["trace[261415408] 'agreement among raft nodes before linearized reading' (duration: 201.462853ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:19.005144Z","caller":"traceutil/trace.go:171","msg":"trace[800128841] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"154.576432ms","start":"2026-07-09T00:22:18.850539Z","end":"2026-07-09T00:22:19.005116Z","steps":["trace[800128841] 'process raft request' (duration: 154.374957ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:19.011135Z","caller":"traceutil/trace.go:171","msg":"trace[1939775429] linearizableReadLoop","detail":"{readStateIndex:26059; appliedIndex:26057; }","duration":"118.670458ms","start":"2026-07-09T00:22:18.892437Z","end":"2026-07-09T00:22:19.011107Z","steps":["trace[1939775429] 'read index received' (duration: 112.485558ms)","trace[1939775429] 'applied index is now lower than readState.Index' (duration: 6.18391ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:19.011228Z","caller":"traceutil/trace.go:171","msg":"trace[468748826] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"159.736707ms","start":"2026-07-09T00:22:18.851458Z","end":"2026-07-09T00:22:19.011194Z","steps":["trace[468748826] 'process raft request' (duration: 159.384548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:19.011364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.878974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:19.011405Z","caller":"traceutil/trace.go:171","msg":"trace[63802801] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:25229; }","duration":"119.001617ms","start":"2026-07-09T00:22:18.892387Z","end":"2026-07-09T00:22:19.011389Z","steps":["trace[63802801] 'agreement among raft nodes before linearized reading' (duration: 118.824162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:19.336117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.637224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-09T00:22:19.336215Z","caller":"traceutil/trace.go:171","msg":"trace[1641883022] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25229; }","duration":"101.785028ms","start":"2026-07-09T00:22:19.234407Z","end":"2026-07-09T00:22:19.336192Z","steps":["trace[1641883022] 'range keys from in-memory index tree' (duration: 101.422158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.113481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813145290923555,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T00:22:20.12601Z","caller":"traceutil/trace.go:171","msg":"trace[2086701418] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"564.17379ms","start":"2026-07-09T00:22:19.56181Z","end":"2026-07-09T00:22:20.125984Z","steps":["trace[2086701418] 'process raft request' (duration: 564.004396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.126203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:19.561767Z","time spent":"564.332984ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:20.336223Z","caller":"traceutil/trace.go:171","msg":"trace[84576353] linearizableReadLoop","detail":"{readStateIndex:26064; appliedIndex:26063; }","duration":"724.416519ms","start":"2026-07-09T00:22:19.611778Z","end":"2026-07-09T00:22:20.336195Z","steps":["trace[84576353] 'read index received' (duration: 515.012413ms)","trace[84576353] 'applied index is now lower than readState.Index' (duration: 209.402906ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:20.33651Z","caller":"traceutil/trace.go:171","msg":"trace[973646261] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"545.465044ms","start":"2026-07-09T00:22:19.791028Z","end":"2026-07-09T00:22:20.336493Z","steps":["trace[973646261] 'process raft request' (duration: 450.221726ms)","trace[973646261] 'compare' (duration: 94.564682ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:20.33663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:19.790992Z","time spent":"545.559146ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:20.33685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.072555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:20.336896Z","caller":"traceutil/trace.go:171","msg":"trace[884760803] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25233; }","duration":"725.150106ms","start":"2026-07-09T00:22:19.611733Z","end":"2026-07-09T00:22:20.336883Z","steps":["trace[884760803] 'agreement among raft nodes before linearized reading' (duration: 725.079815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.336937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:19.611713Z","time spent":"725.215048ms","remote":"127.0.0.1:36270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true "} {"level":"warn","ts":"2026-07-09T00:22:20.337195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.34441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-07-09T00:22:20.337234Z","caller":"traceutil/trace.go:171","msg":"trace[1799560935] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25233; }","duration":"690.425862ms","start":"2026-07-09T00:22:19.646799Z","end":"2026-07-09T00:22:20.337225Z","steps":["trace[1799560935] 'agreement among raft nodes before linearized reading' (duration: 690.298039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.337267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:19.646777Z","time spent":"690.483812ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-07-09T00:22:20.337643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.352223ms","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-07-09T00:22:20.33769Z","caller":"traceutil/trace.go:171","msg":"trace[1597704324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25233; }","duration":"128.425775ms","start":"2026-07-09T00:22:20.209252Z","end":"2026-07-09T00:22:20.337678Z","steps":["trace[1597704324] 'agreement among raft nodes before linearized reading' (duration: 128.313423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.337906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.080632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-09T00:22:20.337943Z","caller":"traceutil/trace.go:171","msg":"trace[517898285] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:25233; }","duration":"420.156735ms","start":"2026-07-09T00:22:19.917777Z","end":"2026-07-09T00:22:20.337934Z","steps":["trace[517898285] 'agreement among raft nodes before linearized reading' (duration: 420.053052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.337976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:19.917761Z","time spent":"420.207896ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"warn","ts":"2026-07-09T00:22:20.338182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.159933ms","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-07-09T00:22:20.33822Z","caller":"traceutil/trace.go:171","msg":"trace[1076940101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25233; }","duration":"549.249196ms","start":"2026-07-09T00:22:19.788962Z","end":"2026-07-09T00:22:20.338211Z","steps":["trace[1076940101] 'agreement among raft nodes before linearized reading' (duration: 549.155074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.338259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:19.788935Z","time spent":"549.318308ms","remote":"127.0.0.1:48744","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-07-09T00:22:20.338452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.774808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:20.338492Z","caller":"traceutil/trace.go:171","msg":"trace[407362177] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25233; }","duration":"664.858681ms","start":"2026-07-09T00:22:19.673623Z","end":"2026-07-09T00:22:20.338482Z","steps":["trace[407362177] 'agreement among raft nodes before linearized reading' (duration: 664.799229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.338539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:19.673601Z","time spent":"664.925572ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true "} {"level":"warn","ts":"2026-07-09T00:22:20.33872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.034492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:20.33876Z","caller":"traceutil/trace.go:171","msg":"trace[1737777551] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25233; }","duration":"683.123464ms","start":"2026-07-09T00:22:19.655626Z","end":"2026-07-09T00:22:20.338749Z","steps":["trace[1737777551] 'agreement among raft nodes before linearized reading' (duration: 683.063263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.338796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:19.655601Z","time spent":"683.187125ms","remote":"127.0.0.1:55864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T00:22:20.723031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.860308ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-09T00:22:20.723418Z","caller":"traceutil/trace.go:171","msg":"trace[1148421610] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"371.534432ms","start":"2026-07-09T00:22:20.351856Z","end":"2026-07-09T00:22:20.723391Z","steps":["trace[1148421610] 'process raft request' (duration: 371.45121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.723536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:20.346011Z","time spent":"377.522347ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-09T00:22:20.723535Z","caller":"traceutil/trace.go:171","msg":"trace[1506517573] linearizableReadLoop","detail":"{readStateIndex:26065; appliedIndex:26064; }","duration":"372.581947ms","start":"2026-07-09T00:22:20.350924Z","end":"2026-07-09T00:22:20.723506Z","steps":["trace[1506517573] 'read index received' (duration: 187.227306ms)","trace[1506517573] 'applied index is now lower than readState.Index' (duration: 185.348481ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:20.724164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.868134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-07-09T00:22:20.724218Z","caller":"traceutil/trace.go:171","msg":"trace[29096941] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25235; }","duration":"373.287774ms","start":"2026-07-09T00:22:20.350921Z","end":"2026-07-09T00:22:20.724209Z","steps":["trace[29096941] 'agreement among raft nodes before linearized reading' (duration: 372.70338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.724245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:20.350899Z","time spent":"373.339325ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-07-09T00:22:20.724255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:20.351844Z","time spent":"371.625804ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:20.724248Z","caller":"traceutil/trace.go:171","msg":"trace[1842717712] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"372.779132ms","start":"2026-07-09T00:22:20.351396Z","end":"2026-07-09T00:22:20.724176Z","steps":["trace[1842717712] 'process raft request' (duration: 371.762907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:20.724522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:20.351362Z","time spent":"373.008497ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:21.006457Z","caller":"traceutil/trace.go:171","msg":"trace[651036230] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"330.497223ms","start":"2026-07-09T00:22:20.675933Z","end":"2026-07-09T00:22:21.006431Z","steps":["trace[651036230] 'process raft request' (duration: 330.246697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:21.006687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:20.675866Z","time spent":"330.680747ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:21.006774Z","caller":"traceutil/trace.go:171","msg":"trace[253896989] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"330.664347ms","start":"2026-07-09T00:22:20.67607Z","end":"2026-07-09T00:22:21.006734Z","steps":["trace[253896989] 'process raft request' (duration: 330.315989ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:21.00682Z","caller":"traceutil/trace.go:171","msg":"trace[1737252706] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"330.652757ms","start":"2026-07-09T00:22:20.67615Z","end":"2026-07-09T00:22:21.006802Z","steps":["trace[1737252706] 'process raft request' (duration: 330.433001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:21.006968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:20.676061Z","time spent":"330.80362ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:21.007116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:20.676142Z","time spent":"330.847331ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:21.009667Z","caller":"traceutil/trace.go:171","msg":"trace[1117222852] linearizableReadLoop","detail":"{readStateIndex:26071; appliedIndex:26067; }","duration":"286.051291ms","start":"2026-07-09T00:22:20.723597Z","end":"2026-07-09T00:22:21.009648Z","steps":["trace[1117222852] 'read index received' (duration: 11.729535ms)","trace[1117222852] 'applied index is now lower than readState.Index' (duration: 274.320446ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:21.009678Z","caller":"traceutil/trace.go:171","msg":"trace[812197036] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"284.41367ms","start":"2026-07-09T00:22:20.725249Z","end":"2026-07-09T00:22:21.009663Z","steps":["trace[812197036] 'process raft request' (duration: 281.454028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:21.009949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.781858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T00:22:21.009949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.521816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:21.009999Z","caller":"traceutil/trace.go:171","msg":"trace[1718661910] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25240; }","duration":"399.841349ms","start":"2026-07-09T00:22:20.610145Z","end":"2026-07-09T00:22:21.009986Z","steps":["trace[1718661910] 'agreement among raft nodes before linearized reading' (duration: 399.622274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:21.010246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:20.610125Z","time spent":"400.103536ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-09T00:22:21.010122Z","caller":"traceutil/trace.go:171","msg":"trace[1132031993] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25240; }","duration":"296.72002ms","start":"2026-07-09T00:22:20.713363Z","end":"2026-07-09T00:22:21.010083Z","steps":["trace[1132031993] 'agreement among raft nodes before linearized reading' (duration: 296.338231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:21.38415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.457468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:21.384221Z","caller":"traceutil/trace.go:171","msg":"trace[1942439245] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25243; }","duration":"170.577361ms","start":"2026-07-09T00:22:21.213629Z","end":"2026-07-09T00:22:21.384206Z","steps":["trace[1942439245] 'count revisions from in-memory index tree' (duration: 170.310905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:21.384611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.948654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:21.384638Z","caller":"traceutil/trace.go:171","msg":"trace[1960725450] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25243; }","duration":"195.018666ms","start":"2026-07-09T00:22:21.18961Z","end":"2026-07-09T00:22:21.384629Z","steps":["trace[1960725450] 'count revisions from in-memory index tree' (duration: 194.858552ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:21.67234Z","caller":"traceutil/trace.go:171","msg":"trace[1447851914] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"261.260288ms","start":"2026-07-09T00:22:21.411052Z","end":"2026-07-09T00:22:21.672312Z","steps":["trace[1447851914] 'process raft request' (duration: 261.056373ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:21.672985Z","caller":"traceutil/trace.go:171","msg":"trace[482759198] linearizableReadLoop","detail":"{readStateIndex:26077; appliedIndex:26075; }","duration":"213.471355ms","start":"2026-07-09T00:22:21.45949Z","end":"2026-07-09T00:22:21.672962Z","steps":["trace[482759198] 'read index received' (duration: 212.718857ms)","trace[482759198] 'applied index is now lower than readState.Index' (duration: 751.408µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:21.67307Z","caller":"traceutil/trace.go:171","msg":"trace[1167311690] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"259.953177ms","start":"2026-07-09T00:22:21.413046Z","end":"2026-07-09T00:22:21.672999Z","steps":["trace[1167311690] 'process raft request' (duration: 259.799083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:21.673184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.674909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-07-09T00:22:21.673214Z","caller":"traceutil/trace.go:171","msg":"trace[13220853] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25245; }","duration":"213.789103ms","start":"2026-07-09T00:22:21.459418Z","end":"2026-07-09T00:22:21.673207Z","steps":["trace[13220853] 'agreement among raft nodes before linearized reading' (duration: 213.652179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:21.675412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.280561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-09T00:22:21.675487Z","caller":"traceutil/trace.go:171","msg":"trace[352894073] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25245; }","duration":"114.412764ms","start":"2026-07-09T00:22:21.561056Z","end":"2026-07-09T00:22:21.675469Z","steps":["trace[352894073] 'agreement among raft nodes before linearized reading' (duration: 112.131578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:22.275596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.427497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T00:22:22.275712Z","caller":"traceutil/trace.go:171","msg":"trace[1143447538] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25246; }","duration":"193.664743ms","start":"2026-07-09T00:22:22.082018Z","end":"2026-07-09T00:22:22.275683Z","steps":["trace[1143447538] 'count revisions from in-memory index tree' (duration: 193.270433ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:22.276365Z","caller":"traceutil/trace.go:171","msg":"trace[755268912] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"143.989243ms","start":"2026-07-09T00:22:22.132335Z","end":"2026-07-09T00:22:22.276324Z","steps":["trace[755268912] 'process raft request' (duration: 113.46396ms)","trace[755268912] 'compare' (duration: 29.586051ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:28.644511Z","caller":"traceutil/trace.go:171","msg":"trace[1661693421] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"170.776726ms","start":"2026-07-09T00:22:28.473697Z","end":"2026-07-09T00:22:28.644474Z","steps":["trace[1661693421] 'process raft request' (duration: 170.55815ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:28.963768Z","caller":"traceutil/trace.go:171","msg":"trace[1774765611] linearizableReadLoop","detail":"{readStateIndex:26128; appliedIndex:26127; }","duration":"186.613201ms","start":"2026-07-09T00:22:28.77713Z","end":"2026-07-09T00:22:28.963744Z","steps":["trace[1774765611] 'read index received' (duration: 186.487097ms)","trace[1774765611] 'applied index is now lower than readState.Index' (duration: 125.364µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:28.963996Z","caller":"traceutil/trace.go:171","msg":"trace[1241210313] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"188.658812ms","start":"2026-07-09T00:22:28.775329Z","end":"2026-07-09T00:22:28.963987Z","steps":["trace[1241210313] 'process raft request' (duration: 188.072867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:28.964366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.196256ms","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-07-09T00:22:28.96448Z","caller":"traceutil/trace.go:171","msg":"trace[434817537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25295; }","duration":"187.355279ms","start":"2026-07-09T00:22:28.777098Z","end":"2026-07-09T00:22:28.964453Z","steps":["trace[434817537] 'agreement among raft nodes before linearized reading' (duration: 187.040602ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:29.107603Z","caller":"traceutil/trace.go:171","msg":"trace[103727201] linearizableReadLoop","detail":"{readStateIndex:26129; appliedIndex:26128; }","duration":"143.739828ms","start":"2026-07-09T00:22:28.963833Z","end":"2026-07-09T00:22:29.107573Z","steps":["trace[103727201] 'read index received' (duration: 133.57215ms)","trace[103727201] 'applied index is now lower than readState.Index' (duration: 10.166238ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:29.107681Z","caller":"traceutil/trace.go:171","msg":"trace[58352590] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"264.317072ms","start":"2026-07-09T00:22:28.843326Z","end":"2026-07-09T00:22:29.107643Z","steps":["trace[58352590] 'process raft request' (duration: 253.766555ms)","trace[58352590] 'compare' (duration: 10.191978ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:29.107887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.828552ms","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-07-09T00:22:29.107976Z","caller":"traceutil/trace.go:171","msg":"trace[437731269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25296; }","duration":"328.981535ms","start":"2026-07-09T00:22:28.778975Z","end":"2026-07-09T00:22:29.107957Z","steps":["trace[437731269] 'agreement among raft nodes before linearized reading' (duration: 328.705149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:29.108019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:28.778955Z","time spent":"329.056777ms","remote":"127.0.0.1:48744","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-07-09T00:22:29.136158Z","caller":"traceutil/trace.go:171","msg":"trace[1255900734] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"166.640585ms","start":"2026-07-09T00:22:28.969483Z","end":"2026-07-09T00:22:29.136123Z","steps":["trace[1255900734] 'process raft request' (duration: 166.373868ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:29.13653Z","caller":"traceutil/trace.go:171","msg":"trace[1813394301] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"101.049079ms","start":"2026-07-09T00:22:29.035432Z","end":"2026-07-09T00:22:29.136481Z","steps":["trace[1813394301] 'process raft request' (duration: 100.628599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:29.136604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.638942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:29.136669Z","caller":"traceutil/trace.go:171","msg":"trace[370544491] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25300; }","duration":"161.739835ms","start":"2026-07-09T00:22:28.974915Z","end":"2026-07-09T00:22:29.136655Z","steps":["trace[370544491] 'agreement among raft nodes before linearized reading' (duration: 161.542671ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:29.443772Z","caller":"traceutil/trace.go:171","msg":"trace[1856778090] linearizableReadLoop","detail":"{readStateIndex:26135; appliedIndex:26133; }","duration":"302.679156ms","start":"2026-07-09T00:22:29.141058Z","end":"2026-07-09T00:22:29.443737Z","steps":["trace[1856778090] 'read index received' (duration: 302.250725ms)","trace[1856778090] 'applied index is now lower than readState.Index' (duration: 427.061µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:29.44392Z","caller":"traceutil/trace.go:171","msg":"trace[1960144263] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"331.292322ms","start":"2026-07-09T00:22:29.112616Z","end":"2026-07-09T00:22:29.443908Z","steps":["trace[1960144263] 'process raft request' (duration: 330.81214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:29.444044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:29.112594Z","time spent":"331.357424ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:29.444403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.093113ms","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-07-09T00:22:29.444467Z","caller":"traceutil/trace.go:171","msg":"trace[1995696909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25302; }","duration":"298.170146ms","start":"2026-07-09T00:22:29.146286Z","end":"2026-07-09T00:22:29.444457Z","steps":["trace[1995696909] 'agreement among raft nodes before linearized reading' (duration: 298.004272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:29.444373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.300161ms","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-07-09T00:22:29.444636Z","caller":"traceutil/trace.go:171","msg":"trace[301133125] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"321.520604ms","start":"2026-07-09T00:22:29.123081Z","end":"2026-07-09T00:22:29.444602Z","steps":["trace[301133125] 'process raft request' (duration: 320.565961ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:29.444658Z","caller":"traceutil/trace.go:171","msg":"trace[2043315377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25302; }","duration":"303.627019ms","start":"2026-07-09T00:22:29.141Z","end":"2026-07-09T00:22:29.444627Z","steps":["trace[2043315377] 'agreement among raft nodes before linearized reading' (duration: 303.104406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:29.444756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:29.140975Z","time spent":"303.752651ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T00:22:29.444796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:29.12307Z","time spent":"321.641227ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:29.726974Z","caller":"traceutil/trace.go:171","msg":"trace[1780354821] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"276.887318ms","start":"2026-07-09T00:22:29.450057Z","end":"2026-07-09T00:22:29.726945Z","steps":["trace[1780354821] 'process raft request' (duration: 272.843041ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:30.059591Z","caller":"traceutil/trace.go:171","msg":"trace[1969791057] linearizableReadLoop","detail":"{readStateIndex:26139; appliedIndex:26138; }","duration":"141.089584ms","start":"2026-07-09T00:22:29.918474Z","end":"2026-07-09T00:22:30.059563Z","steps":["trace[1969791057] 'read index received' (duration: 140.839578ms)","trace[1969791057] 'applied index is now lower than readState.Index' (duration: 249.076µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:30.059768Z","caller":"traceutil/trace.go:171","msg":"trace[1872148066] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"326.197419ms","start":"2026-07-09T00:22:29.73352Z","end":"2026-07-09T00:22:30.059717Z","steps":["trace[1872148066] 'process raft request' (duration: 325.895961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:30.060021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:29.7335Z","time spent":"326.401234ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:30.060013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.507864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-09T00:22:30.060112Z","caller":"traceutil/trace.go:171","msg":"trace[696371400] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:25306; }","duration":"141.657257ms","start":"2026-07-09T00:22:29.91843Z","end":"2026-07-09T00:22:30.060087Z","steps":["trace[696371400] 'agreement among raft nodes before linearized reading' (duration: 141.392681ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:30.407749Z","caller":"traceutil/trace.go:171","msg":"trace[949040607] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"320.982922ms","start":"2026-07-09T00:22:30.086735Z","end":"2026-07-09T00:22:30.407718Z","steps":["trace[949040607] 'process raft request' (duration: 268.763071ms)","trace[949040607] 'compare' (duration: 51.76938ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:30.407919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:30.086704Z","time spent":"321.133935ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:30.682597Z","caller":"traceutil/trace.go:171","msg":"trace[1829548630] linearizableReadLoop","detail":"{readStateIndex:26142; appliedIndex:26140; }","duration":"367.003051ms","start":"2026-07-09T00:22:30.315562Z","end":"2026-07-09T00:22:30.682565Z","steps":["trace[1829548630] 'read index received' (duration: 40.094826ms)","trace[1829548630] 'applied index is now lower than readState.Index' (duration: 326.906965ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:30.682676Z","caller":"traceutil/trace.go:171","msg":"trace[939733797] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"371.299385ms","start":"2026-07-09T00:22:30.311326Z","end":"2026-07-09T00:22:30.682625Z","steps":["trace[939733797] 'process raft request' (duration: 288.380757ms)","trace[939733797] 'compare' (duration: 82.675992ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:30.682921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.337259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-07-09T00:22:30.682928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:30.311302Z","time spent":"371.475049ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:30.682997Z","caller":"traceutil/trace.go:171","msg":"trace[344179034] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25308; }","duration":"367.435891ms","start":"2026-07-09T00:22:30.315544Z","end":"2026-07-09T00:22:30.68298Z","steps":["trace[344179034] 'agreement among raft nodes before linearized reading' (duration: 367.140094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:30.68305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:30.31548Z","time spent":"367.555255ms","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":178,"response size":32,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-09T00:22:30.710292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.542213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:30.710418Z","caller":"traceutil/trace.go:171","msg":"trace[325467013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25309; }","duration":"330.725309ms","start":"2026-07-09T00:22:30.37967Z","end":"2026-07-09T00:22:30.710396Z","steps":["trace[325467013] 'agreement among raft nodes before linearized reading' (duration: 330.412241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:30.710445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.110745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T00:22:30.710463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:30.379646Z","time spent":"330.80729ms","remote":"127.0.0.1:48452","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-07-09T00:22:30.710414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.686173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-09T00:22:30.710646Z","caller":"traceutil/trace.go:171","msg":"trace[951865732] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25309; }","duration":"299.96593ms","start":"2026-07-09T00:22:30.410658Z","end":"2026-07-09T00:22:30.710624Z","steps":["trace[951865732] 'agreement among raft nodes before linearized reading' (duration: 299.639502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:30.710708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:30.41064Z","time spent":"300.047882ms","remote":"127.0.0.1:48640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-09T00:22:30.710518Z","caller":"traceutil/trace.go:171","msg":"trace[346446423] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25309; }","duration":"321.213847ms","start":"2026-07-09T00:22:30.389281Z","end":"2026-07-09T00:22:30.710495Z","steps":["trace[346446423] 'agreement among raft nodes before linearized reading' (duration: 321.090624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:30.711092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:30.389261Z","time spent":"321.807082ms","remote":"127.0.0.1:54460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-07-09T00:22:39.222853Z","caller":"traceutil/trace.go:171","msg":"trace[602802805] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"100.197519ms","start":"2026-07-09T00:22:39.122623Z","end":"2026-07-09T00:22:39.22282Z","steps":["trace[602802805] 'process raft request' (duration: 99.929042ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:39.357059Z","caller":"traceutil/trace.go:171","msg":"trace[1021306931] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"177.385918ms","start":"2026-07-09T00:22:39.179642Z","end":"2026-07-09T00:22:39.357028Z","steps":["trace[1021306931] 'process raft request' (duration: 177.147012ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:39.357308Z","caller":"traceutil/trace.go:171","msg":"trace[335946720] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"177.504161ms","start":"2026-07-09T00:22:39.179779Z","end":"2026-07-09T00:22:39.357283Z","steps":["trace[335946720] 'process raft request' (duration: 177.199114ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:39.357692Z","caller":"traceutil/trace.go:171","msg":"trace[37634583] linearizableReadLoop","detail":"{readStateIndex:26266; appliedIndex:26262; }","duration":"165.266082ms","start":"2026-07-09T00:22:39.192389Z","end":"2026-07-09T00:22:39.357655Z","steps":["trace[37634583] 'read index received' (duration: 30.133294ms)","trace[37634583] 'applied index is now lower than readState.Index' (duration: 135.130768ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:39.357747Z","caller":"traceutil/trace.go:171","msg":"trace[1283456042] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"169.418343ms","start":"2026-07-09T00:22:39.188304Z","end":"2026-07-09T00:22:39.357723Z","steps":["trace[1283456042] 'process raft request' (duration: 168.897301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:39.357952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.546208ms","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-07-09T00:22:39.358001Z","caller":"traceutil/trace.go:171","msg":"trace[752800885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25430; }","duration":"165.661401ms","start":"2026-07-09T00:22:39.192327Z","end":"2026-07-09T00:22:39.357988Z","steps":["trace[752800885] 'agreement among raft nodes before linearized reading' (duration: 165.448946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:39.783067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.918695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-09T00:22:39.783163Z","caller":"traceutil/trace.go:171","msg":"trace[871644034] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25431; }","duration":"313.094749ms","start":"2026-07-09T00:22:39.470046Z","end":"2026-07-09T00:22:39.78314Z","steps":["trace[871644034] 'range keys from in-memory index tree' (duration: 312.33404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:39.783211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:39.470016Z","time spent":"313.183511ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"warn","ts":"2026-07-09T00:22:39.783543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.961012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:22:39.78364Z","caller":"traceutil/trace.go:171","msg":"trace[1075446366] linearizableReadLoop","detail":"{readStateIndex:26268; appliedIndex:26267; }","duration":"290.663574ms","start":"2026-07-09T00:22:39.492964Z","end":"2026-07-09T00:22:39.783627Z","steps":["trace[1075446366] 'read index received' (duration: 167.392194ms)","trace[1075446366] 'applied index is now lower than readState.Index' (duration: 123.2697ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:39.783754Z","caller":"traceutil/trace.go:171","msg":"trace[343512296] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"298.564606ms","start":"2026-07-09T00:22:39.485115Z","end":"2026-07-09T00:22:39.783679Z","steps":["trace[343512296] 'process raft request' (duration: 175.296326ms)","trace[343512296] 'compare' (duration: 122.101251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:39.783914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.92072ms","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-07-09T00:22:39.784024Z","caller":"traceutil/trace.go:171","msg":"trace[1187050670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25432; }","duration":"291.096834ms","start":"2026-07-09T00:22:39.4929Z","end":"2026-07-09T00:22:39.783997Z","steps":["trace[1187050670] 'agreement among raft nodes before linearized reading' (duration: 290.779476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:39.787765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.609203ms","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-07-09T00:22:39.787883Z","caller":"traceutil/trace.go:171","msg":"trace[1036318921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25432; }","duration":"265.768337ms","start":"2026-07-09T00:22:39.522071Z","end":"2026-07-09T00:22:39.787839Z","steps":["trace[1036318921] 'agreement among raft nodes before linearized reading' (duration: 261.653547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.070705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.561404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:22:40.070887Z","caller":"traceutil/trace.go:171","msg":"trace[124858164] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"282.216157ms","start":"2026-07-09T00:22:39.788645Z","end":"2026-07-09T00:22:40.070861Z","steps":["trace[124858164] 'process raft request' (duration: 161.417138ms)","trace[124858164] 'compare' (duration: 120.383819ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:40.083407Z","caller":"traceutil/trace.go:171","msg":"trace[1106062259] linearizableReadLoop","detail":"{readStateIndex:26271; appliedIndex:26268; }","duration":"271.044866ms","start":"2026-07-09T00:22:39.812325Z","end":"2026-07-09T00:22:40.08337Z","steps":["trace[1106062259] 'read index received' (duration: 137.894985ms)","trace[1106062259] 'applied index is now lower than readState.Index' (duration: 133.148611ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:40.083543Z","caller":"traceutil/trace.go:171","msg":"trace[1641892001] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"291.319199ms","start":"2026-07-09T00:22:39.792184Z","end":"2026-07-09T00:22:40.083503Z","steps":["trace[1641892001] 'process raft request' (duration: 291.135005ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:40.083481Z","caller":"traceutil/trace.go:171","msg":"trace[843011995] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"292.301003ms","start":"2026-07-09T00:22:39.791144Z","end":"2026-07-09T00:22:40.083445Z","steps":["trace[843011995] 'process raft request' (duration: 292.005016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.083672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.342643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-09T00:22:40.083703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.575553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c26a7c62-ba4a-42f6-aca7-ac748c5bc6b9\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-09T00:22:40.083731Z","caller":"traceutil/trace.go:171","msg":"trace[735102549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25435; }","duration":"271.444125ms","start":"2026-07-09T00:22:39.81227Z","end":"2026-07-09T00:22:40.083714Z","steps":["trace[735102549] 'agreement among raft nodes before linearized reading' (duration: 271.257781ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:40.083748Z","caller":"traceutil/trace.go:171","msg":"trace[339853814] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c26a7c62-ba4a-42f6-aca7-ac748c5bc6b9; range_end:; response_count:1; response_revision:25435; }","duration":"242.685486ms","start":"2026-07-09T00:22:39.841047Z","end":"2026-07-09T00:22:40.083733Z","steps":["trace[339853814] 'agreement among raft nodes before linearized reading' (duration: 242.496401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.083794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.657345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-09T00:22:40.083862Z","caller":"traceutil/trace.go:171","msg":"trace[976270012] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25435; }","duration":"228.783398ms","start":"2026-07-09T00:22:39.85506Z","end":"2026-07-09T00:22:40.083844Z","steps":["trace[976270012] 'agreement among raft nodes before linearized reading' (duration: 228.642045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.083854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.053517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-09T00:22:40.083937Z","caller":"traceutil/trace.go:171","msg":"trace[1859588195] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:25435; }","duration":"165.211201ms","start":"2026-07-09T00:22:39.918702Z","end":"2026-07-09T00:22:40.083914Z","steps":["trace[1859588195] 'agreement among raft nodes before linearized reading' (duration: 164.895604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.345691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.431285ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-09T00:22:40.34589Z","caller":"traceutil/trace.go:171","msg":"trace[1978044507] linearizableReadLoop","detail":"{readStateIndex:26272; appliedIndex:26271; }","duration":"259.375982ms","start":"2026-07-09T00:22:40.086452Z","end":"2026-07-09T00:22:40.345828Z","steps":["trace[1978044507] 'read index received' (duration: 137.854634ms)","trace[1978044507] 'applied index is now lower than readState.Index' (duration: 121.518928ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:40.346155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.689289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c26a7c62-ba4a-42f6-aca7-ac748c5bc6b9\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-09T00:22:40.346217Z","caller":"traceutil/trace.go:171","msg":"trace[709705834] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c26a7c62-ba4a-42f6-aca7-ac748c5bc6b9; range_end:; response_count:1; response_revision:25435; }","duration":"259.780162ms","start":"2026-07-09T00:22:40.086417Z","end":"2026-07-09T00:22:40.346198Z","steps":["trace[709705834] 'agreement among raft nodes before linearized reading' (duration: 259.555267ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:40.443587Z","caller":"traceutil/trace.go:171","msg":"trace[729365439] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"355.247665ms","start":"2026-07-09T00:22:40.088308Z","end":"2026-07-09T00:22:40.443556Z","steps":["trace[729365439] 'process raft request' (duration: 354.972278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.44381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:40.088276Z","time spent":"355.442589ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:40.443838Z","caller":"traceutil/trace.go:171","msg":"trace[708295577] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"351.173126ms","start":"2026-07-09T00:22:40.092618Z","end":"2026-07-09T00:22:40.443791Z","steps":["trace[708295577] 'process raft request' (duration: 350.859428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.443972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.761792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-09T00:22:40.444055Z","caller":"traceutil/trace.go:171","msg":"trace[451275669] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25437; }","duration":"326.887755ms","start":"2026-07-09T00:22:40.117147Z","end":"2026-07-09T00:22:40.444035Z","steps":["trace[451275669] 'agreement among raft nodes before linearized reading' (duration: 326.66115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.444058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:40.09259Z","time spent":"351.348821ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:40.444098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:40.117135Z","time spent":"326.953086ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-09T00:22:40.707643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.365855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:22:40.70808Z","caller":"traceutil/trace.go:171","msg":"trace[1955120923] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"258.905981ms","start":"2026-07-09T00:22:40.449159Z","end":"2026-07-09T00:22:40.708065Z","steps":["trace[1955120923] 'process raft request' (duration: 258.603654ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:40.708256Z","caller":"traceutil/trace.go:171","msg":"trace[1671181823] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"361.433925ms","start":"2026-07-09T00:22:40.346815Z","end":"2026-07-09T00:22:40.708249Z","steps":["trace[1671181823] 'process raft request' (duration: 174.360223ms)","trace[1671181823] 'compare' (duration: 186.062528ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:40.708295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:40.346786Z","time spent":"361.488657ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:40.708391Z","caller":"traceutil/trace.go:171","msg":"trace[962210669] linearizableReadLoop","detail":"{readStateIndex:26275; appliedIndex:26274; }","duration":"264.679901ms","start":"2026-07-09T00:22:40.443704Z","end":"2026-07-09T00:22:40.708384Z","steps":["trace[962210669] 'read index received' (duration: 77.491655ms)","trace[962210669] 'applied index is now lower than readState.Index' (duration: 187.187556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:40.70848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.52568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:40.7085Z","caller":"traceutil/trace.go:171","msg":"trace[554973850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25439; }","duration":"329.587231ms","start":"2026-07-09T00:22:40.378908Z","end":"2026-07-09T00:22:40.708495Z","steps":["trace[554973850] 'agreement among raft nodes before linearized reading' (duration: 329.502799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.708516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:40.378885Z","time spent":"329.627712ms","remote":"127.0.0.1:48452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T00:22:40.988938Z","caller":"traceutil/trace.go:171","msg":"trace[1384350026] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"261.852581ms","start":"2026-07-09T00:22:40.727033Z","end":"2026-07-09T00:22:40.988885Z","steps":["trace[1384350026] 'process raft request' (duration: 175.950931ms)","trace[1384350026] 'compare' (duration: 85.394228ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:40.989329Z","caller":"traceutil/trace.go:171","msg":"trace[638319179] linearizableReadLoop","detail":"{readStateIndex:26278; appliedIndex:26276; }","duration":"146.251679ms","start":"2026-07-09T00:22:40.843054Z","end":"2026-07-09T00:22:40.989305Z","steps":["trace[638319179] 'read index received' (duration: 60.02217ms)","trace[638319179] 'applied index is now lower than readState.Index' (duration: 86.228359ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:40.989363Z","caller":"traceutil/trace.go:171","msg":"trace[1416798968] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"239.751794ms","start":"2026-07-09T00:22:40.7496Z","end":"2026-07-09T00:22:40.989352Z","steps":["trace[1416798968] 'process raft request' (duration: 239.213071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.989495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.396842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:40.989582Z","caller":"traceutil/trace.go:171","msg":"trace[261965972] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25441; }","duration":"146.626088ms","start":"2026-07-09T00:22:40.842947Z","end":"2026-07-09T00:22:40.989573Z","steps":["trace[261965972] 'agreement among raft nodes before linearized reading' (duration: 146.442364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:40.989713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.267506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:40.989808Z","caller":"traceutil/trace.go:171","msg":"trace[1415315069] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:25441; }","duration":"106.4418ms","start":"2026-07-09T00:22:40.883341Z","end":"2026-07-09T00:22:40.989782Z","steps":["trace[1415315069] 'agreement among raft nodes before linearized reading' (duration: 106.222195ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:43.10761Z","caller":"traceutil/trace.go:171","msg":"trace[1544737426] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"113.535263ms","start":"2026-07-09T00:22:42.994042Z","end":"2026-07-09T00:22:43.107577Z","steps":["trace[1544737426] 'process raft request' (duration: 113.345528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:43.379193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.561355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:22:43.379584Z","caller":"traceutil/trace.go:171","msg":"trace[2013747736] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"384.857606ms","start":"2026-07-09T00:22:42.994695Z","end":"2026-07-09T00:22:43.379553Z","steps":["trace[2013747736] 'process raft request' (duration: 244.841379ms)","trace[2013747736] 'compare' (duration: 139.402161ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:43.379674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:42.994659Z","time spent":"384.979239ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:43.380065Z","caller":"traceutil/trace.go:171","msg":"trace[30712535] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"364.911681ms","start":"2026-07-09T00:22:43.015141Z","end":"2026-07-09T00:22:43.380052Z","steps":["trace[30712535] 'process raft request' (duration: 364.351157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:43.380151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:43.015117Z","time spent":"365.005704ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:57.56448Z","caller":"traceutil/trace.go:171","msg":"trace[1853826596] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"134.498944ms","start":"2026-07-09T00:22:57.42995Z","end":"2026-07-09T00:22:57.564449Z","steps":["trace[1853826596] 'process raft request' (duration: 134.268248ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:57.704976Z","caller":"traceutil/trace.go:171","msg":"trace[1893791642] transaction","detail":"{read_only:false; response_revision:25590; number_of_response:1; }","duration":"271.384554ms","start":"2026-07-09T00:22:57.433564Z","end":"2026-07-09T00:22:57.704949Z","steps":["trace[1893791642] 'process raft request' (duration: 270.949694ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:57.705244Z","caller":"traceutil/trace.go:171","msg":"trace[798632878] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"205.356557ms","start":"2026-07-09T00:22:57.499863Z","end":"2026-07-09T00:22:57.70522Z","steps":["trace[798632878] 'process raft request' (duration: 204.991988ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:57.705673Z","caller":"traceutil/trace.go:171","msg":"trace[1886587459] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"204.604568ms","start":"2026-07-09T00:22:57.501052Z","end":"2026-07-09T00:22:57.705656Z","steps":["trace[1886587459] 'process raft request' (duration: 204.081676ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:57.70577Z","caller":"traceutil/trace.go:171","msg":"trace[923218072] linearizableReadLoop","detail":"{readStateIndex:26431; appliedIndex:26429; }","duration":"210.488162ms","start":"2026-07-09T00:22:57.495271Z","end":"2026-07-09T00:22:57.705759Z","steps":["trace[923218072] 'read index received' (duration: 68.980289ms)","trace[923218072] 'applied index is now lower than readState.Index' (duration: 141.506793ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:57.706011Z","caller":"traceutil/trace.go:171","msg":"trace[217594357] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"202.925228ms","start":"2026-07-09T00:22:57.503068Z","end":"2026-07-09T00:22:57.705993Z","steps":["trace[217594357] 'process raft request' (duration: 202.538018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:57.706274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.980903ms","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-07-09T00:22:57.706341Z","caller":"traceutil/trace.go:171","msg":"trace[587181066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25593; }","duration":"183.112166ms","start":"2026-07-09T00:22:57.523214Z","end":"2026-07-09T00:22:57.706326Z","steps":["trace[587181066] 'agreement among raft nodes before linearized reading' (duration: 182.943702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:57.706612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.156807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:987"} {"level":"info","ts":"2026-07-09T00:22:57.706693Z","caller":"traceutil/trace.go:171","msg":"trace[895304188] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:25593; }","duration":"162.266759ms","start":"2026-07-09T00:22:57.544408Z","end":"2026-07-09T00:22:57.706675Z","steps":["trace[895304188] 'agreement among raft nodes before linearized reading' (duration: 161.897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:57.70761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.79089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:57.707715Z","caller":"traceutil/trace.go:171","msg":"trace[171220823] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25593; }","duration":"212.47071ms","start":"2026-07-09T00:22:57.49522Z","end":"2026-07-09T00:22:57.70769Z","steps":["trace[171220823] 'agreement among raft nodes before linearized reading' (duration: 210.771739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:58.062555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.499408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:22:58.062778Z","caller":"traceutil/trace.go:171","msg":"trace[1094844557] transaction","detail":"{read_only:false; response_revision:25595; number_of_response:1; }","duration":"205.041849ms","start":"2026-07-09T00:22:57.857691Z","end":"2026-07-09T00:22:58.062733Z","steps":["trace[1094844557] 'process raft request' (duration: 85.250374ms)","trace[1094844557] 'compare' (duration: 119.319124ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:58.06268Z","caller":"traceutil/trace.go:171","msg":"trace[1080923872] linearizableReadLoop","detail":"{readStateIndex:26436; appliedIndex:26435; }","duration":"176.771171ms","start":"2026-07-09T00:22:57.885888Z","end":"2026-07-09T00:22:58.062659Z","steps":["trace[1080923872] 'read index received' (duration: 56.952056ms)","trace[1080923872] 'applied index is now lower than readState.Index' (duration: 119.817245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:58.063143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.265073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-07-09T00:22:58.063195Z","caller":"traceutil/trace.go:171","msg":"trace[1175123253] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25595; }","duration":"177.372996ms","start":"2026-07-09T00:22:57.885809Z","end":"2026-07-09T00:22:58.063182Z","steps":["trace[1175123253] 'agreement among raft nodes before linearized reading' (duration: 177.10761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:58.371454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.388319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-09T00:22:58.371562Z","caller":"traceutil/trace.go:171","msg":"trace[1933411464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25595; }","duration":"198.541982ms","start":"2026-07-09T00:22:58.172997Z","end":"2026-07-09T00:22:58.371539Z","steps":["trace[1933411464] 'range keys from in-memory index tree' (duration: 198.234105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:58.371594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.653074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-09T00:22:58.371643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.609914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:58.3717Z","caller":"traceutil/trace.go:171","msg":"trace[473474893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25595; }","duration":"205.841829ms","start":"2026-07-09T00:22:58.16583Z","end":"2026-07-09T00:22:58.371672Z","steps":["trace[473474893] 'range keys from in-memory index tree' (duration: 205.388248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:58.37175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.364154ms","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-07-09T00:22:58.371868Z","caller":"traceutil/trace.go:171","msg":"trace[2110485070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25595; }","duration":"204.571278ms","start":"2026-07-09T00:22:58.167273Z","end":"2026-07-09T00:22:58.371844Z","steps":["trace[2110485070] 'range keys from in-memory index tree' (duration: 204.109578ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:58.371771Z","caller":"traceutil/trace.go:171","msg":"trace[1902071958] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25595; }","duration":"159.780888ms","start":"2026-07-09T00:22:58.211958Z","end":"2026-07-09T00:22:58.371739Z","steps":["trace[1902071958] 'range keys from in-memory index tree' (duration: 159.508802ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:58.509051Z","caller":"traceutil/trace.go:171","msg":"trace[57156424] transaction","detail":"{read_only:false; response_revision:25596; number_of_response:1; }","duration":"131.948841ms","start":"2026-07-09T00:22:58.377057Z","end":"2026-07-09T00:22:58.509006Z","steps":["trace[57156424] 'process raft request' (duration: 131.656764ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:58.795948Z","caller":"traceutil/trace.go:171","msg":"trace[727972275] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"418.3046ms","start":"2026-07-09T00:22:58.377605Z","end":"2026-07-09T00:22:58.795909Z","steps":["trace[727972275] 'process raft request' (duration: 392.888622ms)","trace[727972275] 'compare' (duration: 24.944746ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:58.796209Z","caller":"traceutil/trace.go:171","msg":"trace[2051670694] linearizableReadLoop","detail":"{readStateIndex:26439; appliedIndex:26436; }","duration":"416.922596ms","start":"2026-07-09T00:22:58.379257Z","end":"2026-07-09T00:22:58.796179Z","steps":["trace[2051670694] 'read index received' (duration: 129.409189ms)","trace[2051670694] 'applied index is now lower than readState.Index' (duration: 287.511587ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:58.796225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:58.377589Z","time spent":"418.485094ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:22:58.796467Z","caller":"traceutil/trace.go:171","msg":"trace[623987067] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"418.243287ms","start":"2026-07-09T00:22:58.378195Z","end":"2026-07-09T00:22:58.796439Z","steps":["trace[623987067] 'process raft request' (duration: 417.650823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:58.796598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:58.378189Z","time spent":"418.34004ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T00:22:58.796628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.344446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:58.796801Z","caller":"traceutil/trace.go:171","msg":"trace[319144920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25598; }","duration":"417.461259ms","start":"2026-07-09T00:22:58.37922Z","end":"2026-07-09T00:22:58.796681Z","steps":["trace[319144920] 'agreement among raft nodes before linearized reading' (duration: 417.272764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:58.796841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.942606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-09T00:22:58.796857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.167099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-07-09T00:22:58.796894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:58.37918Z","time spent":"417.680884ms","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T00:22:58.796912Z","caller":"traceutil/trace.go:171","msg":"trace[1095607238] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25598; }","duration":"417.024858ms","start":"2026-07-09T00:22:58.379871Z","end":"2026-07-09T00:22:58.796895Z","steps":["trace[1095607238] 'agreement among raft nodes before linearized reading' (duration: 416.809073ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:58.796923Z","caller":"traceutil/trace.go:171","msg":"trace[1417208782] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25598; }","duration":"308.23495ms","start":"2026-07-09T00:22:58.488671Z","end":"2026-07-09T00:22:58.796906Z","steps":["trace[1417208782] 'agreement among raft nodes before linearized reading' (duration: 308.052416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:58.796888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.870413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-09T00:22:58.79696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:58.488642Z","time spent":"308.309202ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"warn","ts":"2026-07-09T00:22:58.796959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:22:58.379853Z","time spent":"417.09438ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-09T00:22:58.797Z","caller":"traceutil/trace.go:171","msg":"trace[780980826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25598; }","duration":"163.044598ms","start":"2026-07-09T00:22:58.63393Z","end":"2026-07-09T00:22:58.796975Z","steps":["trace[780980826] 'agreement among raft nodes before linearized reading' (duration: 162.753841ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:59.024839Z","caller":"traceutil/trace.go:171","msg":"trace[1505259622] linearizableReadLoop","detail":"{readStateIndex:26440; appliedIndex:26439; }","duration":"220.085895ms","start":"2026-07-09T00:22:58.804705Z","end":"2026-07-09T00:22:59.024791Z","steps":["trace[1505259622] 'read index received' (duration: 125.779ms)","trace[1505259622] 'applied index is now lower than readState.Index' (duration: 94.305275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:22:59.025375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.63708ms","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-07-09T00:22:59.02538Z","caller":"traceutil/trace.go:171","msg":"trace[434293723] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"221.033929ms","start":"2026-07-09T00:22:58.8043Z","end":"2026-07-09T00:22:59.025334Z","steps":["trace[434293723] 'process raft request' (duration: 126.271512ms)","trace[434293723] 'compare' (duration: 93.970827ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:22:59.025669Z","caller":"traceutil/trace.go:171","msg":"trace[1338927483] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25599; }","duration":"220.969867ms","start":"2026-07-09T00:22:58.804678Z","end":"2026-07-09T00:22:59.025648Z","steps":["trace[1338927483] 'agreement among raft nodes before linearized reading' (duration: 220.575478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:59.025812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.373508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-09T00:22:59.025806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.582086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-09T00:22:59.025928Z","caller":"traceutil/trace.go:171","msg":"trace[890805457] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25599; }","duration":"196.511201ms","start":"2026-07-09T00:22:58.82939Z","end":"2026-07-09T00:22:59.025901Z","steps":["trace[890805457] 'agreement among raft nodes before linearized reading' (duration: 195.954188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:22:59.025681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.156519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:22:59.026091Z","caller":"traceutil/trace.go:171","msg":"trace[687692751] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25599; }","duration":"122.02729ms","start":"2026-07-09T00:22:58.904041Z","end":"2026-07-09T00:22:59.026068Z","steps":["trace[687692751] 'agreement among raft nodes before linearized reading' (duration: 121.297873ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:59.026676Z","caller":"traceutil/trace.go:171","msg":"trace[1999215426] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25599; }","duration":"215.877093ms","start":"2026-07-09T00:22:58.810177Z","end":"2026-07-09T00:22:59.026054Z","steps":["trace[1999215426] 'agreement among raft nodes before linearized reading' (duration: 215.169076ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:22:59.888124Z","caller":"traceutil/trace.go:171","msg":"trace[1091879767] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"105.792945ms","start":"2026-07-09T00:22:59.782296Z","end":"2026-07-09T00:22:59.888089Z","steps":["trace[1091879767] 'process raft request' (duration: 42.866273ms)","trace[1091879767] 'compare' (duration: 62.619794ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:23:11.38071Z","caller":"traceutil/trace.go:171","msg":"trace[1504680184] linearizableReadLoop","detail":"{readStateIndex:26548; appliedIndex:26547; }","duration":"148.025662ms","start":"2026-07-09T00:23:11.232646Z","end":"2026-07-09T00:23:11.380672Z","steps":["trace[1504680184] 'read index received' (duration: 131.991262ms)","trace[1504680184] 'applied index is now lower than readState.Index' (duration: 16.03284ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:23:11.381063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.080468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:23:11.381102Z","caller":"traceutil/trace.go:171","msg":"trace[111140980] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25704; }","duration":"163.202981ms","start":"2026-07-09T00:23:11.217887Z","end":"2026-07-09T00:23:11.38109Z","steps":["trace[111140980] 'agreement among raft nodes before linearized reading' (duration: 163.087438ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:11.381203Z","caller":"traceutil/trace.go:171","msg":"trace[1026558456] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"159.305867ms","start":"2026-07-09T00:23:11.221686Z","end":"2026-07-09T00:23:11.380992Z","steps":["trace[1026558456] 'process raft request' (duration: 142.927848ms)","trace[1026558456] 'compare' (duration: 15.691912ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:23:11.529008Z","caller":"traceutil/trace.go:171","msg":"trace[506611066] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"290.635472ms","start":"2026-07-09T00:23:11.238344Z","end":"2026-07-09T00:23:11.52898Z","steps":["trace[506611066] 'process raft request' (duration: 290.390396ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:11.529134Z","caller":"traceutil/trace.go:171","msg":"trace[717189882] linearizableReadLoop","detail":"{readStateIndex:26550; appliedIndex:26548; }","duration":"148.326209ms","start":"2026-07-09T00:23:11.38079Z","end":"2026-07-09T00:23:11.529116Z","steps":["trace[717189882] 'read index received' (duration: 147.94022ms)","trace[717189882] 'applied index is now lower than readState.Index' (duration: 384.349µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:23:11.529184Z","caller":"traceutil/trace.go:171","msg":"trace[2019639454] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"244.552151ms","start":"2026-07-09T00:23:11.284588Z","end":"2026-07-09T00:23:11.529141Z","steps":["trace[2019639454] 'process raft request' (duration: 244.337016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:23:11.529231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.970645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:23:11.529473Z","caller":"traceutil/trace.go:171","msg":"trace[411486465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25706; }","duration":"149.235111ms","start":"2026-07-09T00:23:11.38019Z","end":"2026-07-09T00:23:11.529425Z","steps":["trace[411486465] 'agreement among raft nodes before linearized reading' (duration: 148.971814ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:12.083241Z","caller":"traceutil/trace.go:171","msg":"trace[2094424194] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"212.907242ms","start":"2026-07-09T00:23:11.870298Z","end":"2026-07-09T00:23:12.083205Z","steps":["trace[2094424194] 'process raft request' (duration: 171.124375ms)","trace[2094424194] 'compare' (duration: 41.334046ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:23:12.083267Z","caller":"traceutil/trace.go:171","msg":"trace[133008679] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"209.902959ms","start":"2026-07-09T00:23:11.873301Z","end":"2026-07-09T00:23:12.083203Z","steps":["trace[133008679] 'process raft request' (duration: 209.665973ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:12.083338Z","caller":"traceutil/trace.go:171","msg":"trace[698083560] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"211.238171ms","start":"2026-07-09T00:23:11.872068Z","end":"2026-07-09T00:23:12.083306Z","steps":["trace[698083560] 'process raft request' (duration: 210.860662ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:23.504246Z","caller":"traceutil/trace.go:171","msg":"trace[90332751] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"164.528474ms","start":"2026-07-09T00:23:23.339669Z","end":"2026-07-09T00:23:23.504197Z","steps":["trace[90332751] 'process raft request' (duration: 164.191846ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:23.74352Z","caller":"traceutil/trace.go:171","msg":"trace[506754021] linearizableReadLoop","detail":"{readStateIndex:26637; appliedIndex:26636; }","duration":"155.745959ms","start":"2026-07-09T00:23:23.587746Z","end":"2026-07-09T00:23:23.743492Z","steps":["trace[506754021] 'read index received' (duration: 155.487393ms)","trace[506754021] 'applied index is now lower than readState.Index' (duration: 257.016µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:23:23.743656Z","caller":"traceutil/trace.go:171","msg":"trace[1313900050] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"162.484784ms","start":"2026-07-09T00:23:23.581107Z","end":"2026-07-09T00:23:23.743592Z","steps":["trace[1313900050] 'process raft request' (duration: 162.196007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:23:23.743762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.701973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-09T00:23:23.743863Z","caller":"traceutil/trace.go:171","msg":"trace[565161086] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25791; }","duration":"103.826536ms","start":"2026-07-09T00:23:23.640013Z","end":"2026-07-09T00:23:23.74384Z","steps":["trace[565161086] 'agreement among raft nodes before linearized reading' (duration: 103.664842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:23:23.743889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.121958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-09T00:23:23.744Z","caller":"traceutil/trace.go:171","msg":"trace[1443253879] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25791; }","duration":"156.357795ms","start":"2026-07-09T00:23:23.587612Z","end":"2026-07-09T00:23:23.74397Z","steps":["trace[1443253879] 'agreement among raft nodes before linearized reading' (duration: 155.995117ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:23.880385Z","caller":"traceutil/trace.go:171","msg":"trace[1919137488] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"131.09682ms","start":"2026-07-09T00:23:23.749248Z","end":"2026-07-09T00:23:23.880344Z","steps":["trace[1919137488] 'process raft request' (duration: 129.696556ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:26.872415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22419} {"level":"info","ts":"2026-07-09T00:23:26.98115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22419,"took":"104.700699ms","hash":2148217677,"current-db-size-bytes":57118720,"current-db-size":"57 MB","current-db-size-in-use-bytes":45694976,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-07-09T00:23:26.981226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2148217677,"revision":22419,"compact-revision":20109} {"level":"info","ts":"2026-07-09T00:23:33.976598Z","caller":"traceutil/trace.go:171","msg":"trace[268605036] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"198.697245ms","start":"2026-07-09T00:23:33.777871Z","end":"2026-07-09T00:23:33.976568Z","steps":["trace[268605036] 'process raft request' (duration: 198.520011ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:34.224381Z","caller":"traceutil/trace.go:171","msg":"trace[427027720] transaction","detail":"{read_only:false; response_revision:25878; number_of_response:1; }","duration":"319.833982ms","start":"2026-07-09T00:23:33.904522Z","end":"2026-07-09T00:23:34.224356Z","steps":["trace[427027720] 'process raft request' (duration: 319.651078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:23:34.224534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:23:33.904487Z","time spent":"319.961025ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:23:34.224733Z","caller":"traceutil/trace.go:171","msg":"trace[57685418] linearizableReadLoop","detail":"{readStateIndex:26728; appliedIndex:26725; }","duration":"302.693096ms","start":"2026-07-09T00:23:33.922011Z","end":"2026-07-09T00:23:34.224704Z","steps":["trace[57685418] 'read index received' (duration: 54.357082ms)","trace[57685418] 'applied index is now lower than readState.Index' (duration: 248.334784ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:23:34.224848Z","caller":"traceutil/trace.go:171","msg":"trace[627942486] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"312.488245ms","start":"2026-07-09T00:23:33.91235Z","end":"2026-07-09T00:23:34.224838Z","steps":["trace[627942486] 'process raft request' (duration: 311.955791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:23:34.224964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.951123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-09T00:23:34.225003Z","caller":"traceutil/trace.go:171","msg":"trace[161517289] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25879; }","duration":"303.016434ms","start":"2026-07-09T00:23:33.921975Z","end":"2026-07-09T00:23:34.224991Z","steps":["trace[161517289] 'agreement among raft nodes before linearized reading' (duration: 302.911922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:23:34.225027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:23:33.921959Z","time spent":"303.062386ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-09T00:23:34.225405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:23:33.912331Z","time spent":"312.638738ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:23:34.34907Z","caller":"traceutil/trace.go:171","msg":"trace[1865597763] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"251.792757ms","start":"2026-07-09T00:23:34.09725Z","end":"2026-07-09T00:23:34.349042Z","steps":["trace[1865597763] 'process raft request' (duration: 251.733926ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:34.349267Z","caller":"traceutil/trace.go:171","msg":"trace[1551013450] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"252.644238ms","start":"2026-07-09T00:23:34.096502Z","end":"2026-07-09T00:23:34.349146Z","steps":["trace[1551013450] 'process raft request' (duration: 218.756553ms)","trace[1551013450] 'compare' (duration: 33.587387ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:23:34.43534Z","caller":"traceutil/trace.go:171","msg":"trace[2002884501] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"207.556171ms","start":"2026-07-09T00:23:34.227765Z","end":"2026-07-09T00:23:34.435321Z","steps":["trace[2002884501] 'process raft request' (duration: 207.493959ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:34.435375Z","caller":"traceutil/trace.go:171","msg":"trace[1462706601] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"207.749055ms","start":"2026-07-09T00:23:34.227589Z","end":"2026-07-09T00:23:34.435338Z","steps":["trace[1462706601] 'process raft request' (duration: 207.633362ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:34.435713Z","caller":"traceutil/trace.go:171","msg":"trace[105863447] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"206.533556ms","start":"2026-07-09T00:23:34.229169Z","end":"2026-07-09T00:23:34.435702Z","steps":["trace[105863447] 'process raft request' (duration: 206.119336ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:34.435806Z","caller":"traceutil/trace.go:171","msg":"trace[1070674762] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"136.921702ms","start":"2026-07-09T00:23:34.298874Z","end":"2026-07-09T00:23:34.435796Z","steps":["trace[1070674762] 'process raft request' (duration: 136.698707ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:34.435785Z","caller":"traceutil/trace.go:171","msg":"trace[904884538] linearizableReadLoop","detail":"{readStateIndex:26734; appliedIndex:26728; }","duration":"179.289463ms","start":"2026-07-09T00:23:34.256467Z","end":"2026-07-09T00:23:34.435756Z","steps":["trace[904884538] 'read index received' (duration: 58.766251ms)","trace[904884538] 'applied index is now lower than readState.Index' (duration: 120.521382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:23:34.43605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.56382ms","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-07-09T00:23:34.436104Z","caller":"traceutil/trace.go:171","msg":"trace[889865808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25886; }","duration":"179.681422ms","start":"2026-07-09T00:23:34.256409Z","end":"2026-07-09T00:23:34.43609Z","steps":["trace[889865808] 'agreement among raft nodes before linearized reading' (duration: 179.476757ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:34.43624Z","caller":"traceutil/trace.go:171","msg":"trace[611790347] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"208.712568ms","start":"2026-07-09T00:23:34.227511Z","end":"2026-07-09T00:23:34.436224Z","steps":["trace[611790347] 'process raft request' (duration: 207.576771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:23:34.669838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.151964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-09T00:23:34.670239Z","caller":"traceutil/trace.go:171","msg":"trace[42866258] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"350.401938ms","start":"2026-07-09T00:23:34.319759Z","end":"2026-07-09T00:23:34.670161Z","steps":["trace[42866258] 'process raft request' (duration: 206.799333ms)","trace[42866258] 'compare' (duration: 142.784634ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T00:23:34.670388Z","caller":"traceutil/trace.go:171","msg":"trace[834182027] linearizableReadLoop","detail":"{readStateIndex:26736; appliedIndex:26735; }","duration":"234.420155ms","start":"2026-07-09T00:23:34.435865Z","end":"2026-07-09T00:23:34.670285Z","steps":["trace[834182027] 'read index received' (duration: 90.718257ms)","trace[834182027] 'applied index is now lower than readState.Index' (duration: 143.700158ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:23:34.670464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T00:23:34.319726Z","time spent":"350.613563ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T00:23:34.670255Z","caller":"traceutil/trace.go:171","msg":"trace[1735677236] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"228.84913ms","start":"2026-07-09T00:23:34.441391Z","end":"2026-07-09T00:23:34.67024Z","steps":["trace[1735677236] 'process raft request' (duration: 228.746337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T00:23:34.67069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.636263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-09T00:23:34.670754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.450358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-09T00:23:34.670822Z","caller":"traceutil/trace.go:171","msg":"trace[1062019308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25888; }","duration":"290.860928ms","start":"2026-07-09T00:23:34.379937Z","end":"2026-07-09T00:23:34.670798Z","steps":["trace[1062019308] 'agreement among raft nodes before linearized reading' (duration: 290.600152ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:23:34.670845Z","caller":"traceutil/trace.go:171","msg":"trace[1234773899] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25888; }","duration":"237.619663ms","start":"2026-07-09T00:23:34.433203Z","end":"2026-07-09T00:23:34.670823Z","steps":["trace[1234773899] 'agreement among raft nodes before linearized reading' (duration: 237.362387ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:24:01.217499Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000693a.wal"} {"level":"info","ts":"2026-07-09T00:28:26.880288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25828} {"level":"info","ts":"2026-07-09T00:28:27.03273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25828,"took":"148.264315ms","hash":1014144067,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":36569088,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-09T00:28:27.032818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1014144067,"revision":25828,"compact-revision":22419} {"level":"info","ts":"2026-07-09T00:29:16.657069Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-09T00:29:16.662562Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-09T00:29:16.662658Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-09T00:33:26.88646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28706} {"level":"info","ts":"2026-07-09T00:33:27.021577Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28706,"took":"129.661283ms","hash":1248077257,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":31899648,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-09T00:33:27.021671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1248077257,"revision":28706,"compact-revision":25828} {"level":"info","ts":"2026-07-09T00:38:26.897166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30930} {"level":"info","ts":"2026-07-09T00:38:27.029807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30930,"took":"126.170147ms","hash":2460882829,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-09T00:38:27.029894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2460882829,"revision":30930,"compact-revision":28706} {"level":"info","ts":"2026-07-09T00:42:49.318011Z","caller":"traceutil/trace.go:171","msg":"trace[303141067] transaction","detail":"{read_only:false; response_revision:35204; number_of_response:1; }","duration":"113.469278ms","start":"2026-07-09T00:42:49.204504Z","end":"2026-07-09T00:42:49.317974Z","steps":["trace[303141067] 'process raft request' (duration: 113.228302ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:42:49.318369Z","caller":"traceutil/trace.go:171","msg":"trace[936983152] linearizableReadLoop","detail":"{readStateIndex:36310; appliedIndex:36308; }","duration":"113.732515ms","start":"2026-07-09T00:42:49.204602Z","end":"2026-07-09T00:42:49.318334Z","steps":["trace[936983152] 'read index received' (duration: 34.011747ms)","trace[936983152] 'applied index is now lower than readState.Index' (duration: 79.713037ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T00:42:49.318677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.058252ms","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-07-09T00:42:49.318721Z","caller":"traceutil/trace.go:171","msg":"trace[1830932414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:35206; }","duration":"114.180995ms","start":"2026-07-09T00:42:49.20453Z","end":"2026-07-09T00:42:49.318711Z","steps":["trace[1830932414] 'agreement among raft nodes before linearized reading' (duration: 113.9935ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T00:43:26.904478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33189} {"level":"info","ts":"2026-07-09T00:43:27.063649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33189,"took":"153.536112ms","hash":4193259065,"current-db-size-bytes":60268544,"current-db-size":"60 MB","current-db-size-in-use-bytes":32006144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-09T00:43:27.063738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4193259065,"revision":33189,"compact-revision":30930} {"level":"info","ts":"2026-07-09T00:48:26.911523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35485} {"level":"info","ts":"2026-07-09T00:48:27.060107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35485,"took":"143.678073ms","hash":1463634663,"current-db-size-bytes":61394944,"current-db-size":"61 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-09T00:48:27.060324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1463634663,"revision":35485,"compact-revision":33189} {"level":"info","ts":"2026-07-09T00:50:43.168567Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-09T00:50:43.173522Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-09T00:50:43.173632Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-09T00:53:26.918458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37716} {"level":"info","ts":"2026-07-09T00:53:27.045777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37716,"took":"121.579035ms","hash":2271586867,"current-db-size-bytes":61394944,"current-db-size":"61 MB","current-db-size-in-use-bytes":32620544,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-09T00:53:27.045868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2271586867,"revision":37716,"compact-revision":35485}