{"level":"warn","ts":"2026-10-09T15:29:27.912097Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T15:29:27.9122Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84: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-10-09T15:29:27.912275Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T15:29:27.91229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-10-09T15:29:27.91231Z","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-10-09T15:29:27.913047Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-10-09T15:29:27.913138Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84: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-10-09T15:29:27.917345Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.96604ms"} {"level":"info","ts":"2026-10-09T15:29:27.934712Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-10-09T15:29:27.934806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T15:29:27.93483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-10-09T15:29:27.934859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T15:29:27.934872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-10-09T15:29:27.934924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-10-09T15:29:27.94751Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T15:29:27.949667Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T15:29:27.951315Z","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-10-09T15:29:27.954302Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T15:29:27.954681Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T15:29:27.95487Z","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-10-09T15:29:27.954928Z","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-10-09T15:29:27.954941Z","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-10-09T15:29:27.955469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-10-09T15:29:27.955687Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-10-09T15:29:27.959996Z","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-10-09T15:29:27.960157Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-10-09T15:29:27.960236Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-10-09T15:29:27.960336Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T15:29:27.960404Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T15:29:28.336399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T15:29:28.336449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T15:29:28.336475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-10-09T15:29:28.336488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-10-09T15:29:28.336495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-10-09T15:29:28.336505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-10-09T15:29:28.336514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-10-09T15:29:28.337549Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T15:29:28.338477Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T15:29:28.338485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T15:29:28.338601Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T15:29:28.338911Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T15:29:28.338977Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T15:29:28.339163Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T15:29:28.339288Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T15:29:28.339348Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T15:29:28.341314Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-10-09T15:29:28.341867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T15:29:30.14755Z","caller":"traceutil/trace.go:171","msg":"trace[1775598918] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"127.758344ms","start":"2026-10-09T15:29:30.019738Z","end":"2026-10-09T15:29:30.147496Z","steps":["trace[1775598918] 'process raft request' (duration: 109.848127ms)","trace[1775598918] 'compare' (duration: 17.764363ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:29:30.148214Z","caller":"traceutil/trace.go:171","msg":"trace[947884783] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"128.426202ms","start":"2026-10-09T15:29:30.019774Z","end":"2026-10-09T15:29:30.1482Z","steps":["trace[947884783] 'process raft request' (duration: 128.151825ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:29:30.148331Z","caller":"traceutil/trace.go:171","msg":"trace[679851873] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"128.484993ms","start":"2026-10-09T15:29:30.019821Z","end":"2026-10-09T15:29:30.148306Z","steps":["trace[679851873] 'process raft request' (duration: 128.263997ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:29:30.148368Z","caller":"traceutil/trace.go:171","msg":"trace[1191711257] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"128.445032ms","start":"2026-10-09T15:29:30.019899Z","end":"2026-10-09T15:29:30.148344Z","steps":["trace[1191711257] 'process raft request' (duration: 128.257307ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:29:30.148607Z","caller":"traceutil/trace.go:171","msg":"trace[305721926] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"128.286108ms","start":"2026-10-09T15:29:30.020234Z","end":"2026-10-09T15:29:30.14852Z","steps":["trace[305721926] 'process raft request' (duration: 127.975509ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:29:30.148908Z","caller":"traceutil/trace.go:171","msg":"trace[1601139396] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"128.326689ms","start":"2026-10-09T15:29:30.020568Z","end":"2026-10-09T15:29:30.148895Z","steps":["trace[1601139396] 'process raft request' (duration: 127.783384ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:29:30.148939Z","caller":"traceutil/trace.go:171","msg":"trace[1830336] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"115.217765ms","start":"2026-10-09T15:29:30.033708Z","end":"2026-10-09T15:29:30.148926Z","steps":["trace[1830336] 'process raft request' (duration: 114.910687ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:29:30.14911Z","caller":"traceutil/trace.go:171","msg":"trace[60139671] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:6; }","duration":"129.133462ms","start":"2026-10-09T15:29:30.019808Z","end":"2026-10-09T15:29:30.148942Z","steps":["trace[60139671] 'read index received' (duration: 109.899958ms)","trace[60139671] 'applied index is now lower than readState.Index' (duration: 19.222673ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:29:30.14922Z","caller":"traceutil/trace.go:171","msg":"trace[1342957828] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"110.565147ms","start":"2026-10-09T15:29:30.038639Z","end":"2026-10-09T15:29:30.149204Z","steps":["trace[1342957828] 'process raft request' (duration: 110.208007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:29:30.149348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.512252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-09T15:29:30.149416Z","caller":"traceutil/trace.go:171","msg":"trace[537402840] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"129.635196ms","start":"2026-10-09T15:29:30.019768Z","end":"2026-10-09T15:29:30.149403Z","steps":["trace[537402840] 'agreement among raft nodes before linearized reading' (duration: 129.44265ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:30:14.045195Z","caller":"traceutil/trace.go:171","msg":"trace[2113466063] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"165.33878ms","start":"2026-10-09T15:30:13.879833Z","end":"2026-10-09T15:30:14.045171Z","steps":["trace[2113466063] 'process raft request' (duration: 165.196966ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:30:14.05043Z","caller":"traceutil/trace.go:171","msg":"trace[1921028224] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"103.52561ms","start":"2026-10-09T15:30:13.946863Z","end":"2026-10-09T15:30:14.050389Z","steps":["trace[1921028224] 'process raft request' (duration: 103.327135ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:30:20.474938Z","caller":"traceutil/trace.go:171","msg":"trace[2040675659] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"214.007853ms","start":"2026-10-09T15:30:20.260896Z","end":"2026-10-09T15:30:20.474904Z","steps":["trace[2040675659] 'process raft request' (duration: 185.345595ms)","trace[2040675659] 'compare' (duration: 28.532854ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T15:30:20.688942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.664056ms","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-10-09T15:30:20.689028Z","caller":"traceutil/trace.go:171","msg":"trace[1182422244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"102.759169ms","start":"2026-10-09T15:30:20.58625Z","end":"2026-10-09T15:30:20.689009Z","steps":["trace[1182422244] 'range keys from in-memory index tree' (duration: 102.504982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:30:21.149847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.473373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6d2m6\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-10-09T15:30:21.149929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.510281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-09T15:30:21.149958Z","caller":"traceutil/trace.go:171","msg":"trace[1696963545] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6d2m6; range_end:; response_count:1; response_revision:681; }","duration":"124.646928ms","start":"2026-10-09T15:30:21.025287Z","end":"2026-10-09T15:30:21.149934Z","steps":["trace[1696963545] 'range keys from in-memory index tree' (duration: 124.237846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:30:21.149848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.990264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:30:21.150012Z","caller":"traceutil/trace.go:171","msg":"trace[1118494597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"162.650095ms","start":"2026-10-09T15:30:20.987343Z","end":"2026-10-09T15:30:21.149993Z","steps":["trace[1118494597] 'range keys from in-memory index tree' (duration: 162.283255ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:30:21.150116Z","caller":"traceutil/trace.go:171","msg":"trace[2033112438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"324.257942ms","start":"2026-10-09T15:30:20.825775Z","end":"2026-10-09T15:30:21.150033Z","steps":["trace[2033112438] 'range keys from in-memory index tree' (duration: 323.907861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:30:21.150181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:30:20.825761Z","time spent":"324.405905ms","remote":"127.0.0.1:52472","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-10-09T15:30:36.962996Z","caller":"traceutil/trace.go:171","msg":"trace[539460959] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"162.924873ms","start":"2026-10-09T15:30:36.800048Z","end":"2026-10-09T15:30:36.962973Z","steps":["trace[539460959] 'read index received' (duration: 162.715638ms)","trace[539460959] 'applied index is now lower than readState.Index' (duration: 208.095µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:30:36.96304Z","caller":"traceutil/trace.go:171","msg":"trace[1173881549] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"163.224672ms","start":"2026-10-09T15:30:36.799791Z","end":"2026-10-09T15:30:36.963015Z","steps":["trace[1173881549] 'process raft request' (duration: 163.028046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:30:36.963222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.418109ms","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:5"} {"level":"warn","ts":"2026-10-09T15:30:36.963351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.270013ms","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-10-09T15:30:36.963443Z","caller":"traceutil/trace.go:171","msg":"trace[92954263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"163.405067ms","start":"2026-10-09T15:30:36.800019Z","end":"2026-10-09T15:30:36.963424Z","steps":["trace[92954263] 'agreement among raft nodes before linearized reading' (duration: 163.099589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:30:36.963469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.595375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:30:36.963588Z","caller":"traceutil/trace.go:171","msg":"trace[1407769163] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:799; }","duration":"157.722108ms","start":"2026-10-09T15:30:36.805856Z","end":"2026-10-09T15:30:36.963578Z","steps":["trace[1407769163] 'agreement among raft nodes before linearized reading' (duration: 157.547964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:30:36.963497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.804604ms","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-10-09T15:30:36.963724Z","caller":"traceutil/trace.go:171","msg":"trace[726327697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"129.034691ms","start":"2026-10-09T15:30:36.834662Z","end":"2026-10-09T15:30:36.963697Z","steps":["trace[726327697] 'agreement among raft nodes before linearized reading' (duration: 128.738572ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:30:36.963369Z","caller":"traceutil/trace.go:171","msg":"trace[1367614066] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:799; }","duration":"157.505522ms","start":"2026-10-09T15:30:36.805747Z","end":"2026-10-09T15:30:36.963253Z","steps":["trace[1367614066] 'agreement among raft nodes before linearized reading' (duration: 157.41158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:30:36.963375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.05533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:30:36.96391Z","caller":"traceutil/trace.go:171","msg":"trace[1733140140] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:799; }","duration":"157.613136ms","start":"2026-10-09T15:30:36.806266Z","end":"2026-10-09T15:30:36.963879Z","steps":["trace[1733140140] 'agreement among raft nodes before linearized reading' (duration: 157.0576ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:31:50.783718Z","caller":"traceutil/trace.go:171","msg":"trace[1713011028] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"102.115141ms","start":"2026-10-09T15:31:50.681578Z","end":"2026-10-09T15:31:50.783693Z","steps":["trace[1713011028] 'process raft request' (duration: 101.884134ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:32:03.040402Z","caller":"traceutil/trace.go:171","msg":"trace[573689309] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"134.767529ms","start":"2026-10-09T15:32:02.905611Z","end":"2026-10-09T15:32:03.040379Z","steps":["trace[573689309] 'process raft request' (duration: 134.722337ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:32:03.040509Z","caller":"traceutil/trace.go:171","msg":"trace[273481038] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"193.099745ms","start":"2026-10-09T15:32:02.847375Z","end":"2026-10-09T15:32:03.040475Z","steps":["trace[273481038] 'process raft request' (duration: 149.691292ms)","trace[273481038] 'compare' (duration: 43.168057ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:32:03.040581Z","caller":"traceutil/trace.go:171","msg":"trace[2022243522] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2044; }","duration":"148.235361ms","start":"2026-10-09T15:32:02.8923Z","end":"2026-10-09T15:32:03.040536Z","steps":["trace[2022243522] 'read index received' (duration: 104.740756ms)","trace[2022243522] 'applied index is now lower than readState.Index' (duration: 43.490475ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T15:32:03.040694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.382345ms","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-10-09T15:32:03.040725Z","caller":"traceutil/trace.go:171","msg":"trace[775426475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1966; }","duration":"148.449247ms","start":"2026-10-09T15:32:02.892269Z","end":"2026-10-09T15:32:03.040718Z","steps":["trace[775426475] 'agreement among raft nodes before linearized reading' (duration: 148.365205ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:32:39.660867Z","caller":"traceutil/trace.go:171","msg":"trace[1948780507] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"130.69503ms","start":"2026-10-09T15:32:39.530151Z","end":"2026-10-09T15:32:39.660846Z","steps":["trace[1948780507] 'process raft request' (duration: 130.564147ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:32:45.316012Z","caller":"traceutil/trace.go:171","msg":"trace[1574952869] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"166.73775ms","start":"2026-10-09T15:32:45.149246Z","end":"2026-10-09T15:32:45.315984Z","steps":["trace[1574952869] 'process raft request' (duration: 166.502114ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:32:45.594899Z","caller":"traceutil/trace.go:171","msg":"trace[650672673] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"349.724851ms","start":"2026-10-09T15:32:45.245151Z","end":"2026-10-09T15:32:45.594876Z","steps":["trace[650672673] 'process raft request' (duration: 349.566616ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:32:45.595052Z","caller":"traceutil/trace.go:171","msg":"trace[2077405253] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2294; }","duration":"336.468375ms","start":"2026-10-09T15:32:45.258558Z","end":"2026-10-09T15:32:45.595026Z","steps":["trace[2077405253] 'read index received' (duration: 57.190489ms)","trace[2077405253] 'applied index is now lower than readState.Index' (duration: 279.276346ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:32:45.595087Z","caller":"traceutil/trace.go:171","msg":"trace[1692567708] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"319.036342ms","start":"2026-10-09T15:32:45.276026Z","end":"2026-10-09T15:32:45.595062Z","steps":["trace[1692567708] 'process raft request' (duration: 318.816106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:32:45.595291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.721213ms","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-10-09T15:32:45.595336Z","caller":"traceutil/trace.go:171","msg":"trace[1306043014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2208; }","duration":"336.807995ms","start":"2026-10-09T15:32:45.258517Z","end":"2026-10-09T15:32:45.595325Z","steps":["trace[1306043014] 'agreement among raft nodes before linearized reading' (duration: 336.643021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:32:45.595366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:32:45.258505Z","time spent":"336.853776ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T15:32:45.595902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:32:45.245125Z","time spent":"349.843504ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T15:32:45.596081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:32:45.276013Z","time spent":"319.193276ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T15:32:45.619104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.831391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:32:45.619187Z","caller":"traceutil/trace.go:171","msg":"trace[1003747344] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2209; }","duration":"250.959315ms","start":"2026-10-09T15:32:45.368213Z","end":"2026-10-09T15:32:45.619172Z","steps":["trace[1003747344] 'agreement among raft nodes before linearized reading' (duration: 250.815251ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:32:45.619096Z","caller":"traceutil/trace.go:171","msg":"trace[1630408734] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"199.427786ms","start":"2026-10-09T15:32:45.419642Z","end":"2026-10-09T15:32:45.61907Z","steps":["trace[1630408734] 'process raft request' (duration: 199.301052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:37:02.134401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.067078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T15:37:02.134526Z","caller":"traceutil/trace.go:171","msg":"trace[1753125788] transaction","detail":"{read_only:false; response_revision:3973; number_of_response:1; }","duration":"269.338179ms","start":"2026-10-09T15:37:01.865168Z","end":"2026-10-09T15:37:02.134506Z","steps":["trace[1753125788] 'process raft request' (duration: 134.898161ms)","trace[1753125788] 'compare' (duration: 133.863852ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:38:30.900476Z","caller":"traceutil/trace.go:171","msg":"trace[670774848] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"134.842802ms","start":"2026-10-09T15:38:30.765564Z","end":"2026-10-09T15:38:30.900407Z","steps":["trace[670774848] 'process raft request' (duration: 48.957446ms)","trace[670774848] 'compare' (duration: 84.840817ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:38:30.902613Z","caller":"traceutil/trace.go:171","msg":"trace[667266398] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"136.621801ms","start":"2026-10-09T15:38:30.765945Z","end":"2026-10-09T15:38:30.902567Z","steps":["trace[667266398] 'process raft request' (duration: 134.257935ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:38:30.902906Z","caller":"traceutil/trace.go:171","msg":"trace[1934156023] linearizableReadLoop","detail":"{readStateIndex:5211; appliedIndex:5208; }","duration":"136.763325ms","start":"2026-10-09T15:38:30.766122Z","end":"2026-10-09T15:38:30.902885Z","steps":["trace[1934156023] 'read index received' (duration: 8.317732ms)","trace[1934156023] 'applied index is now lower than readState.Index' (duration: 128.444333ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:38:30.902995Z","caller":"traceutil/trace.go:171","msg":"trace[20385516] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"136.337533ms","start":"2026-10-09T15:38:30.766645Z","end":"2026-10-09T15:38:30.902982Z","steps":["trace[20385516] 'process raft request' (duration: 133.620937ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:38:30.903248Z","caller":"traceutil/trace.go:171","msg":"trace[1384116189] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"136.437526ms","start":"2026-10-09T15:38:30.766794Z","end":"2026-10-09T15:38:30.903231Z","steps":["trace[1384116189] 'process raft request' (duration: 135.989753ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:38:30.903395Z","caller":"traceutil/trace.go:171","msg":"trace[136577224] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"135.732496ms","start":"2026-10-09T15:38:30.767652Z","end":"2026-10-09T15:38:30.903385Z","steps":["trace[136577224] 'process raft request' (duration: 135.183741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:30.903657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.514556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-10-09T15:38:30.903728Z","caller":"traceutil/trace.go:171","msg":"trace[1607832456] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5022; }","duration":"137.623889ms","start":"2026-10-09T15:38:30.766093Z","end":"2026-10-09T15:38:30.903717Z","steps":["trace[1607832456] 'agreement among raft nodes before linearized reading' (duration: 137.409503ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:38:30.935402Z","caller":"traceutil/trace.go:171","msg":"trace[449656683] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"160.671012ms","start":"2026-10-09T15:38:30.774688Z","end":"2026-10-09T15:38:30.935359Z","steps":["trace[449656683] 'process raft request' (duration: 160.424325ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:38:30.972638Z","caller":"traceutil/trace.go:171","msg":"trace[414192452] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"153.982165ms","start":"2026-10-09T15:38:30.81863Z","end":"2026-10-09T15:38:30.972613Z","steps":["trace[414192452] 'process raft request' (duration: 153.785849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:30.973819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.647923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:38:30.973967Z","caller":"traceutil/trace.go:171","msg":"trace[1138728910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5026; }","duration":"145.853138ms","start":"2026-10-09T15:38:30.828059Z","end":"2026-10-09T15:38:30.973912Z","steps":["trace[1138728910] 'agreement among raft nodes before linearized reading' (duration: 144.875321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:30.97452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.508911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T15:38:30.974678Z","caller":"traceutil/trace.go:171","msg":"trace[1898576] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5026; }","duration":"106.724797ms","start":"2026-10-09T15:38:30.867892Z","end":"2026-10-09T15:38:30.974617Z","steps":["trace[1898576] 'agreement among raft nodes before linearized reading' (duration: 105.175924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:31.036286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.094415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-10-09T15:38:31.036386Z","caller":"traceutil/trace.go:171","msg":"trace[1983397950] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5030; }","duration":"127.222509ms","start":"2026-10-09T15:38:30.909139Z","end":"2026-10-09T15:38:31.036361Z","steps":["trace[1983397950] 'agreement among raft nodes before linearized reading' (duration: 126.954522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:36.162501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.455505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:38:36.162585Z","caller":"traceutil/trace.go:171","msg":"trace[1549426546] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5183; }","duration":"132.603869ms","start":"2026-10-09T15:38:36.029963Z","end":"2026-10-09T15:38:36.162567Z","steps":["trace[1549426546] 'range keys from in-memory index tree' (duration: 132.318291ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:38:51.858497Z","caller":"traceutil/trace.go:171","msg":"trace[1478746811] linearizableReadLoop","detail":"{readStateIndex:5660; appliedIndex:5659; }","duration":"229.439681ms","start":"2026-10-09T15:38:51.629032Z","end":"2026-10-09T15:38:51.858472Z","steps":["trace[1478746811] 'read index received' (duration: 229.308907ms)","trace[1478746811] 'applied index is now lower than readState.Index' (duration: 129.424µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:38:51.858576Z","caller":"traceutil/trace.go:171","msg":"trace[389346774] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"345.401035ms","start":"2026-10-09T15:38:51.513125Z","end":"2026-10-09T15:38:51.858526Z","steps":["trace[389346774] 'process raft request' (duration: 345.20507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:51.85871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.805434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T15:38:51.858754Z","caller":"traceutil/trace.go:171","msg":"trace[671912408] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5454; }","duration":"213.923527ms","start":"2026-10-09T15:38:51.644818Z","end":"2026-10-09T15:38:51.858742Z","steps":["trace[671912408] 'agreement among raft nodes before linearized reading' (duration: 213.808744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:51.858771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.725958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-09T15:38:51.85884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:38:51.51309Z","time spent":"345.609261ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T15:38:51.858859Z","caller":"traceutil/trace.go:171","msg":"trace[1322650360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5454; }","duration":"229.869012ms","start":"2026-10-09T15:38:51.62897Z","end":"2026-10-09T15:38:51.858839Z","steps":["trace[1322650360] 'agreement among raft nodes before linearized reading' (duration: 229.652846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:51.858943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.145636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-09T15:38:51.858961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.113193ms","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-10-09T15:38:51.858984Z","caller":"traceutil/trace.go:171","msg":"trace[1367631931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5454; }","duration":"161.210158ms","start":"2026-10-09T15:38:51.697759Z","end":"2026-10-09T15:38:51.858969Z","steps":["trace[1367631931] 'agreement among raft nodes before linearized reading' (duration: 161.059624ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:38:51.85903Z","caller":"traceutil/trace.go:171","msg":"trace[8769332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5454; }","duration":"157.206096ms","start":"2026-10-09T15:38:51.701806Z","end":"2026-10-09T15:38:51.859013Z","steps":["trace[8769332] 'agreement among raft nodes before linearized reading' (duration: 157.037721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:51.859035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.403762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-09T15:38:51.859202Z","caller":"traceutil/trace.go:171","msg":"trace[1973457659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5454; }","duration":"204.616728ms","start":"2026-10-09T15:38:51.654562Z","end":"2026-10-09T15:38:51.859179Z","steps":["trace[1973457659] 'agreement among raft nodes before linearized reading' (duration: 204.266548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:38:59.269648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.074088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T15:38:59.269916Z","caller":"traceutil/trace.go:171","msg":"trace[1861271784] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"106.496781ms","start":"2026-10-09T15:38:59.163395Z","end":"2026-10-09T15:38:59.269892Z","steps":["trace[1861271784] 'process raft request' (duration: 106.395308ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:38:59.269948Z","caller":"traceutil/trace.go:171","msg":"trace[2108807366] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"166.039942ms","start":"2026-10-09T15:38:59.103876Z","end":"2026-10-09T15:38:59.269916Z","steps":["trace[2108807366] 'process raft request' (duration: 63.598374ms)","trace[2108807366] 'compare' (duration: 101.970255ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:39:28.36991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-10-09T15:39:28.457146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"85.740832ms","hash":3649164533,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":32538624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T15:39:28.457226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3649164533,"revision":2969,"compact-revision":-1} {"level":"info","ts":"2026-10-09T15:44:28.380242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5902} {"level":"info","ts":"2026-10-09T15:44:28.487681Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5902,"took":"105.350418ms","hash":4185052815,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":25501696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T15:44:28.487792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4185052815,"revision":5902,"compact-revision":2969} {"level":"info","ts":"2026-10-09T15:45:24.303357Z","caller":"traceutil/trace.go:171","msg":"trace[1580467101] linearizableReadLoop","detail":"{readStateIndex:8850; appliedIndex:8849; }","duration":"161.392666ms","start":"2026-10-09T15:45:24.141934Z","end":"2026-10-09T15:45:24.303326Z","steps":["trace[1580467101] 'read index received' (duration: 161.240482ms)","trace[1580467101] 'applied index is now lower than readState.Index' (duration: 150.974µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:45:24.303407Z","caller":"traceutil/trace.go:171","msg":"trace[1890460073] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"282.538162ms","start":"2026-10-09T15:45:24.020834Z","end":"2026-10-09T15:45:24.303372Z","steps":["trace[1890460073] 'process raft request' (duration: 282.289426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:45:24.303743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.747616ms","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-10-09T15:45:24.303883Z","caller":"traceutil/trace.go:171","msg":"trace[1447258307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8546; }","duration":"161.933021ms","start":"2026-10-09T15:45:24.14192Z","end":"2026-10-09T15:45:24.303853Z","steps":["trace[1447258307] 'agreement among raft nodes before linearized reading' (duration: 161.606032ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:45:24.462151Z","caller":"traceutil/trace.go:171","msg":"trace[462906775] linearizableReadLoop","detail":"{readStateIndex:8851; appliedIndex:8850; }","duration":"158.631649ms","start":"2026-10-09T15:45:24.303492Z","end":"2026-10-09T15:45:24.462123Z","steps":["trace[462906775] 'read index received' (duration: 158.484515ms)","trace[462906775] 'applied index is now lower than readState.Index' (duration: 144.914µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T15:45:24.462299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.18137ms","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-10-09T15:45:24.462352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.86572ms","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-10-09T15:45:24.46233Z","caller":"traceutil/trace.go:171","msg":"trace[1450907461] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"162.090135ms","start":"2026-10-09T15:45:24.300197Z","end":"2026-10-09T15:45:24.462287Z","steps":["trace[1450907461] 'process raft request' (duration: 161.690844ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:45:24.462392Z","caller":"traceutil/trace.go:171","msg":"trace[1529299803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8547; }","duration":"310.932262ms","start":"2026-10-09T15:45:24.151449Z","end":"2026-10-09T15:45:24.462381Z","steps":["trace[1529299803] 'agreement among raft nodes before linearized reading' (duration: 310.815589ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:45:24.462381Z","caller":"traceutil/trace.go:171","msg":"trace[539160445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8547; }","duration":"297.302473ms","start":"2026-10-09T15:45:24.165061Z","end":"2026-10-09T15:45:24.462363Z","steps":["trace[539160445] 'agreement among raft nodes before linearized reading' (duration: 297.135849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:45:24.462292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.751575ms","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-10-09T15:45:24.462686Z","caller":"traceutil/trace.go:171","msg":"trace[1346739733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8547; }","duration":"294.174887ms","start":"2026-10-09T15:45:24.16849Z","end":"2026-10-09T15:45:24.462665Z","steps":["trace[1346739733] 'agreement among raft nodes before linearized reading' (duration: 293.641042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:45:24.462466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:45:24.151381Z","time spent":"311.076476ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T15:45:24.463625Z","caller":"traceutil/trace.go:171","msg":"trace[1399855159] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"155.760589ms","start":"2026-10-09T15:45:24.30784Z","end":"2026-10-09T15:45:24.463601Z","steps":["trace[1399855159] 'process raft request' (duration: 155.514982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:45:24.463628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.698609ms","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-10-09T15:45:24.463764Z","caller":"traceutil/trace.go:171","msg":"trace[1690251861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8548; }","duration":"154.842934ms","start":"2026-10-09T15:45:24.308862Z","end":"2026-10-09T15:45:24.463705Z","steps":["trace[1690251861] 'agreement among raft nodes before linearized reading' (duration: 154.648778ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:45:32.277003Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022c2.wal"} {"level":"warn","ts":"2026-10-09T15:45:38.815627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.92173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T15:45:38.815914Z","caller":"traceutil/trace.go:171","msg":"trace[638895544] transaction","detail":"{read_only:false; response_revision:8626; number_of_response:1; }","duration":"258.714251ms","start":"2026-10-09T15:45:38.557178Z","end":"2026-10-09T15:45:38.815892Z","steps":["trace[638895544] 'process raft request' (duration: 258.670179ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:45:38.816176Z","caller":"traceutil/trace.go:171","msg":"trace[1263205476] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"259.597675ms","start":"2026-10-09T15:45:38.556566Z","end":"2026-10-09T15:45:38.816164Z","steps":["trace[1263205476] 'process raft request' (duration: 259.248946ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:45:38.816391Z","caller":"traceutil/trace.go:171","msg":"trace[392468445] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"260.019207ms","start":"2026-10-09T15:45:38.556173Z","end":"2026-10-09T15:45:38.816192Z","steps":["trace[392468445] 'process raft request' (duration: 142.427999ms)","trace[392468445] 'compare' (duration: 116.792136ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:45:38.816126Z","caller":"traceutil/trace.go:171","msg":"trace[1785946689] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"259.840022ms","start":"2026-10-09T15:45:38.556233Z","end":"2026-10-09T15:45:38.816073Z","steps":["trace[1785946689] 'process raft request' (duration: 259.528683ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:45:39.017386Z","caller":"traceutil/trace.go:171","msg":"trace[1655280226] linearizableReadLoop","detail":"{readStateIndex:8936; appliedIndex:8933; }","duration":"189.678382ms","start":"2026-10-09T15:45:38.827678Z","end":"2026-10-09T15:45:39.017356Z","steps":["trace[1655280226] 'read index received' (duration: 72.175386ms)","trace[1655280226] 'applied index is now lower than readState.Index' (duration: 117.501686ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:45:39.017434Z","caller":"traceutil/trace.go:171","msg":"trace[666734712] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"205.882062ms","start":"2026-10-09T15:45:38.811499Z","end":"2026-10-09T15:45:39.017381Z","steps":["trace[666734712] 'process raft request' (duration: 135.592198ms)","trace[666734712] 'compare' (duration: 70.063548ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:45:39.017588Z","caller":"traceutil/trace.go:171","msg":"trace[1610786199] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"206.030786ms","start":"2026-10-09T15:45:38.811509Z","end":"2026-10-09T15:45:39.01754Z","steps":["trace[1610786199] 'process raft request' (duration: 205.79512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:45:39.017661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.95428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:45:39.017719Z","caller":"traceutil/trace.go:171","msg":"trace[1610938352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8629; }","duration":"190.064323ms","start":"2026-10-09T15:45:38.827637Z","end":"2026-10-09T15:45:39.017701Z","steps":["trace[1610938352] 'agreement among raft nodes before linearized reading' (duration: 189.886408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:45:39.320656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.670449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-09T15:45:39.320838Z","caller":"traceutil/trace.go:171","msg":"trace[548117347] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8629; }","duration":"195.907515ms","start":"2026-10-09T15:45:39.1249Z","end":"2026-10-09T15:45:39.320808Z","steps":["trace[548117347] 'range keys from in-memory index tree' (duration: 195.392351ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:46:11.680572Z","caller":"traceutil/trace.go:171","msg":"trace[731478323] transaction","detail":"{read_only:false; response_revision:8856; number_of_response:1; }","duration":"116.052415ms","start":"2026-10-09T15:46:11.564491Z","end":"2026-10-09T15:46:11.680543Z","steps":["trace[731478323] 'process raft request' (duration: 115.899191ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:46:35.367596Z","caller":"traceutil/trace.go:171","msg":"trace[160466323] transaction","detail":"{read_only:false; response_revision:9063; number_of_response:1; }","duration":"111.425353ms","start":"2026-10-09T15:46:35.256138Z","end":"2026-10-09T15:46:35.367563Z","steps":["trace[160466323] 'process raft request' (duration: 111.199916ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:46:35.3683Z","caller":"traceutil/trace.go:171","msg":"trace[991576682] transaction","detail":"{read_only:false; response_revision:9064; number_of_response:1; }","duration":"110.58941ms","start":"2026-10-09T15:46:35.257679Z","end":"2026-10-09T15:46:35.368268Z","steps":["trace[991576682] 'process raft request' (duration: 110.466507ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:47:32.860006Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T15:47:32.867004Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T15:47:32.867131Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-09T15:47:51.289774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.206422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T15:47:51.289876Z","caller":"traceutil/trace.go:171","msg":"trace[1026654981] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:9766; }","duration":"122.410248ms","start":"2026-10-09T15:47:51.167443Z","end":"2026-10-09T15:47:51.289853Z","steps":["trace[1026654981] 'count revisions from in-memory index tree' (duration: 122.018976ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:49:28.387467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8053} {"level":"info","ts":"2026-10-09T15:49:28.458559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8053,"took":"68.942772ms","hash":2568214663,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":27459584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T15:49:28.458641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2568214663,"revision":8053,"compact-revision":5902} {"level":"warn","ts":"2026-10-09T15:53:29.686156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.040271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-09T15:53:29.686244Z","caller":"traceutil/trace.go:171","msg":"trace[1980065489] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12198; }","duration":"155.219826ms","start":"2026-10-09T15:53:29.531006Z","end":"2026-10-09T15:53:29.686226Z","steps":["trace[1980065489] 'range keys from in-memory index tree' (duration: 154.678581ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:54:28.395151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10445} {"level":"info","ts":"2026-10-09T15:54:28.485956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10445,"took":"87.365748ms","hash":581887499,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":26042368,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T15:54:28.486028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":581887499,"revision":10445,"compact-revision":8053} {"level":"info","ts":"2026-10-09T15:59:28.403652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12614} {"level":"info","ts":"2026-10-09T15:59:28.488695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12614,"took":"82.584645ms","hash":1702336281,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":28000256,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T15:59:28.488784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1702336281,"revision":12614,"compact-revision":10445} {"level":"info","ts":"2026-10-09T15:59:29.926658Z","caller":"traceutil/trace.go:171","msg":"trace[838514819] transaction","detail":"{read_only:false; response_revision:14640; number_of_response:1; }","duration":"101.389346ms","start":"2026-10-09T15:59:29.825245Z","end":"2026-10-09T15:59:29.926634Z","steps":["trace[838514819] 'process raft request' (duration: 100.865901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:00:08.932955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.333707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T16:00:08.93304Z","caller":"traceutil/trace.go:171","msg":"trace[41525557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14859; }","duration":"104.450241ms","start":"2026-10-09T16:00:08.828571Z","end":"2026-10-09T16:00:08.933021Z","steps":["trace[41525557] 'range keys from in-memory index tree' (duration: 104.276296ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:00:09.613372Z","caller":"traceutil/trace.go:171","msg":"trace[350318521] transaction","detail":"{read_only:false; response_revision:14861; number_of_response:1; }","duration":"159.959857ms","start":"2026-10-09T16:00:09.453389Z","end":"2026-10-09T16:00:09.613348Z","steps":["trace[350318521] 'process raft request' (duration: 159.802063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:00:09.874027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.702775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T16:00:09.874169Z","caller":"traceutil/trace.go:171","msg":"trace[6181579] transaction","detail":"{read_only:false; response_revision:14862; number_of_response:1; }","duration":"256.672684ms","start":"2026-10-09T16:00:09.617467Z","end":"2026-10-09T16:00:09.87414Z","steps":["trace[6181579] 'process raft request' (duration: 98.730412ms)","trace[6181579] 'compare' (duration: 157.602392ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:00:09.876408Z","caller":"traceutil/trace.go:171","msg":"trace[1500558805] transaction","detail":"{read_only:false; response_revision:14863; number_of_response:1; }","duration":"258.381701ms","start":"2026-10-09T16:00:09.617994Z","end":"2026-10-09T16:00:09.876376Z","steps":["trace[1500558805] 'process raft request' (duration: 258.221796ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:00:09.876612Z","caller":"traceutil/trace.go:171","msg":"trace[69589475] transaction","detail":"{read_only:false; response_revision:14865; number_of_response:1; }","duration":"250.300698ms","start":"2026-10-09T16:00:09.626297Z","end":"2026-10-09T16:00:09.876598Z","steps":["trace[69589475] 'process raft request' (duration: 250.02885ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:00:09.876633Z","caller":"traceutil/trace.go:171","msg":"trace[595197658] transaction","detail":"{read_only:false; response_revision:14864; number_of_response:1; }","duration":"258.170725ms","start":"2026-10-09T16:00:09.618412Z","end":"2026-10-09T16:00:09.876583Z","steps":["trace[595197658] 'process raft request' (duration: 257.877197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:01:55.181784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.158011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T16:01:55.181992Z","caller":"traceutil/trace.go:171","msg":"trace[670404169] linearizableReadLoop","detail":"{readStateIndex:16520; appliedIndex:16518; }","duration":"147.229956ms","start":"2026-10-09T16:01:55.03474Z","end":"2026-10-09T16:01:55.18197Z","steps":["trace[670404169] 'read index received' (duration: 32.713076ms)","trace[670404169] 'applied index is now lower than readState.Index' (duration: 114.51545ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:01:55.182291Z","caller":"traceutil/trace.go:171","msg":"trace[1723910478] transaction","detail":"{read_only:false; response_revision:15967; number_of_response:1; }","duration":"251.78832ms","start":"2026-10-09T16:01:54.930484Z","end":"2026-10-09T16:01:55.182272Z","steps":["trace[1723910478] 'process raft request' (duration: 136.998522ms)","trace[1723910478] 'compare' (duration: 114.035467ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:01:55.182506Z","caller":"traceutil/trace.go:171","msg":"trace[449507305] transaction","detail":"{read_only:false; response_revision:15968; number_of_response:1; }","duration":"247.602174ms","start":"2026-10-09T16:01:54.934889Z","end":"2026-10-09T16:01:55.182491Z","steps":["trace[449507305] 'process raft request' (duration: 247.017168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:01:55.182686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.939385ms","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-10-09T16:01:55.182766Z","caller":"traceutil/trace.go:171","msg":"trace[533116459] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15968; }","duration":"148.10443ms","start":"2026-10-09T16:01:55.034645Z","end":"2026-10-09T16:01:55.18275Z","steps":["trace[533116459] 'agreement among raft nodes before linearized reading' (duration: 147.996997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:01:55.182874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.919915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T16:01:55.182966Z","caller":"traceutil/trace.go:171","msg":"trace[1677902721] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15968; }","duration":"148.038288ms","start":"2026-10-09T16:01:55.034905Z","end":"2026-10-09T16:01:55.182943Z","steps":["trace[1677902721] 'agreement among raft nodes before linearized reading' (duration: 147.886894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:01:55.183092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.692415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-10-09T16:01:55.183196Z","caller":"traceutil/trace.go:171","msg":"trace[1942363998] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15968; }","duration":"136.813067ms","start":"2026-10-09T16:01:55.046363Z","end":"2026-10-09T16:01:55.183177Z","steps":["trace[1942363998] 'agreement among raft nodes before linearized reading' (duration: 136.585401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:01:55.183241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.126535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-09T16:01:55.18329Z","caller":"traceutil/trace.go:171","msg":"trace[425515157] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15968; }","duration":"142.196546ms","start":"2026-10-09T16:01:55.041081Z","end":"2026-10-09T16:01:55.183278Z","steps":["trace[425515157] 'agreement among raft nodes before linearized reading' (duration: 142.003071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:01:55.183409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.370061ms","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-10-09T16:01:55.183437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.396398ms","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-10-09T16:01:55.183474Z","caller":"traceutil/trace.go:171","msg":"trace[548360278] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15968; }","duration":"142.461494ms","start":"2026-10-09T16:01:55.040994Z","end":"2026-10-09T16:01:55.183455Z","steps":["trace[548360278] 'agreement among raft nodes before linearized reading' (duration: 142.375702ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:01:55.183494Z","caller":"traceutil/trace.go:171","msg":"trace[143961474] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15968; }","duration":"148.46499ms","start":"2026-10-09T16:01:55.035014Z","end":"2026-10-09T16:01:55.183479Z","steps":["trace[143961474] 'agreement among raft nodes before linearized reading' (duration: 148.383547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:01:55.183665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.444547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-10-09T16:01:55.183775Z","caller":"traceutil/trace.go:171","msg":"trace[1057337025] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15968; }","duration":"136.591881ms","start":"2026-10-09T16:01:55.047164Z","end":"2026-10-09T16:01:55.183756Z","steps":["trace[1057337025] 'agreement among raft nodes before linearized reading' (duration: 136.309834ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:01:56.063941Z","caller":"traceutil/trace.go:171","msg":"trace[202045688] transaction","detail":"{read_only:false; response_revision:15970; number_of_response:1; }","duration":"152.4464ms","start":"2026-10-09T16:01:55.911459Z","end":"2026-10-09T16:01:56.063905Z","steps":["trace[202045688] 'process raft request' (duration: 152.221084ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:04:28.410741Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14634} {"level":"info","ts":"2026-10-09T16:04:28.495218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14634,"took":"81.693701ms","hash":4048416861,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T16:04:28.495291Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4048416861,"revision":14634,"compact-revision":12614} {"level":"warn","ts":"2026-10-09T16:08:07.538127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.126943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-65f5c5678d-7s42c.18dce794a566885b\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-10-09T16:08:07.538231Z","caller":"traceutil/trace.go:171","msg":"trace[537879498] range","detail":"{range_begin:/registry/events/openstack/cinder-api-65f5c5678d-7s42c.18dce794a566885b; range_end:; response_count:1; response_revision:18678; }","duration":"133.271507ms","start":"2026-10-09T16:08:07.404931Z","end":"2026-10-09T16:08:07.538203Z","steps":["trace[537879498] 'range keys from in-memory index tree' (duration: 132.940818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.336745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.218675ms","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-10-09T16:08:14.336835Z","caller":"traceutil/trace.go:171","msg":"trace[1756648977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18733; }","duration":"215.38957ms","start":"2026-10-09T16:08:14.121426Z","end":"2026-10-09T16:08:14.336816Z","steps":["trace[1756648977] 'range keys from in-memory index tree' (duration: 215.03531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.336821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.956696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-74wgc\" ","response":"range_response_count:1 size:22018"} {"level":"warn","ts":"2026-10-09T16:08:14.336875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.206979ms","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-10-09T16:08:14.336908Z","caller":"traceutil/trace.go:171","msg":"trace[2119009674] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-74wgc; range_end:; response_count:1; response_revision:18733; }","duration":"207.09697ms","start":"2026-10-09T16:08:14.129793Z","end":"2026-10-09T16:08:14.33689Z","steps":["trace[2119009674] 'range keys from in-memory index tree' (duration: 206.756111ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:08:14.336917Z","caller":"traceutil/trace.go:171","msg":"trace[682490202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18733; }","duration":"110.294482ms","start":"2026-10-09T16:08:14.226609Z","end":"2026-10-09T16:08:14.336904Z","steps":["trace[682490202] 'range keys from in-memory index tree' (duration: 110.017624ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:08:14.57572Z","caller":"traceutil/trace.go:171","msg":"trace[1768284431] transaction","detail":"{read_only:false; response_revision:18734; number_of_response:1; }","duration":"234.726224ms","start":"2026-10-09T16:08:14.340941Z","end":"2026-10-09T16:08:14.575667Z","steps":["trace[1768284431] 'process raft request' (duration: 234.564459ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:08:14.776119Z","caller":"traceutil/trace.go:171","msg":"trace[807441284] transaction","detail":"{read_only:false; response_revision:18736; number_of_response:1; }","duration":"418.691974ms","start":"2026-10-09T16:08:14.357402Z","end":"2026-10-09T16:08:14.776094Z","steps":["trace[807441284] 'process raft request' (duration: 418.594521ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:08:14.776177Z","caller":"traceutil/trace.go:171","msg":"trace[1224268418] transaction","detail":"{read_only:false; response_revision:18735; number_of_response:1; }","duration":"435.098427ms","start":"2026-10-09T16:08:14.341044Z","end":"2026-10-09T16:08:14.776143Z","steps":["trace[1224268418] 'process raft request' (duration: 434.779179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.77628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:08:14.357373Z","time spent":"418.827958ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20781,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:08:14.776335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:08:14.341011Z","time spent":"435.235561ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:08:14.776556Z","caller":"traceutil/trace.go:171","msg":"trace[355180411] linearizableReadLoop","detail":"{readStateIndex:19378; appliedIndex:19376; }","duration":"433.067301ms","start":"2026-10-09T16:08:14.343399Z","end":"2026-10-09T16:08:14.776466Z","steps":["trace[355180411] 'read index received' (duration: 232.146352ms)","trace[355180411] 'applied index is now lower than readState.Index' (duration: 200.919629ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:08:14.776578Z","caller":"traceutil/trace.go:171","msg":"trace[221038822] transaction","detail":"{read_only:false; response_revision:18737; number_of_response:1; }","duration":"404.46344ms","start":"2026-10-09T16:08:14.372077Z","end":"2026-10-09T16:08:14.776541Z","steps":["trace[221038822] 'process raft request' (duration: 403.993487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.776761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:08:14.372062Z","time spent":"404.585753ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:08:14.777713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.267864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:946946"} {"level":"info","ts":"2026-10-09T16:08:14.777761Z","caller":"traceutil/trace.go:171","msg":"trace[1933127963] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18737; }","duration":"434.356157ms","start":"2026-10-09T16:08:14.343393Z","end":"2026-10-09T16:08:14.77775Z","steps":["trace[1933127963] 'agreement among raft nodes before linearized reading' (duration: 433.260817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.777799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:08:14.34338Z","time spent":"434.405829ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":946970,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-09T16:08:14.846467Z","caller":"traceutil/trace.go:171","msg":"trace[543203242] transaction","detail":"{read_only:false; response_revision:18738; number_of_response:1; }","duration":"135.212031ms","start":"2026-10-09T16:08:14.711206Z","end":"2026-10-09T16:08:14.846418Z","steps":["trace[543203242] 'process raft request' (duration: 134.965224ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:08:14.846552Z","caller":"traceutil/trace.go:171","msg":"trace[478299342] transaction","detail":"{read_only:false; response_revision:18739; number_of_response:1; }","duration":"118.931111ms","start":"2026-10-09T16:08:14.727581Z","end":"2026-10-09T16:08:14.846512Z","steps":["trace[478299342] 'process raft request' (duration: 118.766816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.846593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.497491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T16:08:14.846668Z","caller":"traceutil/trace.go:171","msg":"trace[3560891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18739; }","duration":"207.641345ms","start":"2026-10-09T16:08:14.639006Z","end":"2026-10-09T16:08:14.846648Z","steps":["trace[3560891] 'agreement among raft nodes before linearized reading' (duration: 207.43903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.846843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.890834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:08:14.846904Z","caller":"traceutil/trace.go:171","msg":"trace[218341199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18739; }","duration":"206.956356ms","start":"2026-10-09T16:08:14.63993Z","end":"2026-10-09T16:08:14.846887Z","steps":["trace[218341199] 'agreement among raft nodes before linearized reading' (duration: 206.587466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.846993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.009043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-qjdk6\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-10-09T16:08:14.847064Z","caller":"traceutil/trace.go:171","msg":"trace[1613971343] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-qjdk6; range_end:; response_count:1; response_revision:18739; }","duration":"402.133186ms","start":"2026-10-09T16:08:14.444913Z","end":"2026-10-09T16:08:14.847046Z","steps":["trace[1613971343] 'agreement among raft nodes before linearized reading' (duration: 401.721265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:08:14.847063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.220583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-09T16:08:14.847103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:08:14.4449Z","time spent":"402.190728ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9736,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-qjdk6\" "} {"level":"info","ts":"2026-10-09T16:08:14.847177Z","caller":"traceutil/trace.go:171","msg":"trace[391658695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18739; }","duration":"207.516061ms","start":"2026-10-09T16:08:14.639608Z","end":"2026-10-09T16:08:14.847124Z","steps":["trace[391658695] 'agreement among raft nodes before linearized reading' (duration: 206.841533ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:09:06.251766Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T16:09:06.261186Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T16:09:06.261304Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T16:09:28.417709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17057} {"level":"info","ts":"2026-10-09T16:09:28.517237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17057,"took":"96.177821ms","hash":1956752558,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":33091584,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T16:09:28.517307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1956752558,"revision":17057,"compact-revision":14634} {"level":"info","ts":"2026-10-09T16:14:01.226196Z","caller":"traceutil/trace.go:171","msg":"trace[2052697871] transaction","detail":"{read_only:false; response_revision:21426; number_of_response:1; }","duration":"200.61861ms","start":"2026-10-09T16:14:01.025523Z","end":"2026-10-09T16:14:01.226142Z","steps":["trace[2052697871] 'process raft request' (duration: 138.606705ms)","trace[2052697871] 'compare' (duration: 60.798532ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:14:16.919242Z","caller":"traceutil/trace.go:171","msg":"trace[564518431] transaction","detail":"{read_only:false; response_revision:21506; number_of_response:1; }","duration":"120.677836ms","start":"2026-10-09T16:14:16.79853Z","end":"2026-10-09T16:14:16.919208Z","steps":["trace[564518431] 'process raft request' (duration: 120.414968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:14:17.210879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.576216ms","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-10-09T16:14:17.21099Z","caller":"traceutil/trace.go:171","msg":"trace[1287327763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21506; }","duration":"110.738471ms","start":"2026-10-09T16:14:17.100224Z","end":"2026-10-09T16:14:17.210963Z","steps":["trace[1287327763] 'range keys from in-memory index tree' (duration: 110.369981ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:14:17.377281Z","caller":"traceutil/trace.go:171","msg":"trace[933173987] transaction","detail":"{read_only:false; response_revision:21508; number_of_response:1; }","duration":"161.099983ms","start":"2026-10-09T16:14:17.216143Z","end":"2026-10-09T16:14:17.377243Z","steps":["trace[933173987] 'process raft request' (duration: 158.229773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:14:17.54012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.896603ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-09T16:14:17.540233Z","caller":"traceutil/trace.go:171","msg":"trace[1997444232] linearizableReadLoop","detail":"{readStateIndex:22244; appliedIndex:22242; }","duration":"229.412601ms","start":"2026-10-09T16:14:17.310801Z","end":"2026-10-09T16:14:17.540214Z","steps":["trace[1997444232] 'read index received' (duration: 63.66896ms)","trace[1997444232] 'applied index is now lower than readState.Index' (duration: 165.742261ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:14:17.540469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.299474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:14:17.54051Z","caller":"traceutil/trace.go:171","msg":"trace[47115251] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:21508; }","duration":"146.395927ms","start":"2026-10-09T16:14:17.3941Z","end":"2026-10-09T16:14:17.540496Z","steps":["trace[47115251] 'agreement among raft nodes before linearized reading' (duration: 146.303895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:14:17.540502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.684408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:14:17.540578Z","caller":"traceutil/trace.go:171","msg":"trace[2112706506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21508; }","duration":"229.817423ms","start":"2026-10-09T16:14:17.310738Z","end":"2026-10-09T16:14:17.540555Z","steps":["trace[2112706506] 'agreement among raft nodes before linearized reading' (duration: 229.575486ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:14:17.730801Z","caller":"traceutil/trace.go:171","msg":"trace[818234949] transaction","detail":"{read_only:false; response_revision:21510; number_of_response:1; }","duration":"155.17441ms","start":"2026-10-09T16:14:17.575596Z","end":"2026-10-09T16:14:17.73077Z","steps":["trace[818234949] 'process raft request' (duration: 154.965454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:14:18.042238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.249782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:14:18.042324Z","caller":"traceutil/trace.go:171","msg":"trace[668326768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21510; }","duration":"214.406156ms","start":"2026-10-09T16:14:17.827896Z","end":"2026-10-09T16:14:18.042302Z","steps":["trace[668326768] 'range keys from in-memory index tree' (duration: 214.140649ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:14:28.429492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19511} {"level":"info","ts":"2026-10-09T16:14:28.553163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19511,"took":"119.179535ms","hash":4211233216,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":29532160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T16:14:28.553292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4211233216,"revision":19511,"compact-revision":17057} {"level":"info","ts":"2026-10-09T16:18:21.90773Z","caller":"traceutil/trace.go:171","msg":"trace[492885093] transaction","detail":"{read_only:false; response_revision:24031; number_of_response:1; }","duration":"114.013421ms","start":"2026-10-09T16:18:21.793664Z","end":"2026-10-09T16:18:21.907677Z","steps":["trace[492885093] 'process raft request' (duration: 113.729673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:22.248304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.710439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-09T16:18:22.248387Z","caller":"traceutil/trace.go:171","msg":"trace[2081632412] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:24032; }","duration":"242.859113ms","start":"2026-10-09T16:18:22.005509Z","end":"2026-10-09T16:18:22.248368Z","steps":["trace[2081632412] 'range keys from in-memory index tree' (duration: 242.523964ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:28.436633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21565} {"level":"info","ts":"2026-10-09T16:19:28.550373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21565,"took":"109.570138ms","hash":3382448882,"current-db-size-bytes":57991168,"current-db-size":"58 MB","current-db-size-in-use-bytes":43433984,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-09T16:19:28.550442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3382448882,"revision":21565,"compact-revision":19511} {"level":"info","ts":"2026-10-09T16:19:33.828845Z","caller":"traceutil/trace.go:171","msg":"trace[1753670640] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"114.890946ms","start":"2026-10-09T16:19:33.71393Z","end":"2026-10-09T16:19:33.828821Z","steps":["trace[1753670640] 'process raft request' (duration: 64.653447ms)","trace[1753670640] 'compare' (duration: 49.616712ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:32.380052Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068fe.wal"} {"level":"info","ts":"2026-10-09T16:21:38.480856Z","caller":"traceutil/trace.go:171","msg":"trace[1718998391] transaction","detail":"{read_only:false; response_revision:26062; number_of_response:1; }","duration":"125.289073ms","start":"2026-10-09T16:21:38.355544Z","end":"2026-10-09T16:21:38.480833Z","steps":["trace[1718998391] 'process raft request' (duration: 125.16432ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:39.069058Z","caller":"traceutil/trace.go:171","msg":"trace[1217721525] linearizableReadLoop","detail":"{readStateIndex:26928; appliedIndex:26927; }","duration":"101.497846ms","start":"2026-10-09T16:21:38.967531Z","end":"2026-10-09T16:21:39.069029Z","steps":["trace[1217721525] 'read index received' (duration: 101.218378ms)","trace[1217721525] 'applied index is now lower than readState.Index' (duration: 278.378µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:39.069125Z","caller":"traceutil/trace.go:171","msg":"trace[2003817768] transaction","detail":"{read_only:false; response_revision:26064; number_of_response:1; }","duration":"150.445939ms","start":"2026-10-09T16:21:38.918638Z","end":"2026-10-09T16:21:39.069084Z","steps":["trace[2003817768] 'process raft request' (duration: 150.184801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:39.069265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.713622ms","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-10-09T16:21:39.069304Z","caller":"traceutil/trace.go:171","msg":"trace[1235565738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26064; }","duration":"101.808134ms","start":"2026-10-09T16:21:38.967483Z","end":"2026-10-09T16:21:39.069291Z","steps":["trace[1235565738] 'agreement among raft nodes before linearized reading' (duration: 101.65504ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:43.52351Z","caller":"traceutil/trace.go:171","msg":"trace[1852709516] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"115.531564ms","start":"2026-10-09T16:21:43.407954Z","end":"2026-10-09T16:21:43.523486Z","steps":["trace[1852709516] 'process raft request' (duration: 115.38298ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:43.825978Z","caller":"traceutil/trace.go:171","msg":"trace[1620637677] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"371.952431ms","start":"2026-10-09T16:21:43.453997Z","end":"2026-10-09T16:21:43.82595Z","steps":["trace[1620637677] 'process raft request' (duration: 275.907007ms)","trace[1620637677] 'compare' (duration: 95.601782ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:43.826141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:43.453985Z","time spent":"372.069394ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:21:43.826277Z","caller":"traceutil/trace.go:171","msg":"trace[117411746] transaction","detail":"{read_only:false; response_revision:26098; number_of_response:1; }","duration":"368.353032ms","start":"2026-10-09T16:21:43.457877Z","end":"2026-10-09T16:21:43.82623Z","steps":["trace[117411746] 'process raft request' (duration: 367.996982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:43.826538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:43.457863Z","time spent":"368.528247ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:21:43.828785Z","caller":"traceutil/trace.go:171","msg":"trace[397170124] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"227.236021ms","start":"2026-10-09T16:21:43.60151Z","end":"2026-10-09T16:21:43.828746Z","steps":["trace[397170124] 'process raft request' (duration: 226.870571ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:43.885074Z","caller":"traceutil/trace.go:171","msg":"trace[1526690649] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"110.075343ms","start":"2026-10-09T16:21:43.774962Z","end":"2026-10-09T16:21:43.885037Z","steps":["trace[1526690649] 'process raft request' (duration: 109.652001ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:44.040917Z","caller":"traceutil/trace.go:171","msg":"trace[1470469433] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"151.20475ms","start":"2026-10-09T16:21:43.889648Z","end":"2026-10-09T16:21:44.040853Z","steps":["trace[1470469433] 'process raft request' (duration: 115.01955ms)","trace[1470469433] 'compare' (duration: 36.033136ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:44.08243Z","caller":"traceutil/trace.go:171","msg":"trace[2025926598] linearizableReadLoop","detail":"{readStateIndex:26967; appliedIndex:26965; }","duration":"132.662307ms","start":"2026-10-09T16:21:43.949716Z","end":"2026-10-09T16:21:44.082378Z","steps":["trace[2025926598] 'read index received' (duration: 54.94947ms)","trace[2025926598] 'applied index is now lower than readState.Index' (duration: 77.711217ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:44.082463Z","caller":"traceutil/trace.go:171","msg":"trace[218871168] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"191.801652ms","start":"2026-10-09T16:21:43.89063Z","end":"2026-10-09T16:21:44.082431Z","steps":["trace[218871168] 'process raft request' (duration: 191.614277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:44.082671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.962355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-10-09T16:21:44.082767Z","caller":"traceutil/trace.go:171","msg":"trace[499031487] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26102; }","duration":"133.079439ms","start":"2026-10-09T16:21:43.949666Z","end":"2026-10-09T16:21:44.082746Z","steps":["trace[499031487] 'agreement among raft nodes before linearized reading' (duration: 132.832892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:44.338319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.32523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:44.338428Z","caller":"traceutil/trace.go:171","msg":"trace[149226783] linearizableReadLoop","detail":"{readStateIndex:26968; appliedIndex:26967; }","duration":"252.161891ms","start":"2026-10-09T16:21:44.08625Z","end":"2026-10-09T16:21:44.338412Z","steps":["trace[149226783] 'read index received' (duration: 98.309418ms)","trace[149226783] 'applied index is now lower than readState.Index' (duration: 153.851123ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:44.338638Z","caller":"traceutil/trace.go:171","msg":"trace[215003328] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"266.863067ms","start":"2026-10-09T16:21:44.071726Z","end":"2026-10-09T16:21:44.338589Z","steps":["trace[215003328] 'process raft request' (duration: 114.174576ms)","trace[215003328] 'compare' (duration: 152.164846ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:44.338795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.52477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8795811a-1a77-4588-b49d-84b084d5c6dd\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-10-09T16:21:44.338884Z","caller":"traceutil/trace.go:171","msg":"trace[2070390202] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8795811a-1a77-4588-b49d-84b084d5c6dd; range_end:; response_count:1; response_revision:26103; }","duration":"252.663114ms","start":"2026-10-09T16:21:44.086197Z","end":"2026-10-09T16:21:44.33886Z","steps":["trace[2070390202] 'agreement among raft nodes before linearized reading' (duration: 252.449878ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:44.392968Z","caller":"traceutil/trace.go:171","msg":"trace[1587990571] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"181.515777ms","start":"2026-10-09T16:21:44.211426Z","end":"2026-10-09T16:21:44.392942Z","steps":["trace[1587990571] 'process raft request' (duration: 181.367663ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:44.49272Z","caller":"traceutil/trace.go:171","msg":"trace[1442516592] linearizableReadLoop","detail":"{readStateIndex:26970; appliedIndex:26968; }","duration":"154.210692ms","start":"2026-10-09T16:21:44.338453Z","end":"2026-10-09T16:21:44.492663Z","steps":["trace[1442516592] 'read index received' (duration: 54.322321ms)","trace[1442516592] 'applied index is now lower than readState.Index' (duration: 99.886081ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:44.492797Z","caller":"traceutil/trace.go:171","msg":"trace[298620142] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"264.008987ms","start":"2026-10-09T16:21:44.228763Z","end":"2026-10-09T16:21:44.492772Z","steps":["trace[298620142] 'process raft request' (duration: 254.951347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:44.493216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.003924ms","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-10-09T16:21:44.493329Z","caller":"traceutil/trace.go:171","msg":"trace[2060843835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26105; }","duration":"278.22019ms","start":"2026-10-09T16:21:44.215082Z","end":"2026-10-09T16:21:44.493302Z","steps":["trace[2060843835] 'agreement among raft nodes before linearized reading' (duration: 277.787018ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:44.644224Z","caller":"traceutil/trace.go:171","msg":"trace[1818856939] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"145.42148ms","start":"2026-10-09T16:21:44.498767Z","end":"2026-10-09T16:21:44.644188Z","steps":["trace[1818856939] 'process raft request' (duration: 131.764902ms)","trace[1818856939] 'compare' (duration: 13.460773ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:44.969513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.631738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:44.969741Z","caller":"traceutil/trace.go:171","msg":"trace[2110550799] linearizableReadLoop","detail":"{readStateIndex:26973; appliedIndex:26971; }","duration":"140.875194ms","start":"2026-10-09T16:21:44.828849Z","end":"2026-10-09T16:21:44.969724Z","steps":["trace[2110550799] 'read index received' (duration: 115.456622ms)","trace[2110550799] 'applied index is now lower than readState.Index' (duration: 25.417112ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:44.969827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.976297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:44.969858Z","caller":"traceutil/trace.go:171","msg":"trace[2072880975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26108; }","duration":"141.048839ms","start":"2026-10-09T16:21:44.8288Z","end":"2026-10-09T16:21:44.969849Z","steps":["trace[2072880975] 'agreement among raft nodes before linearized reading' (duration: 140.976907ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:44.969894Z","caller":"traceutil/trace.go:171","msg":"trace[25500739] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"467.769279ms","start":"2026-10-09T16:21:44.502032Z","end":"2026-10-09T16:21:44.969801Z","steps":["trace[25500739] 'process raft request' (duration: 322.760661ms)","trace[25500739] 'compare' (duration: 144.484854ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:44.969942Z","caller":"traceutil/trace.go:171","msg":"trace[2138382737] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"285.438809ms","start":"2026-10-09T16:21:44.684471Z","end":"2026-10-09T16:21:44.96991Z","steps":["trace[2138382737] 'process raft request' (duration: 285.163022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:44.970087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:44.502005Z","time spent":"467.971615ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:21:45.434959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.863719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:45.43504Z","caller":"traceutil/trace.go:171","msg":"trace[2137693909] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:26109; }","duration":"194.003713ms","start":"2026-10-09T16:21:45.241019Z","end":"2026-10-09T16:21:45.435022Z","steps":["trace[2137693909] 'count revisions from in-memory index tree' (duration: 193.732735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:45.435341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.462838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:45.435432Z","caller":"traceutil/trace.go:171","msg":"trace[1722947764] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26109; }","duration":"108.623812ms","start":"2026-10-09T16:21:45.326787Z","end":"2026-10-09T16:21:45.43541Z","steps":["trace[1722947764] 'count revisions from in-memory index tree' (duration: 108.362855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:45.435663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.477285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:45.435724Z","caller":"traceutil/trace.go:171","msg":"trace[701089994] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:26109; }","duration":"194.562558ms","start":"2026-10-09T16:21:45.241149Z","end":"2026-10-09T16:21:45.435712Z","steps":["trace[701089994] 'count revisions from in-memory index tree' (duration: 194.421704ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:46.258925Z","caller":"traceutil/trace.go:171","msg":"trace[1282626386] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"368.451614ms","start":"2026-10-09T16:21:45.890446Z","end":"2026-10-09T16:21:46.258898Z","steps":["trace[1282626386] 'process raft request' (duration: 368.404483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:46.259088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:45.890421Z","time spent":"368.587068ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:21:46.259265Z","caller":"traceutil/trace.go:171","msg":"trace[166004049] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"426.466598ms","start":"2026-10-09T16:21:45.832747Z","end":"2026-10-09T16:21:46.259214Z","steps":["trace[166004049] 'process raft request' (duration: 425.913963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:46.259519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:45.832719Z","time spent":"426.666254ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:21:46.468186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.132621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:46.468296Z","caller":"traceutil/trace.go:171","msg":"trace[1462395039] linearizableReadLoop","detail":"{readStateIndex:26979; appliedIndex:26976; }","duration":"422.629283ms","start":"2026-10-09T16:21:46.045646Z","end":"2026-10-09T16:21:46.468275Z","steps":["trace[1462395039] 'read index received' (duration: 213.06146ms)","trace[1462395039] 'applied index is now lower than readState.Index' (duration: 209.566353ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:46.468341Z","caller":"traceutil/trace.go:171","msg":"trace[1500709213] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"534.962317ms","start":"2026-10-09T16:21:45.933344Z","end":"2026-10-09T16:21:46.468307Z","steps":["trace[1500709213] 'process raft request' (duration: 380.61336ms)","trace[1500709213] 'compare' (duration: 153.929635ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:46.468458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.89002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T16:21:46.468493Z","caller":"traceutil/trace.go:171","msg":"trace[1159153402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26114; }","duration":"422.955222ms","start":"2026-10-09T16:21:46.045529Z","end":"2026-10-09T16:21:46.468484Z","steps":["trace[1159153402] 'agreement among raft nodes before linearized reading' (duration: 422.818248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:46.468517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:45.933293Z","time spent":"535.122892ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:21:46.468522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:46.045496Z","time spent":"423.017963ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-09T16:21:46.470464Z","caller":"traceutil/trace.go:171","msg":"trace[1821860104] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"126.185098ms","start":"2026-10-09T16:21:46.344256Z","end":"2026-10-09T16:21:46.470441Z","steps":["trace[1821860104] 'process raft request' (duration: 126.080015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:46.490925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.789144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T16:21:46.490999Z","caller":"traceutil/trace.go:171","msg":"trace[638023293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26116; }","duration":"402.902276ms","start":"2026-10-09T16:21:46.088081Z","end":"2026-10-09T16:21:46.490983Z","steps":["trace[638023293] 'agreement among raft nodes before linearized reading' (duration: 402.703791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:46.491035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:46.088065Z","time spent":"402.961389ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T16:21:46.491476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.966382ms","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-10-09T16:21:46.491521Z","caller":"traceutil/trace.go:171","msg":"trace[1084751721] 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:26116; }","duration":"211.052894ms","start":"2026-10-09T16:21:46.280453Z","end":"2026-10-09T16:21:46.491506Z","steps":["trace[1084751721] 'agreement among raft nodes before linearized reading' (duration: 210.987032ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:46.627129Z","caller":"traceutil/trace.go:171","msg":"trace[200772867] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"153.224375ms","start":"2026-10-09T16:21:46.473876Z","end":"2026-10-09T16:21:46.6271Z","steps":["trace[200772867] 'process raft request' (duration: 148.655779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:46.921246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.876235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:46.921645Z","caller":"traceutil/trace.go:171","msg":"trace[1527337131] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"425.624715ms","start":"2026-10-09T16:21:46.495993Z","end":"2026-10-09T16:21:46.921618Z","steps":["trace[1527337131] 'process raft request' (duration: 314.275027ms)","trace[1527337131] 'compare' (duration: 110.656899ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:46.921711Z","caller":"traceutil/trace.go:171","msg":"trace[664316263] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"423.528597ms","start":"2026-10-09T16:21:46.498132Z","end":"2026-10-09T16:21:46.92166Z","steps":["trace[664316263] 'process raft request' (duration: 423.205088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:46.921814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:46.495976Z","time spent":"425.758179ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:21:46.921837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.967919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:46.921918Z","caller":"traceutil/trace.go:171","msg":"trace[1868263325] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26119; }","duration":"424.071162ms","start":"2026-10-09T16:21:46.497826Z","end":"2026-10-09T16:21:46.921898Z","steps":["trace[1868263325] 'agreement among raft nodes before linearized reading' (duration: 423.942928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:46.921971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:46.497818Z","time spent":"424.140864ms","remote":"127.0.0.1:52624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-09T16:21:46.921985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:46.498106Z","time spent":"423.647221ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:21:46.921953Z","caller":"traceutil/trace.go:171","msg":"trace[1155144669] linearizableReadLoop","detail":"{readStateIndex:26983; appliedIndex:26981; }","duration":"423.536317ms","start":"2026-10-09T16:21:46.497868Z","end":"2026-10-09T16:21:46.921404Z","steps":["trace[1155144669] 'read index received' (duration: 124.745208ms)","trace[1155144669] 'applied index is now lower than readState.Index' (duration: 298.789819ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:46.923469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.016984ms","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-10-09T16:21:46.923546Z","caller":"traceutil/trace.go:171","msg":"trace[1705630340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26120; }","duration":"274.151528ms","start":"2026-10-09T16:21:46.649377Z","end":"2026-10-09T16:21:46.923528Z","steps":["trace[1705630340] 'agreement among raft nodes before linearized reading' (duration: 273.927381ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:47.113991Z","caller":"traceutil/trace.go:171","msg":"trace[992150497] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"138.674553ms","start":"2026-10-09T16:21:46.975286Z","end":"2026-10-09T16:21:47.11396Z","steps":["trace[992150497] 'process raft request' (duration: 136.620496ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:47.369083Z","caller":"traceutil/trace.go:171","msg":"trace[1731748508] linearizableReadLoop","detail":"{readStateIndex:26988; appliedIndex:26987; }","duration":"175.977174ms","start":"2026-10-09T16:21:47.193081Z","end":"2026-10-09T16:21:47.369059Z","steps":["trace[1731748508] 'read index received' (duration: 175.863721ms)","trace[1731748508] 'applied index is now lower than readState.Index' (duration: 112.133µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:47.369106Z","caller":"traceutil/trace.go:171","msg":"trace[1029888117] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"393.791885ms","start":"2026-10-09T16:21:46.975294Z","end":"2026-10-09T16:21:47.369086Z","steps":["trace[1029888117] 'process raft request' (duration: 393.62238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:47.369247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:46.975277Z","time spent":"393.894478ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:21:47.369456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.352004ms","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-10-09T16:21:47.369543Z","caller":"traceutil/trace.go:171","msg":"trace[1090186755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26123; }","duration":"176.484918ms","start":"2026-10-09T16:21:47.193037Z","end":"2026-10-09T16:21:47.369522Z","steps":["trace[1090186755] 'agreement among raft nodes before linearized reading' (duration: 176.282702ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:47.469576Z","caller":"traceutil/trace.go:171","msg":"trace[196739742] linearizableReadLoop","detail":"{readStateIndex:26989; appliedIndex:26988; }","duration":"100.405456ms","start":"2026-10-09T16:21:47.369145Z","end":"2026-10-09T16:21:47.469551Z","steps":["trace[196739742] 'read index received' (duration: 58.701713ms)","trace[196739742] 'applied index is now lower than readState.Index' (duration: 41.701573ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:47.469804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.391918ms","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-10-09T16:21:47.469861Z","caller":"traceutil/trace.go:171","msg":"trace[1091285272] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26124; }","duration":"247.576233ms","start":"2026-10-09T16:21:47.222272Z","end":"2026-10-09T16:21:47.469848Z","steps":["trace[1091285272] 'agreement among raft nodes before linearized reading' (duration: 247.357107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:47.469702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.583097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:47.470369Z","caller":"traceutil/trace.go:171","msg":"trace[603651534] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:26124; }","duration":"200.315127ms","start":"2026-10-09T16:21:47.270013Z","end":"2026-10-09T16:21:47.470328Z","steps":["trace[603651534] 'agreement among raft nodes before linearized reading' (duration: 199.562226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:47.779912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.782503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T16:21:47.780004Z","caller":"traceutil/trace.go:171","msg":"trace[2127577325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26124; }","duration":"165.918517ms","start":"2026-10-09T16:21:47.614067Z","end":"2026-10-09T16:21:47.779986Z","steps":["trace[2127577325] 'range keys from in-memory index tree' (duration: 165.619918ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:48.040472Z","caller":"traceutil/trace.go:171","msg":"trace[1970700507] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"119.232006ms","start":"2026-10-09T16:21:47.9212Z","end":"2026-10-09T16:21:48.040432Z","steps":["trace[1970700507] 'process raft request' (duration: 101.64829ms)","trace[1970700507] 'compare' (duration: 17.271587ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:48.378671Z","caller":"traceutil/trace.go:171","msg":"trace[53998044] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"113.738123ms","start":"2026-10-09T16:21:48.264893Z","end":"2026-10-09T16:21:48.378631Z","steps":["trace[53998044] 'process raft request' (duration: 113.524837ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:48.585639Z","caller":"traceutil/trace.go:171","msg":"trace[1504954831] linearizableReadLoop","detail":"{readStateIndex:26994; appliedIndex:26992; }","duration":"268.127861ms","start":"2026-10-09T16:21:48.317472Z","end":"2026-10-09T16:21:48.5856Z","steps":["trace[1504954831] 'read index received' (duration: 60.916614ms)","trace[1504954831] 'applied index is now lower than readState.Index' (duration: 207.209587ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:48.585807Z","caller":"traceutil/trace.go:171","msg":"trace[1707579176] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"320.477598ms","start":"2026-10-09T16:21:48.265315Z","end":"2026-10-09T16:21:48.585792Z","steps":["trace[1707579176] 'process raft request' (duration: 320.073327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:48.585966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:48.26529Z","time spent":"320.573181ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:21:48.586232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.734749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:48.586337Z","caller":"traceutil/trace.go:171","msg":"trace[295467574] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26128; }","duration":"268.922544ms","start":"2026-10-09T16:21:48.317389Z","end":"2026-10-09T16:21:48.586311Z","steps":["trace[295467574] 'agreement among raft nodes before linearized reading' (duration: 268.731279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:48.60066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.70626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:48.600757Z","caller":"traceutil/trace.go:171","msg":"trace[99434072] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:26130; }","duration":"178.871415ms","start":"2026-10-09T16:21:48.421867Z","end":"2026-10-09T16:21:48.600739Z","steps":["trace[99434072] 'agreement among raft nodes before linearized reading' (duration: 178.70357ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:48.601108Z","caller":"traceutil/trace.go:171","msg":"trace[245397949] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"123.379651ms","start":"2026-10-09T16:21:48.477713Z","end":"2026-10-09T16:21:48.601093Z","steps":["trace[245397949] 'process raft request' (duration: 122.689032ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:48.601263Z","caller":"traceutil/trace.go:171","msg":"trace[108471967] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"103.621144ms","start":"2026-10-09T16:21:48.497611Z","end":"2026-10-09T16:21:48.601232Z","steps":["trace[108471967] 'process raft request' (duration: 102.919535ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:48.835959Z","caller":"traceutil/trace.go:171","msg":"trace[1024243868] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"198.619911ms","start":"2026-10-09T16:21:48.6373Z","end":"2026-10-09T16:21:48.83592Z","steps":["trace[1024243868] 'process raft request' (duration: 115.462842ms)","trace[1024243868] 'compare' (duration: 82.881501ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:49.109241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.889603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:49.109484Z","caller":"traceutil/trace.go:171","msg":"trace[2093869930] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"179.308177ms","start":"2026-10-09T16:21:48.930154Z","end":"2026-10-09T16:21:49.109462Z","steps":["trace[2093869930] 'process raft request' (duration: 29.111905ms)","trace[2093869930] 'compare' (duration: 149.7585ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:49.109925Z","caller":"traceutil/trace.go:171","msg":"trace[1217978672] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"179.033389ms","start":"2026-10-09T16:21:48.930867Z","end":"2026-10-09T16:21:49.109901Z","steps":["trace[1217978672] 'process raft request' (duration: 178.550446ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:49.109912Z","caller":"traceutil/trace.go:171","msg":"trace[790823873] linearizableReadLoop","detail":"{readStateIndex:26999; appliedIndex:26997; }","duration":"167.656805ms","start":"2026-10-09T16:21:48.942234Z","end":"2026-10-09T16:21:49.109891Z","steps":["trace[790823873] 'read index received' (duration: 16.948869ms)","trace[790823873] 'applied index is now lower than readState.Index' (duration: 150.706646ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:49.110083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.844579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:21:49.110128Z","caller":"traceutil/trace.go:171","msg":"trace[1510666608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26133; }","duration":"167.937642ms","start":"2026-10-09T16:21:48.942175Z","end":"2026-10-09T16:21:49.110113Z","steps":["trace[1510666608] 'agreement among raft nodes before linearized reading' (duration: 167.794428ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:49.305943Z","caller":"traceutil/trace.go:171","msg":"trace[1159726240] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"191.537763ms","start":"2026-10-09T16:21:49.114386Z","end":"2026-10-09T16:21:49.305924Z","steps":["trace[1159726240] 'process raft request' (duration: 191.41378ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:49.319642Z","caller":"traceutil/trace.go:171","msg":"trace[1569823747] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"150.403227ms","start":"2026-10-09T16:21:49.169201Z","end":"2026-10-09T16:21:49.319605Z","steps":["trace[1569823747] 'process raft request' (duration: 150.349685ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:49.319841Z","caller":"traceutil/trace.go:171","msg":"trace[787887934] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"201.372646ms","start":"2026-10-09T16:21:49.118453Z","end":"2026-10-09T16:21:49.319826Z","steps":["trace[787887934] 'process raft request' (duration: 200.966284ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:49.516043Z","caller":"traceutil/trace.go:171","msg":"trace[1424159894] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"139.050844ms","start":"2026-10-09T16:21:49.376964Z","end":"2026-10-09T16:21:49.516015Z","steps":["trace[1424159894] 'process raft request' (duration: 125.299093ms)","trace[1424159894] 'compare' (duration: 13.242647ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:49.696042Z","caller":"traceutil/trace.go:171","msg":"trace[1074507915] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"175.10394ms","start":"2026-10-09T16:21:49.520912Z","end":"2026-10-09T16:21:49.696016Z","steps":["trace[1074507915] 'process raft request' (duration: 174.919575ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:49.993492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.460526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:49.993593Z","caller":"traceutil/trace.go:171","msg":"trace[314097379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26139; }","duration":"164.59799ms","start":"2026-10-09T16:21:49.828972Z","end":"2026-10-09T16:21:49.99357Z","steps":["trace[314097379] 'range keys from in-memory index tree' (duration: 164.380854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:49.993886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.62946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:21:49.993962Z","caller":"traceutil/trace.go:171","msg":"trace[1216437794] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:26139; }","duration":"105.751783ms","start":"2026-10-09T16:21:49.888191Z","end":"2026-10-09T16:21:49.993943Z","steps":["trace[1216437794] 'count revisions from in-memory index tree' (duration: 105.503487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:49.994128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.089101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T16:21:49.994209Z","caller":"traceutil/trace.go:171","msg":"trace[440452399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26139; }","duration":"183.219894ms","start":"2026-10-09T16:21:49.810971Z","end":"2026-10-09T16:21:49.994191Z","steps":["trace[440452399] 'range keys from in-memory index tree' (duration: 182.821724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:50.272185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.714602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:50.272335Z","caller":"traceutil/trace.go:171","msg":"trace[1631015913] linearizableReadLoop","detail":"{readStateIndex:27007; appliedIndex:27006; }","duration":"172.142398ms","start":"2026-10-09T16:21:50.100174Z","end":"2026-10-09T16:21:50.272317Z","steps":["trace[1631015913] 'read index received' (duration: 19.19118ms)","trace[1631015913] 'applied index is now lower than readState.Index' (duration: 152.949368ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:50.272418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.237791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:50.272444Z","caller":"traceutil/trace.go:171","msg":"trace[9774525] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26141; }","duration":"172.287092ms","start":"2026-10-09T16:21:50.100146Z","end":"2026-10-09T16:21:50.272433Z","steps":["trace[9774525] 'agreement among raft nodes before linearized reading' (duration: 172.21907ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:50.272509Z","caller":"traceutil/trace.go:171","msg":"trace[685339967] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"225.193264ms","start":"2026-10-09T16:21:50.047278Z","end":"2026-10-09T16:21:50.272472Z","steps":["trace[685339967] 'process raft request' (duration: 72.090722ms)","trace[685339967] 'compare' (duration: 152.483875ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:50.610791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.088737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:50.610893Z","caller":"traceutil/trace.go:171","msg":"trace[313730605] linearizableReadLoop","detail":"{readStateIndex:27008; appliedIndex:27007; }","duration":"144.552526ms","start":"2026-10-09T16:21:50.466324Z","end":"2026-10-09T16:21:50.610877Z","steps":["trace[313730605] 'read index received' (duration: 33.771µs)","trace[313730605] 'applied index is now lower than readState.Index' (duration: 144.516875ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:50.610971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.650779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:21:50.610923Z","caller":"traceutil/trace.go:171","msg":"trace[668025649] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"226.152642ms","start":"2026-10-09T16:21:50.384734Z","end":"2026-10-09T16:21:50.610887Z","steps":["trace[668025649] 'process raft request' (duration: 70.88315ms)","trace[668025649] 'compare' (duration: 154.953863ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:50.611003Z","caller":"traceutil/trace.go:171","msg":"trace[1853924361] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:26142; }","duration":"144.71451ms","start":"2026-10-09T16:21:50.466279Z","end":"2026-10-09T16:21:50.610993Z","steps":["trace[1853924361] 'agreement among raft nodes before linearized reading' (duration: 144.658748ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:50.81874Z","caller":"traceutil/trace.go:171","msg":"trace[1046112346] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"211.550387ms","start":"2026-10-09T16:21:50.607165Z","end":"2026-10-09T16:21:50.818715Z","steps":["trace[1046112346] 'process raft request' (duration: 211.255339ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:50.818883Z","caller":"traceutil/trace.go:171","msg":"trace[1874485206] linearizableReadLoop","detail":"{readStateIndex:27011; appliedIndex:27008; }","duration":"207.939727ms","start":"2026-10-09T16:21:50.610918Z","end":"2026-10-09T16:21:50.818857Z","steps":["trace[1874485206] 'read index received' (duration: 58.673772ms)","trace[1874485206] 'applied index is now lower than readState.Index' (duration: 149.263725ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:50.819008Z","caller":"traceutil/trace.go:171","msg":"trace[1770284014] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"211.039903ms","start":"2026-10-09T16:21:50.607954Z","end":"2026-10-09T16:21:50.818994Z","steps":["trace[1770284014] 'process raft request' (duration: 210.694783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:50.819078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.129483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:50.819121Z","caller":"traceutil/trace.go:171","msg":"trace[825406033] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26145; }","duration":"161.231966ms","start":"2026-10-09T16:21:50.657876Z","end":"2026-10-09T16:21:50.819108Z","steps":["trace[825406033] 'agreement among raft nodes before linearized reading' (duration: 161.134613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:50.819242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.817742ms","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-10-09T16:21:50.819305Z","caller":"traceutil/trace.go:171","msg":"trace[2034986963] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26145; }","duration":"352.915955ms","start":"2026-10-09T16:21:50.466374Z","end":"2026-10-09T16:21:50.81929Z","steps":["trace[2034986963] 'agreement among raft nodes before linearized reading' (duration: 352.688149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:50.819338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:50.466362Z","time spent":"352.967816ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-09T16:21:55.501668Z","caller":"traceutil/trace.go:171","msg":"trace[1724063054] transaction","detail":"{read_only:false; response_revision:26177; number_of_response:1; }","duration":"165.236057ms","start":"2026-10-09T16:21:55.336398Z","end":"2026-10-09T16:21:55.501634Z","steps":["trace[1724063054] 'process raft request' (duration: 164.9858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:55.976507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.343535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:55.976752Z","caller":"traceutil/trace.go:171","msg":"trace[1475469619] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"635.531947ms","start":"2026-10-09T16:21:55.341184Z","end":"2026-10-09T16:21:55.976716Z","steps":["trace[1475469619] 'process raft request' (duration: 318.842633ms)","trace[1475469619] 'compare' (duration: 315.950644ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:55.976849Z","caller":"traceutil/trace.go:171","msg":"trace[860502989] linearizableReadLoop","detail":"{readStateIndex:27046; appliedIndex:27044; }","duration":"255.340238ms","start":"2026-10-09T16:21:55.721482Z","end":"2026-10-09T16:21:55.976822Z","steps":["trace[860502989] 'read index received' (duration: 78.083µs)","trace[860502989] 'applied index is now lower than readState.Index' (duration: 255.260805ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:55.976936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:55.34115Z","time spent":"635.682942ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:21:55.977125Z","caller":"traceutil/trace.go:171","msg":"trace[1461976280] transaction","detail":"{read_only:false; response_revision:26179; number_of_response:1; }","duration":"439.319233ms","start":"2026-10-09T16:21:55.537792Z","end":"2026-10-09T16:21:55.977111Z","steps":["trace[1461976280] 'process raft request' (duration: 438.873581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:55.977213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:55.537758Z","time spent":"439.407176ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:21:55.977383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.910804ms","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-10-09T16:21:55.977424Z","caller":"traceutil/trace.go:171","msg":"trace[1433481181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26179; }","duration":"255.993216ms","start":"2026-10-09T16:21:55.721419Z","end":"2026-10-09T16:21:55.977412Z","steps":["trace[1433481181] 'agreement among raft nodes before linearized reading' (duration: 255.857862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:56.460189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.668853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:21:56.460409Z","caller":"traceutil/trace.go:171","msg":"trace[513437255] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"151.661871ms","start":"2026-10-09T16:21:56.308727Z","end":"2026-10-09T16:21:56.460389Z","steps":["trace[513437255] 'process raft request' (duration: 151.562788ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:56.4604Z","caller":"traceutil/trace.go:171","msg":"trace[448965430] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"477.757755ms","start":"2026-10-09T16:21:55.982536Z","end":"2026-10-09T16:21:56.460294Z","steps":["trace[448965430] 'process raft request' (duration: 224.880866ms)","trace[448965430] 'compare' (duration: 252.561371ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:56.460477Z","caller":"traceutil/trace.go:171","msg":"trace[994182346] linearizableReadLoop","detail":"{readStateIndex:27047; appliedIndex:27046; }","duration":"476.915462ms","start":"2026-10-09T16:21:55.983537Z","end":"2026-10-09T16:21:56.460453Z","steps":["trace[994182346] 'read index received' (duration: 223.766945ms)","trace[994182346] 'applied index is now lower than readState.Index' (duration: 253.145487ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:21:56.460832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.5774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-09T16:21:56.460823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.282502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19150"} {"level":"info","ts":"2026-10-09T16:21:56.460878Z","caller":"traceutil/trace.go:171","msg":"trace[131238046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26181; }","duration":"405.696223ms","start":"2026-10-09T16:21:56.055169Z","end":"2026-10-09T16:21:56.460865Z","steps":["trace[131238046] 'agreement among raft nodes before linearized reading' (duration: 405.498648ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:56.460886Z","caller":"traceutil/trace.go:171","msg":"trace[115620020] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26181; }","duration":"477.348823ms","start":"2026-10-09T16:21:55.983524Z","end":"2026-10-09T16:21:56.460872Z","steps":["trace[115620020] 'agreement among raft nodes before linearized reading' (duration: 477.045885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:56.460913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:56.055116Z","time spent":"405.787075ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T16:21:56.460919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:55.983474Z","time spent":"477.437706ms","remote":"127.0.0.1:52602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19174,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-09T16:21:56.460848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:55.982469Z","time spent":"478.043643ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:21:57.010286Z","caller":"traceutil/trace.go:171","msg":"trace[1104982670] linearizableReadLoop","detail":"{readStateIndex:27050; appliedIndex:27049; }","duration":"463.793ms","start":"2026-10-09T16:21:56.546446Z","end":"2026-10-09T16:21:57.010239Z","steps":["trace[1104982670] 'read index received' (duration: 463.714238ms)","trace[1104982670] 'applied index is now lower than readState.Index' (duration: 77.512µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:21:57.01039Z","caller":"traceutil/trace.go:171","msg":"trace[863119982] transaction","detail":"{read_only:false; response_revision:26183; number_of_response:1; }","duration":"464.817409ms","start":"2026-10-09T16:21:56.545536Z","end":"2026-10-09T16:21:57.010353Z","steps":["trace[863119982] 'process raft request' (duration: 464.535081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:57.010444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.977246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:57.010477Z","caller":"traceutil/trace.go:171","msg":"trace[59168816] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26183; }","duration":"464.034067ms","start":"2026-10-09T16:21:56.546433Z","end":"2026-10-09T16:21:57.010467Z","steps":["trace[59168816] 'agreement among raft nodes before linearized reading' (duration: 463.951025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:57.010512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:56.546396Z","time spent":"464.106479ms","remote":"127.0.0.1:52624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-09T16:21:57.010541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:56.545509Z","time spent":"464.949612ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:21:57.024634Z","caller":"traceutil/trace.go:171","msg":"trace[1323847961] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"325.685682ms","start":"2026-10-09T16:21:56.698925Z","end":"2026-10-09T16:21:57.02461Z","steps":["trace[1323847961] 'process raft request' (duration: 325.60458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:57.024832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:56.698892Z","time spent":"325.843086ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:21:57.024653Z","caller":"traceutil/trace.go:171","msg":"trace[1974088837] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"391.859631ms","start":"2026-10-09T16:21:56.632769Z","end":"2026-10-09T16:21:57.024629Z","steps":["trace[1974088837] 'process raft request' (duration: 391.612515ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:57.024869Z","caller":"traceutil/trace.go:171","msg":"trace[1578847021] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"195.706019ms","start":"2026-10-09T16:21:56.82914Z","end":"2026-10-09T16:21:57.024846Z","steps":["trace[1578847021] 'process raft request' (duration: 195.435932ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:57.024972Z","caller":"traceutil/trace.go:171","msg":"trace[1387525919] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"175.800489ms","start":"2026-10-09T16:21:56.849156Z","end":"2026-10-09T16:21:57.024956Z","steps":["trace[1387525919] 'process raft request' (duration: 175.587284ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:57.025046Z","caller":"traceutil/trace.go:171","msg":"trace[1019796982] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"174.177835ms","start":"2026-10-09T16:21:56.850796Z","end":"2026-10-09T16:21:57.024974Z","steps":["trace[1019796982] 'process raft request' (duration: 174.030891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:57.025043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:21:56.632741Z","time spent":"392.195491ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:21:57.025278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.776898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:57.025338Z","caller":"traceutil/trace.go:171","msg":"trace[655825884] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:26189; }","duration":"222.8578ms","start":"2026-10-09T16:21:56.802449Z","end":"2026-10-09T16:21:57.025307Z","steps":["trace[655825884] 'agreement among raft nodes before linearized reading' (duration: 222.784258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:57.02535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.051467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T16:21:57.025382Z","caller":"traceutil/trace.go:171","msg":"trace[418368939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26189; }","duration":"146.111869ms","start":"2026-10-09T16:21:56.879259Z","end":"2026-10-09T16:21:57.025371Z","steps":["trace[418368939] 'agreement among raft nodes before linearized reading' (duration: 146.006406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:21:57.025518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.988515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:21:57.025544Z","caller":"traceutil/trace.go:171","msg":"trace[727657762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26189; }","duration":"197.071828ms","start":"2026-10-09T16:21:56.828463Z","end":"2026-10-09T16:21:57.025535Z","steps":["trace[727657762] 'agreement among raft nodes before linearized reading' (duration: 197.027886ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:06.754256Z","caller":"traceutil/trace.go:171","msg":"trace[143634640] linearizableReadLoop","detail":"{readStateIndex:27181; appliedIndex:27180; }","duration":"250.281158ms","start":"2026-10-09T16:22:06.50395Z","end":"2026-10-09T16:22:06.754231Z","steps":["trace[143634640] 'read index received' (duration: 250.068113ms)","trace[143634640] 'applied index is now lower than readState.Index' (duration: 212.245µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:06.754419Z","caller":"traceutil/trace.go:171","msg":"trace[119275431] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"256.969183ms","start":"2026-10-09T16:22:06.497325Z","end":"2026-10-09T16:22:06.754294Z","steps":["trace[119275431] 'process raft request' (duration: 256.749177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:06.754484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.510655ms","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-10-09T16:22:06.754525Z","caller":"traceutil/trace.go:171","msg":"trace[1594154245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26312; }","duration":"250.608807ms","start":"2026-10-09T16:22:06.503905Z","end":"2026-10-09T16:22:06.754514Z","steps":["trace[1594154245] 'agreement among raft nodes before linearized reading' (duration: 250.453173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:06.754603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.459683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T16:22:06.754706Z","caller":"traceutil/trace.go:171","msg":"trace[807934907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26312; }","duration":"233.607767ms","start":"2026-10-09T16:22:06.521054Z","end":"2026-10-09T16:22:06.754662Z","steps":["trace[807934907] 'agreement among raft nodes before linearized reading' (duration: 233.412222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:06.754783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.653975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:22:06.754862Z","caller":"traceutil/trace.go:171","msg":"trace[774838743] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26312; }","duration":"188.788489ms","start":"2026-10-09T16:22:06.56605Z","end":"2026-10-09T16:22:06.754838Z","steps":["trace[774838743] 'agreement among raft nodes before linearized reading' (duration: 188.621985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:06.754953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.53545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90c4f138-2123-4358-9816-3b5850fb09a1\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-10-09T16:22:06.755068Z","caller":"traceutil/trace.go:171","msg":"trace[134150902] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90c4f138-2123-4358-9816-3b5850fb09a1; range_end:; response_count:1; response_revision:26312; }","duration":"121.698454ms","start":"2026-10-09T16:22:06.633353Z","end":"2026-10-09T16:22:06.755052Z","steps":["trace[134150902] 'agreement among raft nodes before linearized reading' (duration: 121.300413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.109265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.382467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:22:07.10953Z","caller":"traceutil/trace.go:171","msg":"trace[1542929016] linearizableReadLoop","detail":"{readStateIndex:27185; appliedIndex:27182; }","duration":"282.102908ms","start":"2026-10-09T16:22:06.827409Z","end":"2026-10-09T16:22:07.109512Z","steps":["trace[1542929016] 'read index received' (duration: 114.230047ms)","trace[1542929016] 'applied index is now lower than readState.Index' (duration: 167.871991ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:07.109564Z","caller":"traceutil/trace.go:171","msg":"trace[283741419] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"350.338574ms","start":"2026-10-09T16:22:06.75913Z","end":"2026-10-09T16:22:07.109469Z","steps":["trace[283741419] 'process raft request' (duration: 182.610077ms)","trace[283741419] 'compare' (duration: 166.456241ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:07.109758Z","caller":"traceutil/trace.go:171","msg":"trace[1595056864] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"350.476197ms","start":"2026-10-09T16:22:06.759268Z","end":"2026-10-09T16:22:07.109744Z","steps":["trace[1595056864] 'process raft request' (duration: 350.088796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.109825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:06.759242Z","time spent":"350.545009ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:22:07.10973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:06.759113Z","time spent":"350.516289ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:22:07.11022Z","caller":"traceutil/trace.go:171","msg":"trace[256552669] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"347.995499ms","start":"2026-10-09T16:22:06.762188Z","end":"2026-10-09T16:22:07.110184Z","steps":["trace[256552669] 'process raft request' (duration: 347.270239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.11043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:06.762173Z","time spent":"348.190034ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3739,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:22:07.11024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.796497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:22:07.110586Z","caller":"traceutil/trace.go:171","msg":"trace[1585871682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26316; }","duration":"283.190418ms","start":"2026-10-09T16:22:06.827369Z","end":"2026-10-09T16:22:07.110559Z","steps":["trace[1585871682] 'agreement among raft nodes before linearized reading' (duration: 282.756506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.411282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.00635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:22:07.411757Z","caller":"traceutil/trace.go:171","msg":"trace[501004482] transaction","detail":"{read_only:false; response_revision:26318; number_of_response:1; }","duration":"356.941455ms","start":"2026-10-09T16:22:07.054787Z","end":"2026-10-09T16:22:07.411728Z","steps":["trace[501004482] 'process raft request' (duration: 233.393721ms)","trace[501004482] 'compare' (duration: 122.744592ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:07.411909Z","caller":"traceutil/trace.go:171","msg":"trace[1452039582] linearizableReadLoop","detail":"{readStateIndex:27190; appliedIndex:27185; }","duration":"302.323346ms","start":"2026-10-09T16:22:07.109566Z","end":"2026-10-09T16:22:07.411889Z","steps":["trace[1452039582] 'read index received' (duration: 23.895771ms)","trace[1452039582] 'applied index is now lower than readState.Index' (duration: 278.423545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:22:07.412055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.05476Z","time spent":"357.076349ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:22:07.412261Z","caller":"traceutil/trace.go:171","msg":"trace[1784856386] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"331.771989ms","start":"2026-10-09T16:22:07.080455Z","end":"2026-10-09T16:22:07.412227Z","steps":["trace[1784856386] 'process raft request' (duration: 331.361238ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:07.412049Z","caller":"traceutil/trace.go:171","msg":"trace[1632566853] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"335.558304ms","start":"2026-10-09T16:22:07.076479Z","end":"2026-10-09T16:22:07.412038Z","steps":["trace[1632566853] 'process raft request' (duration: 334.926547ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:07.412336Z","caller":"traceutil/trace.go:171","msg":"trace[2024542449] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"334.193846ms","start":"2026-10-09T16:22:07.078077Z","end":"2026-10-09T16:22:07.412271Z","steps":["trace[2024542449] 'process raft request' (duration: 333.695983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.412363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.43005ms","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":"warn","ts":"2026-10-09T16:22:07.412397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.080445Z","time spent":"331.885983ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:22:07.412436Z","caller":"traceutil/trace.go:171","msg":"trace[891857547] 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:26321; }","duration":"352.555204ms","start":"2026-10-09T16:22:07.059862Z","end":"2026-10-09T16:22:07.412418Z","steps":["trace[891857547] 'agreement among raft nodes before linearized reading' (duration: 352.395749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.412415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.076427Z","time spent":"335.942575ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:22:07.41251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.078064Z","time spent":"334.356091ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:22:07.412484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.059846Z","time spent":"352.627566ms","remote":"127.0.0.1:36652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-10-09T16:22:07.41271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.812169ms","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-10-09T16:22:07.412843Z","caller":"traceutil/trace.go:171","msg":"trace[1227307666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26321; }","duration":"323.989594ms","start":"2026-10-09T16:22:07.088811Z","end":"2026-10-09T16:22:07.412801Z","steps":["trace[1227307666] 'agreement among raft nodes before linearized reading' (duration: 323.432289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.412923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.0888Z","time spent":"324.107858ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-09T16:22:07.434791Z","caller":"traceutil/trace.go:171","msg":"trace[1886769622] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"250.530766ms","start":"2026-10-09T16:22:07.184241Z","end":"2026-10-09T16:22:07.434772Z","steps":["trace[1886769622] 'process raft request' (duration: 250.401612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.733311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.754208ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-09T16:22:07.733824Z","caller":"traceutil/trace.go:171","msg":"trace[336245763] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"365.445921ms","start":"2026-10-09T16:22:07.368351Z","end":"2026-10-09T16:22:07.733797Z","steps":["trace[336245763] 'process raft request' (duration: 365.121952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.733987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.368324Z","time spent":"365.557455ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:22:07.734018Z","caller":"traceutil/trace.go:171","msg":"trace[808193325] linearizableReadLoop","detail":"{readStateIndex:27193; appliedIndex:27190; }","duration":"322.010082ms","start":"2026-10-09T16:22:07.411979Z","end":"2026-10-09T16:22:07.733989Z","steps":["trace[808193325] 'read index received' (duration: 22.77094ms)","trace[808193325] 'applied index is now lower than readState.Index' (duration: 299.237902ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:22:07.734202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.887837ms","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-10-09T16:22:07.734233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.457848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T16:22:07.734251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.133634ms","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-10-09T16:22:07.734294Z","caller":"traceutil/trace.go:171","msg":"trace[2065759468] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26324; }","duration":"551.244678ms","start":"2026-10-09T16:22:07.183037Z","end":"2026-10-09T16:22:07.734282Z","steps":["trace[2065759468] 'agreement among raft nodes before linearized reading' (duration: 551.081213ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:07.734287Z","caller":"traceutil/trace.go:171","msg":"trace[997155530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26324; }","duration":"526.00317ms","start":"2026-10-09T16:22:07.208265Z","end":"2026-10-09T16:22:07.734268Z","steps":["trace[997155530] 'agreement among raft nodes before linearized reading' (duration: 525.743563ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:07.734291Z","caller":"traceutil/trace.go:171","msg":"trace[1855486827] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26324; }","duration":"316.5532ms","start":"2026-10-09T16:22:07.417722Z","end":"2026-10-09T16:22:07.734275Z","steps":["trace[1855486827] 'agreement among raft nodes before linearized reading' (duration: 316.451128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.734274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.148967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T16:22:07.734342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.417706Z","time spent":"316.627482ms","remote":"127.0.0.1:52624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-09T16:22:07.734341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.208254Z","time spent":"526.076712ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T16:22:07.734334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.183017Z","time spent":"551.307289ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-09T16:22:07.734394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.23856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:22:07.734413Z","caller":"traceutil/trace.go:171","msg":"trace[120085896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26324; }","duration":"622.361972ms","start":"2026-10-09T16:22:07.112Z","end":"2026-10-09T16:22:07.734362Z","steps":["trace[120085896] 'agreement among raft nodes before linearized reading' (duration: 622.138416ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:07.734406Z","caller":"traceutil/trace.go:171","msg":"trace[215431899] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"317.528547ms","start":"2026-10-09T16:22:07.416846Z","end":"2026-10-09T16:22:07.734374Z","steps":["trace[215431899] 'process raft request' (duration: 316.88869ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:07.734478Z","caller":"traceutil/trace.go:171","msg":"trace[1973717328] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26324; }","duration":"168.347373ms","start":"2026-10-09T16:22:07.566091Z","end":"2026-10-09T16:22:07.734438Z","steps":["trace[1973717328] 'agreement among raft nodes before linearized reading' (duration: 168.182308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.734486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.704674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-09T16:22:07.734494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.111985Z","time spent":"622.463965ms","remote":"127.0.0.1:52472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T16:22:07.734535Z","caller":"traceutil/trace.go:171","msg":"trace[569083832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26324; }","duration":"168.806516ms","start":"2026-10-09T16:22:07.565712Z","end":"2026-10-09T16:22:07.734519Z","steps":["trace[569083832] 'agreement among raft nodes before linearized reading' (duration: 168.608361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.734539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.416819Z","time spent":"317.644471ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:22:07.734666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.47174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90c4f138-2123-4358-9816-3b5850fb09a1\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-10-09T16:22:07.734803Z","caller":"traceutil/trace.go:171","msg":"trace[1909156220] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90c4f138-2123-4358-9816-3b5850fb09a1; range_end:; response_count:1; response_revision:26324; }","duration":"565.663416ms","start":"2026-10-09T16:22:07.16912Z","end":"2026-10-09T16:22:07.734784Z","steps":["trace[1909156220] 'agreement among raft nodes before linearized reading' (duration: 565.234304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:07.734878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:07.169098Z","time spent":"565.765009ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3781,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90c4f138-2123-4358-9816-3b5850fb09a1\" "} {"level":"info","ts":"2026-10-09T16:22:07.921571Z","caller":"traceutil/trace.go:171","msg":"trace[896923772] linearizableReadLoop","detail":"{readStateIndex:27196; appliedIndex:27195; }","duration":"111.845992ms","start":"2026-10-09T16:22:07.809701Z","end":"2026-10-09T16:22:07.921547Z","steps":["trace[896923772] 'read index received' (duration: 49.052926ms)","trace[896923772] 'applied index is now lower than readState.Index' (duration: 62.792166ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:22:07.921756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.922423ms","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-10-09T16:22:07.921727Z","caller":"traceutil/trace.go:171","msg":"trace[1869621438] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"181.082525ms","start":"2026-10-09T16:22:07.74058Z","end":"2026-10-09T16:22:07.921663Z","steps":["trace[1869621438] 'process raft request' (duration: 118.157506ms)","trace[1869621438] 'compare' (duration: 62.688512ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:07.921791Z","caller":"traceutil/trace.go:171","msg":"trace[83553564] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26326; }","duration":"179.995605ms","start":"2026-10-09T16:22:07.741782Z","end":"2026-10-09T16:22:07.921778Z","steps":["trace[83553564] 'agreement among raft nodes before linearized reading' (duration: 179.892092ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:10.061708Z","caller":"traceutil/trace.go:171","msg":"trace[725243111] linearizableReadLoop","detail":"{readStateIndex:27210; appliedIndex:27209; }","duration":"148.506455ms","start":"2026-10-09T16:22:09.913169Z","end":"2026-10-09T16:22:10.061675Z","steps":["trace[725243111] 'read index received' (duration: 148.402522ms)","trace[725243111] 'applied index is now lower than readState.Index' (duration: 103.003µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:10.061851Z","caller":"traceutil/trace.go:171","msg":"trace[425814291] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"155.366265ms","start":"2026-10-09T16:22:09.906448Z","end":"2026-10-09T16:22:10.061814Z","steps":["trace[425814291] 'process raft request' (duration: 155.102777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:10.061919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.71984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:22:10.062151Z","caller":"traceutil/trace.go:171","msg":"trace[700081167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26340; }","duration":"148.984018ms","start":"2026-10-09T16:22:09.913143Z","end":"2026-10-09T16:22:10.062127Z","steps":["trace[700081167] 'agreement among raft nodes before linearized reading' (duration: 148.69269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:10.063353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.211096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-10-09T16:22:10.063408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.428104ms","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-10-09T16:22:10.063427Z","caller":"traceutil/trace.go:171","msg":"trace[1258917902] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26342; }","duration":"140.301129ms","start":"2026-10-09T16:22:09.923105Z","end":"2026-10-09T16:22:10.063407Z","steps":["trace[1258917902] 'agreement among raft nodes before linearized reading' (duration: 140.013391ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:10.063452Z","caller":"traceutil/trace.go:171","msg":"trace[250228087] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:26342; }","duration":"135.505675ms","start":"2026-10-09T16:22:09.927934Z","end":"2026-10-09T16:22:10.06344Z","steps":["trace[250228087] 'agreement among raft nodes before linearized reading' (duration: 135.397892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:10.063485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.012887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-10-09T16:22:10.063556Z","caller":"traceutil/trace.go:171","msg":"trace[1800604048] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26342; }","duration":"103.135321ms","start":"2026-10-09T16:22:09.960404Z","end":"2026-10-09T16:22:10.063539Z","steps":["trace[1800604048] 'agreement among raft nodes before linearized reading' (duration: 102.792672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:10.063808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.942911ms","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-10-09T16:22:10.064642Z","caller":"traceutil/trace.go:171","msg":"trace[1751570653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26342; }","duration":"139.020053ms","start":"2026-10-09T16:22:09.92483Z","end":"2026-10-09T16:22:10.06385Z","steps":["trace[1751570653] 'agreement among raft nodes before linearized reading' (duration: 138.593651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:14.17921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.243295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:22:14.179315Z","caller":"traceutil/trace.go:171","msg":"trace[968229443] linearizableReadLoop","detail":"{readStateIndex:27263; appliedIndex:27262; }","duration":"244.18114ms","start":"2026-10-09T16:22:13.935119Z","end":"2026-10-09T16:22:14.1793Z","steps":["trace[968229443] 'read index received' (duration: 111.695938ms)","trace[968229443] 'applied index is now lower than readState.Index' (duration: 132.483482ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:14.179355Z","caller":"traceutil/trace.go:171","msg":"trace[871127854] transaction","detail":"{read_only:false; response_revision:26392; number_of_response:1; }","duration":"332.84257ms","start":"2026-10-09T16:22:13.84648Z","end":"2026-10-09T16:22:14.179322Z","steps":["trace[871127854] 'process raft request' (duration: 200.379479ms)","trace[871127854] 'compare' (duration: 132.06378ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:22:14.17947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:22:13.846456Z","time spent":"332.954344ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:22:14.179483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.361544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:22:14.179513Z","caller":"traceutil/trace.go:171","msg":"trace[297118483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26392; }","duration":"244.427656ms","start":"2026-10-09T16:22:13.935075Z","end":"2026-10-09T16:22:14.179503Z","steps":["trace[297118483] 'agreement among raft nodes before linearized reading' (duration: 244.291072ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:14.204336Z","caller":"traceutil/trace.go:171","msg":"trace[82559032] transaction","detail":"{read_only:false; response_revision:26393; number_of_response:1; }","duration":"127.859164ms","start":"2026-10-09T16:22:14.076451Z","end":"2026-10-09T16:22:14.20431Z","steps":["trace[82559032] 'process raft request' (duration: 127.7037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:14.23681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.430115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:22:14.236909Z","caller":"traceutil/trace.go:171","msg":"trace[2086555687] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26395; }","duration":"262.579509ms","start":"2026-10-09T16:22:13.974303Z","end":"2026-10-09T16:22:14.236883Z","steps":["trace[2086555687] 'agreement among raft nodes before linearized reading' (duration: 262.413694ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:14.237447Z","caller":"traceutil/trace.go:171","msg":"trace[933069392] transaction","detail":"{read_only:false; response_revision:26394; number_of_response:1; }","duration":"160.946949ms","start":"2026-10-09T16:22:14.076481Z","end":"2026-10-09T16:22:14.237428Z","steps":["trace[933069392] 'process raft request' (duration: 159.953291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:14.237906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.893642ms","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-10-09T16:22:14.237956Z","caller":"traceutil/trace.go:171","msg":"trace[28898448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26395; }","duration":"120.978424ms","start":"2026-10-09T16:22:14.116962Z","end":"2026-10-09T16:22:14.237941Z","steps":["trace[28898448] 'agreement among raft nodes before linearized reading' (duration: 120.82633ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:14.355811Z","caller":"traceutil/trace.go:171","msg":"trace[842719534] transaction","detail":"{read_only:false; response_revision:26396; number_of_response:1; }","duration":"111.642495ms","start":"2026-10-09T16:22:14.244142Z","end":"2026-10-09T16:22:14.355784Z","steps":["trace[842719534] 'process raft request' (duration: 79.198588ms)","trace[842719534] 'compare' (duration: 32.258092ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:22:17.65957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.006171ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-09T16:22:17.724312Z","caller":"traceutil/trace.go:171","msg":"trace[723451679] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"266.924148ms","start":"2026-10-09T16:22:17.457365Z","end":"2026-10-09T16:22:17.724289Z","steps":["trace[723451679] 'process raft request' (duration: 266.719183ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:17.724534Z","caller":"traceutil/trace.go:171","msg":"trace[2037975012] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"265.726575ms","start":"2026-10-09T16:22:17.458787Z","end":"2026-10-09T16:22:17.724514Z","steps":["trace[2037975012] 'process raft request' (duration: 265.455117ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:17.724703Z","caller":"traceutil/trace.go:171","msg":"trace[2071762382] linearizableReadLoop","detail":"{readStateIndex:27295; appliedIndex:27289; }","duration":"255.911005ms","start":"2026-10-09T16:22:17.468745Z","end":"2026-10-09T16:22:17.724656Z","steps":["trace[2071762382] 'read index received' (duration: 25.758483ms)","trace[2071762382] 'applied index is now lower than readState.Index' (duration: 230.151542ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:17.724782Z","caller":"traceutil/trace.go:171","msg":"trace[380034527] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"265.91064ms","start":"2026-10-09T16:22:17.458863Z","end":"2026-10-09T16:22:17.724774Z","steps":["trace[380034527] 'process raft request' (duration: 265.56566ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:17.724826Z","caller":"traceutil/trace.go:171","msg":"trace[1293631481] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"259.998457ms","start":"2026-10-09T16:22:17.464816Z","end":"2026-10-09T16:22:17.724815Z","steps":["trace[1293631481] 'process raft request' (duration: 259.772861ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:17.724748Z","caller":"traceutil/trace.go:171","msg":"trace[414229137] transaction","detail":"{read_only:false; response_revision:26422; number_of_response:1; }","duration":"265.751666ms","start":"2026-10-09T16:22:17.458986Z","end":"2026-10-09T16:22:17.724738Z","steps":["trace[414229137] 'process raft request' (duration: 265.564881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:17.724996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.247223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:22:17.725028Z","caller":"traceutil/trace.go:171","msg":"trace[1373123463] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:26423; }","duration":"256.297915ms","start":"2026-10-09T16:22:17.468722Z","end":"2026-10-09T16:22:17.72502Z","steps":["trace[1373123463] 'agreement among raft nodes before linearized reading' (duration: 256.222203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:17.72506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.047196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:22:17.725102Z","caller":"traceutil/trace.go:171","msg":"trace[1743674270] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:26423; }","duration":"197.115169ms","start":"2026-10-09T16:22:17.527977Z","end":"2026-10-09T16:22:17.725092Z","steps":["trace[1743674270] 'agreement among raft nodes before linearized reading' (duration: 197.049607ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:19.303626Z","caller":"traceutil/trace.go:171","msg":"trace[1069452257] linearizableReadLoop","detail":"{readStateIndex:27303; appliedIndex:27302; }","duration":"125.88747ms","start":"2026-10-09T16:22:19.177711Z","end":"2026-10-09T16:22:19.303598Z","steps":["trace[1069452257] 'read index received' (duration: 125.634263ms)","trace[1069452257] 'applied index is now lower than readState.Index' (duration: 251.637µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:19.303749Z","caller":"traceutil/trace.go:171","msg":"trace[1842297346] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"133.612893ms","start":"2026-10-09T16:22:19.170107Z","end":"2026-10-09T16:22:19.30372Z","steps":["trace[1842297346] 'process raft request' (duration: 133.319195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:19.303958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.202109ms","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-10-09T16:22:19.304027Z","caller":"traceutil/trace.go:171","msg":"trace[523775701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26431; }","duration":"126.332131ms","start":"2026-10-09T16:22:19.177677Z","end":"2026-10-09T16:22:19.304009Z","steps":["trace[523775701] 'agreement among raft nodes before linearized reading' (duration: 126.090195ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:37.56449Z","caller":"traceutil/trace.go:171","msg":"trace[1344981133] transaction","detail":"{read_only:false; response_revision:26583; number_of_response:1; }","duration":"166.276946ms","start":"2026-10-09T16:22:37.398185Z","end":"2026-10-09T16:22:37.564462Z","steps":["trace[1344981133] 'process raft request' (duration: 166.07991ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:37.651568Z","caller":"traceutil/trace.go:171","msg":"trace[1656927054] transaction","detail":"{read_only:false; response_revision:26584; number_of_response:1; }","duration":"251.143212ms","start":"2026-10-09T16:22:37.400399Z","end":"2026-10-09T16:22:37.651543Z","steps":["trace[1656927054] 'process raft request' (duration: 250.990107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:38.090301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.726053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T16:22:38.090383Z","caller":"traceutil/trace.go:171","msg":"trace[275196352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26589; }","duration":"105.864866ms","start":"2026-10-09T16:22:37.9845Z","end":"2026-10-09T16:22:38.090365Z","steps":["trace[275196352] 'range keys from in-memory index tree' (duration: 105.528347ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:38.601989Z","caller":"traceutil/trace.go:171","msg":"trace[59821134] transaction","detail":"{read_only:false; response_revision:26593; number_of_response:1; }","duration":"206.995162ms","start":"2026-10-09T16:22:38.394975Z","end":"2026-10-09T16:22:38.60197Z","steps":["trace[59821134] 'process raft request' (duration: 206.860328ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:38.607221Z","caller":"traceutil/trace.go:171","msg":"trace[3851104] linearizableReadLoop","detail":"{readStateIndex:27471; appliedIndex:27469; }","duration":"129.751467ms","start":"2026-10-09T16:22:38.477447Z","end":"2026-10-09T16:22:38.607198Z","steps":["trace[3851104] 'read index received' (duration: 124.43557ms)","trace[3851104] 'applied index is now lower than readState.Index' (duration: 5.314917ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:38.607327Z","caller":"traceutil/trace.go:171","msg":"trace[259873369] transaction","detail":"{read_only:false; response_revision:26594; number_of_response:1; }","duration":"182.366341ms","start":"2026-10-09T16:22:38.42495Z","end":"2026-10-09T16:22:38.607316Z","steps":["trace[259873369] 'process raft request' (duration: 181.913188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:38.60755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.093406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T16:22:38.607582Z","caller":"traceutil/trace.go:171","msg":"trace[466006464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26594; }","duration":"130.165298ms","start":"2026-10-09T16:22:38.477403Z","end":"2026-10-09T16:22:38.607568Z","steps":["trace[466006464] 'agreement among raft nodes before linearized reading' (duration: 130.042054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:38.60788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.299129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T16:22:38.607912Z","caller":"traceutil/trace.go:171","msg":"trace[142692768] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26594; }","duration":"114.370651ms","start":"2026-10-09T16:22:38.493534Z","end":"2026-10-09T16:22:38.607905Z","steps":["trace[142692768] 'agreement among raft nodes before linearized reading' (duration: 114.112554ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:38.768467Z","caller":"traceutil/trace.go:171","msg":"trace[268147154] linearizableReadLoop","detail":"{readStateIndex:27472; appliedIndex:27471; }","duration":"151.93659ms","start":"2026-10-09T16:22:38.616508Z","end":"2026-10-09T16:22:38.768444Z","steps":["trace[268147154] 'read index received' (duration: 70.828688ms)","trace[268147154] 'applied index is now lower than readState.Index' (duration: 81.106862ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:38.768729Z","caller":"traceutil/trace.go:171","msg":"trace[288711461] transaction","detail":"{read_only:false; response_revision:26595; number_of_response:1; }","duration":"153.41131ms","start":"2026-10-09T16:22:38.615301Z","end":"2026-10-09T16:22:38.768713Z","steps":["trace[288711461] 'process raft request' (duration: 72.171575ms)","trace[288711461] 'compare' (duration: 80.875495ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:22:38.76905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.518416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19150"} {"level":"info","ts":"2026-10-09T16:22:38.769129Z","caller":"traceutil/trace.go:171","msg":"trace[1129283018] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26595; }","duration":"152.636549ms","start":"2026-10-09T16:22:38.616473Z","end":"2026-10-09T16:22:38.76911Z","steps":["trace[1129283018] 'agreement among raft nodes before linearized reading' (duration: 152.395242ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:38.988258Z","caller":"traceutil/trace.go:171","msg":"trace[189500389] transaction","detail":"{read_only:false; response_revision:26596; number_of_response:1; }","duration":"108.735546ms","start":"2026-10-09T16:22:38.879489Z","end":"2026-10-09T16:22:38.988224Z","steps":["trace[189500389] 'process raft request' (duration: 58.327662ms)","trace[189500389] 'compare' (duration: 50.26766ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:39.812275Z","caller":"traceutil/trace.go:171","msg":"trace[2035604416] transaction","detail":"{read_only:false; response_revision:26598; number_of_response:1; }","duration":"152.081414ms","start":"2026-10-09T16:22:39.66016Z","end":"2026-10-09T16:22:39.812241Z","steps":["trace[2035604416] 'process raft request' (duration: 69.399839ms)","trace[2035604416] 'compare' (duration: 82.483759ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:40.033038Z","caller":"traceutil/trace.go:171","msg":"trace[2036298094] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"180.032816ms","start":"2026-10-09T16:22:39.852965Z","end":"2026-10-09T16:22:40.032997Z","steps":["trace[2036298094] 'process raft request' (duration: 86.027088ms)","trace[2036298094] 'compare' (duration: 93.768882ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:40.033168Z","caller":"traceutil/trace.go:171","msg":"trace[777076558] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"133.008997ms","start":"2026-10-09T16:22:39.900149Z","end":"2026-10-09T16:22:40.033158Z","steps":["trace[777076558] 'process raft request' (duration: 132.849912ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:40.033093Z","caller":"traceutil/trace.go:171","msg":"trace[377949062] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"132.835132ms","start":"2026-10-09T16:22:39.900234Z","end":"2026-10-09T16:22:40.033069Z","steps":["trace[377949062] 'process raft request' (duration: 132.799541ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:40.033069Z","caller":"traceutil/trace.go:171","msg":"trace[1564807991] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"179.567034ms","start":"2026-10-09T16:22:39.853451Z","end":"2026-10-09T16:22:40.033018Z","steps":["trace[1564807991] 'process raft request' (duration: 179.460741ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:40.033151Z","caller":"traceutil/trace.go:171","msg":"trace[313675349] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"176.90161ms","start":"2026-10-09T16:22:39.856242Z","end":"2026-10-09T16:22:40.033143Z","steps":["trace[313675349] 'process raft request' (duration: 176.722545ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:40.584447Z","caller":"traceutil/trace.go:171","msg":"trace[290430934] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"158.668226ms","start":"2026-10-09T16:22:40.425731Z","end":"2026-10-09T16:22:40.584399Z","steps":["trace[290430934] 'process raft request' (duration: 95.040847ms)","trace[290430934] 'compare' (duration: 63.355441ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:40.584791Z","caller":"traceutil/trace.go:171","msg":"trace[1895486496] linearizableReadLoop","detail":"{readStateIndex:27483; appliedIndex:27482; }","duration":"125.692045ms","start":"2026-10-09T16:22:40.45907Z","end":"2026-10-09T16:22:40.584762Z","steps":["trace[1895486496] 'read index received' (duration: 61.686815ms)","trace[1895486496] 'applied index is now lower than readState.Index' (duration: 64.00313ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:22:40.585089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.838338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:22:40.585148Z","caller":"traceutil/trace.go:171","msg":"trace[1779089711] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26606; }","duration":"126.080555ms","start":"2026-10-09T16:22:40.459054Z","end":"2026-10-09T16:22:40.585134Z","steps":["trace[1779089711] 'agreement among raft nodes before linearized reading' (duration: 125.808268ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:51.9027Z","caller":"traceutil/trace.go:171","msg":"trace[1378376344] transaction","detail":"{read_only:false; response_revision:26685; number_of_response:1; }","duration":"172.336823ms","start":"2026-10-09T16:22:51.730314Z","end":"2026-10-09T16:22:51.902651Z","steps":["trace[1378376344] 'process raft request' (duration: 172.121458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:52.241171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.901116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:22:52.24142Z","caller":"traceutil/trace.go:171","msg":"trace[1209192934] transaction","detail":"{read_only:false; response_revision:26687; number_of_response:1; }","duration":"164.275481ms","start":"2026-10-09T16:22:52.077Z","end":"2026-10-09T16:22:52.241275Z","steps":["trace[1209192934] 'process raft request' (duration: 32.166169ms)","trace[1209192934] 'compare' (duration: 131.737851ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:52.283666Z","caller":"traceutil/trace.go:171","msg":"trace[965006570] transaction","detail":"{read_only:false; response_revision:26688; number_of_response:1; }","duration":"203.095714ms","start":"2026-10-09T16:22:52.080551Z","end":"2026-10-09T16:22:52.283647Z","steps":["trace[965006570] 'process raft request' (duration: 202.859427ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:52.283966Z","caller":"traceutil/trace.go:171","msg":"trace[963239148] transaction","detail":"{read_only:false; response_revision:26689; number_of_response:1; }","duration":"195.080532ms","start":"2026-10-09T16:22:52.08885Z","end":"2026-10-09T16:22:52.28393Z","steps":["trace[963239148] 'process raft request' (duration: 194.703071ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:52.284201Z","caller":"traceutil/trace.go:171","msg":"trace[1878274513] linearizableReadLoop","detail":"{readStateIndex:27572; appliedIndex:27567; }","duration":"151.658062ms","start":"2026-10-09T16:22:52.13253Z","end":"2026-10-09T16:22:52.284188Z","steps":["trace[1878274513] 'read index received' (duration: 150.950832ms)","trace[1878274513] 'applied index is now lower than readState.Index' (duration: 706.32µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:52.284342Z","caller":"traceutil/trace.go:171","msg":"trace[589771852] transaction","detail":"{read_only:false; response_revision:26691; number_of_response:1; }","duration":"195.474193ms","start":"2026-10-09T16:22:52.088856Z","end":"2026-10-09T16:22:52.28433Z","steps":["trace[589771852] 'process raft request' (duration: 195.243157ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:52.2844Z","caller":"traceutil/trace.go:171","msg":"trace[1058592971] transaction","detail":"{read_only:false; response_revision:26692; number_of_response:1; }","duration":"195.197506ms","start":"2026-10-09T16:22:52.089191Z","end":"2026-10-09T16:22:52.284388Z","steps":["trace[1058592971] 'process raft request' (duration: 194.962499ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:52.284472Z","caller":"traceutil/trace.go:171","msg":"trace[165772024] transaction","detail":"{read_only:false; response_revision:26690; number_of_response:1; }","duration":"195.608037ms","start":"2026-10-09T16:22:52.088854Z","end":"2026-10-09T16:22:52.284462Z","steps":["trace[165772024] 'process raft request' (duration: 194.739153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:52.284567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.033343ms","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-10-09T16:22:52.284602Z","caller":"traceutil/trace.go:171","msg":"trace[1260379087] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:26692; }","duration":"152.123535ms","start":"2026-10-09T16:22:52.132465Z","end":"2026-10-09T16:22:52.284589Z","steps":["trace[1260379087] 'agreement among raft nodes before linearized reading' (duration: 152.054793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:52.483578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.423351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T16:22:52.483709Z","caller":"traceutil/trace.go:171","msg":"trace[1663353919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26693; }","duration":"111.553734ms","start":"2026-10-09T16:22:52.372088Z","end":"2026-10-09T16:22:52.483642Z","steps":["trace[1663353919] 'range keys from in-memory index tree' (duration: 111.226734ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:52.739362Z","caller":"traceutil/trace.go:171","msg":"trace[284305640] linearizableReadLoop","detail":"{readStateIndex:27576; appliedIndex:27575; }","duration":"103.519331ms","start":"2026-10-09T16:22:52.63581Z","end":"2026-10-09T16:22:52.73933Z","steps":["trace[284305640] 'read index received' (duration: 103.251844ms)","trace[284305640] 'applied index is now lower than readState.Index' (duration: 265.607µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:52.739539Z","caller":"traceutil/trace.go:171","msg":"trace[1169182901] transaction","detail":"{read_only:false; response_revision:26696; number_of_response:1; }","duration":"110.69827ms","start":"2026-10-09T16:22:52.628825Z","end":"2026-10-09T16:22:52.739523Z","steps":["trace[1169182901] 'process raft request' (duration: 110.225187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:52.739711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.856191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:22:52.739807Z","caller":"traceutil/trace.go:171","msg":"trace[1843822332] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:26696; }","duration":"103.987045ms","start":"2026-10-09T16:22:52.635796Z","end":"2026-10-09T16:22:52.739783Z","steps":["trace[1843822332] 'agreement among raft nodes before linearized reading' (duration: 103.80604ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:56.724216Z","caller":"traceutil/trace.go:171","msg":"trace[600458808] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"133.655994ms","start":"2026-10-09T16:22:56.59053Z","end":"2026-10-09T16:22:56.724186Z","steps":["trace[600458808] 'process raft request' (duration: 126.562468ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:22:56.892613Z","caller":"traceutil/trace.go:171","msg":"trace[340239333] linearizableReadLoop","detail":"{readStateIndex:27602; appliedIndex:27601; }","duration":"129.149881ms","start":"2026-10-09T16:22:56.763428Z","end":"2026-10-09T16:22:56.892578Z","steps":["trace[340239333] 'read index received' (duration: 62.882588ms)","trace[340239333] 'applied index is now lower than readState.Index' (duration: 66.261092ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:22:56.892712Z","caller":"traceutil/trace.go:171","msg":"trace[2057800056] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"130.256441ms","start":"2026-10-09T16:22:56.762393Z","end":"2026-10-09T16:22:56.89265Z","steps":["trace[2057800056] 'process raft request' (duration: 63.935467ms)","trace[2057800056] 'compare' (duration: 66.039706ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:22:56.892928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.462549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:22:56.89303Z","caller":"traceutil/trace.go:171","msg":"trace[1672629158] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:26722; }","duration":"129.603153ms","start":"2026-10-09T16:22:56.763393Z","end":"2026-10-09T16:22:56.892996Z","steps":["trace[1672629158] 'agreement among raft nodes before linearized reading' (duration: 129.385797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:22:56.893078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.947723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:22:56.893138Z","caller":"traceutil/trace.go:171","msg":"trace[1846639514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26722; }","duration":"117.152509ms","start":"2026-10-09T16:22:56.775967Z","end":"2026-10-09T16:22:56.893119Z","steps":["trace[1846639514] 'agreement among raft nodes before linearized reading' (duration: 116.909162ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:02.387567Z","caller":"traceutil/trace.go:171","msg":"trace[1509726562] transaction","detail":"{read_only:false; response_revision:26771; number_of_response:1; }","duration":"342.636012ms","start":"2026-10-09T16:23:02.044901Z","end":"2026-10-09T16:23:02.387537Z","steps":["trace[1509726562] 'process raft request' (duration: 265.214372ms)","trace[1509726562] 'compare' (duration: 77.266256ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:23:02.387727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:23:02.044886Z","time spent":"342.753735ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:23:02.387573Z","caller":"traceutil/trace.go:171","msg":"trace[1869224069] linearizableReadLoop","detail":"{readStateIndex:27653; appliedIndex:27652; }","duration":"341.757287ms","start":"2026-10-09T16:23:02.045792Z","end":"2026-10-09T16:23:02.387549Z","steps":["trace[1869224069] 'read index received' (duration: 264.354598ms)","trace[1869224069] 'applied index is now lower than readState.Index' (duration: 77.401899ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:23:02.387969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.153028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-09T16:23:02.388044Z","caller":"traceutil/trace.go:171","msg":"trace[424281039] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26772; }","duration":"342.264382ms","start":"2026-10-09T16:23:02.045769Z","end":"2026-10-09T16:23:02.388033Z","steps":["trace[424281039] 'agreement among raft nodes before linearized reading' (duration: 341.857221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:23:02.388121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:23:02.045759Z","time spent":"342.348064ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-10-09T16:23:02.447667Z","caller":"traceutil/trace.go:171","msg":"trace[484672556] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"128.938285ms","start":"2026-10-09T16:23:02.318704Z","end":"2026-10-09T16:23:02.447642Z","steps":["trace[484672556] 'process raft request' (duration: 128.706988ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:02.448104Z","caller":"traceutil/trace.go:171","msg":"trace[706289940] transaction","detail":"{read_only:false; response_revision:26776; number_of_response:1; }","duration":"120.495861ms","start":"2026-10-09T16:23:02.327584Z","end":"2026-10-09T16:23:02.448079Z","steps":["trace[706289940] 'process raft request' (duration: 120.416149ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:02.448193Z","caller":"traceutil/trace.go:171","msg":"trace[18116198] transaction","detail":"{read_only:false; response_revision:26777; number_of_response:1; }","duration":"117.664673ms","start":"2026-10-09T16:23:02.33051Z","end":"2026-10-09T16:23:02.448175Z","steps":["trace[18116198] 'process raft request' (duration: 117.530309ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:02.448146Z","caller":"traceutil/trace.go:171","msg":"trace[730591651] transaction","detail":"{read_only:false; response_revision:26775; number_of_response:1; }","duration":"124.749859ms","start":"2026-10-09T16:23:02.323328Z","end":"2026-10-09T16:23:02.448078Z","steps":["trace[730591651] 'process raft request' (duration: 124.543133ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:02.448627Z","caller":"traceutil/trace.go:171","msg":"trace[1148445314] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"128.361658ms","start":"2026-10-09T16:23:02.319662Z","end":"2026-10-09T16:23:02.448024Z","steps":["trace[1148445314] 'process raft request' (duration: 127.923946ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:02.810109Z","caller":"traceutil/trace.go:171","msg":"trace[1311929130] linearizableReadLoop","detail":"{readStateIndex:27660; appliedIndex:27659; }","duration":"101.830095ms","start":"2026-10-09T16:23:02.708241Z","end":"2026-10-09T16:23:02.810072Z","steps":["trace[1311929130] 'read index received' (duration: 101.552407ms)","trace[1311929130] 'applied index is now lower than readState.Index' (duration: 275.968µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:23:02.810615Z","caller":"traceutil/trace.go:171","msg":"trace[951875881] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"132.272766ms","start":"2026-10-09T16:23:02.678291Z","end":"2026-10-09T16:23:02.810564Z","steps":["trace[951875881] 'process raft request' (duration: 131.529776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:23:02.81088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.537144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-10-09T16:23:02.810999Z","caller":"traceutil/trace.go:171","msg":"trace[1793731422] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26778; }","duration":"102.782571ms","start":"2026-10-09T16:23:02.708187Z","end":"2026-10-09T16:23:02.810969Z","steps":["trace[1793731422] 'agreement among raft nodes before linearized reading' (duration: 102.295697ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:02.977311Z","caller":"traceutil/trace.go:171","msg":"trace[575955136] linearizableReadLoop","detail":"{readStateIndex:27661; appliedIndex:27660; }","duration":"111.361908ms","start":"2026-10-09T16:23:02.865911Z","end":"2026-10-09T16:23:02.977273Z","steps":["trace[575955136] 'read index received' (duration: 35.025288ms)","trace[575955136] 'applied index is now lower than readState.Index' (duration: 76.33489ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:23:02.97747Z","caller":"traceutil/trace.go:171","msg":"trace[1860459355] transaction","detail":"{read_only:false; response_revision:26779; number_of_response:1; }","duration":"161.146345ms","start":"2026-10-09T16:23:02.816121Z","end":"2026-10-09T16:23:02.977267Z","steps":["trace[1860459355] 'process raft request' (duration: 84.942718ms)","trace[1860459355] 'compare' (duration: 75.863397ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:23:02.979355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.407235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:23:02.979468Z","caller":"traceutil/trace.go:171","msg":"trace[1385011477] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26780; }","duration":"113.58994ms","start":"2026-10-09T16:23:02.865852Z","end":"2026-10-09T16:23:02.979442Z","steps":["trace[1385011477] 'agreement among raft nodes before linearized reading' (duration: 111.515423ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:03.351466Z","caller":"traceutil/trace.go:171","msg":"trace[1201350606] transaction","detail":"{read_only:false; response_revision:26781; number_of_response:1; }","duration":"362.611764ms","start":"2026-10-09T16:23:02.988826Z","end":"2026-10-09T16:23:03.351438Z","steps":["trace[1201350606] 'process raft request' (duration: 362.351076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:23:03.351627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:23:02.988796Z","time spent":"362.743167ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:23:03.4395Z","caller":"traceutil/trace.go:171","msg":"trace[1661149370] transaction","detail":"{read_only:false; response_revision:26782; number_of_response:1; }","duration":"450.222226ms","start":"2026-10-09T16:23:02.989241Z","end":"2026-10-09T16:23:03.439463Z","steps":["trace[1661149370] 'process raft request' (duration: 414.102948ms)","trace[1661149370] 'compare' (duration: 35.882481ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:23:03.439527Z","caller":"traceutil/trace.go:171","msg":"trace[282527264] transaction","detail":"{read_only:false; response_revision:26783; number_of_response:1; }","duration":"287.609851ms","start":"2026-10-09T16:23:03.151874Z","end":"2026-10-09T16:23:03.439484Z","steps":["trace[282527264] 'process raft request' (duration: 287.480207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:23:03.439638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.530281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T16:23:03.4397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:23:02.98921Z","time spent":"450.3744ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:23:03.439716Z","caller":"traceutil/trace.go:171","msg":"trace[430033390] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:26783; }","duration":"422.686865ms","start":"2026-10-09T16:23:03.017017Z","end":"2026-10-09T16:23:03.439703Z","steps":["trace[430033390] 'agreement among raft nodes before linearized reading' (duration: 422.52526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:23:03.439756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:23:03.016988Z","time spent":"422.757937ms","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":32,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-10-09T16:23:03.439494Z","caller":"traceutil/trace.go:171","msg":"trace[1029791907] linearizableReadLoop","detail":"{readStateIndex:27664; appliedIndex:27662; }","duration":"422.346606ms","start":"2026-10-09T16:23:03.0171Z","end":"2026-10-09T16:23:03.439447Z","steps":["trace[1029791907] 'read index received' (duration: 334.074345ms)","trace[1029791907] 'applied index is now lower than readState.Index' (duration: 88.27086ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:23:03.683855Z","caller":"traceutil/trace.go:171","msg":"trace[1574702558] linearizableReadLoop","detail":"{readStateIndex:27666; appliedIndex:27665; }","duration":"126.325502ms","start":"2026-10-09T16:23:03.557494Z","end":"2026-10-09T16:23:03.683819Z","steps":["trace[1574702558] 'read index received' (duration: 50.436864ms)","trace[1574702558] 'applied index is now lower than readState.Index' (duration: 75.886678ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:23:03.684084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.550928ms","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-10-09T16:23:03.684151Z","caller":"traceutil/trace.go:171","msg":"trace[1479032783] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26784; }","duration":"126.675052ms","start":"2026-10-09T16:23:03.557454Z","end":"2026-10-09T16:23:03.684129Z","steps":["trace[1479032783] 'agreement among raft nodes before linearized reading' (duration: 126.500617ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:23:03.684256Z","caller":"traceutil/trace.go:171","msg":"trace[1568412684] transaction","detail":"{read_only:false; response_revision:26784; number_of_response:1; }","duration":"294.981084ms","start":"2026-10-09T16:23:03.389233Z","end":"2026-10-09T16:23:03.684214Z","steps":["trace[1568412684] 'process raft request' (duration: 218.690525ms)","trace[1568412684] 'compare' (duration: 75.632711ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:23:03.978477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.553464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:23:03.978549Z","caller":"traceutil/trace.go:171","msg":"trace[1762065363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26784; }","duration":"149.667587ms","start":"2026-10-09T16:23:03.828863Z","end":"2026-10-09T16:23:03.978531Z","steps":["trace[1762065363] 'range keys from in-memory index tree' (duration: 149.478062ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:24:28.444162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24803} {"level":"info","ts":"2026-10-09T16:24:28.610336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24803,"took":"162.081421ms","hash":2187085086,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":36564992,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-09T16:24:28.610409Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2187085086,"revision":24803,"compact-revision":21565} {"level":"info","ts":"2026-10-09T16:26:48.552993Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T16:26:48.562019Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T16:26:48.56215Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T16:29:28.451447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27631} {"level":"info","ts":"2026-10-09T16:29:28.585323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27631,"took":"129.462899ms","hash":1981506554,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":36802560,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-09T16:29:28.585394Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1981506554,"revision":27631,"compact-revision":24803} {"level":"info","ts":"2026-10-09T16:34:28.460053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30289} {"level":"info","ts":"2026-10-09T16:34:28.627233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30289,"took":"150.307213ms","hash":3962969476,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T16:34:28.627329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3962969476,"revision":30289,"compact-revision":27631} {"level":"info","ts":"2026-10-09T16:39:28.466666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32542} {"level":"info","ts":"2026-10-09T16:39:28.628524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32542,"took":"156.854284ms","hash":477626662,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T16:39:28.628609Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":477626662,"revision":32542,"compact-revision":30289} {"level":"info","ts":"2026-10-09T16:44:28.475693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34771} {"level":"info","ts":"2026-10-09T16:44:28.622099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34771,"took":"141.413228ms","hash":3837643324,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":31203328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T16:44:28.622182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3837643324,"revision":34771,"compact-revision":32542} {"level":"info","ts":"2026-10-09T16:48:12.816972Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T16:48:12.827378Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T16:48:12.827539Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T16:49:28.483482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37067} {"level":"info","ts":"2026-10-09T16:49:28.621595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37067,"took":"133.376183ms","hash":2863034868,"current-db-size-bytes":60727296,"current-db-size":"61 MB","current-db-size-in-use-bytes":32153600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T16:49:28.621664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2863034868,"revision":37067,"compact-revision":34771} {"level":"info","ts":"2026-10-09T16:54:28.493559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39339} {"level":"info","ts":"2026-10-09T16:54:28.635354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39339,"took":"137.485436ms","hash":2300273328,"current-db-size-bytes":62431232,"current-db-size":"62 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T16:54:28.63543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2300273328,"revision":39339,"compact-revision":37067} {"level":"info","ts":"2026-10-09T16:59:28.501166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41576} {"level":"info","ts":"2026-10-09T16:59:28.626149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41576,"took":"120.874308ms","hash":495366390,"current-db-size-bytes":62431232,"current-db-size":"62 MB","current-db-size-in-use-bytes":29827072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T16:59:28.626213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":495366390,"revision":41576,"compact-revision":39339}