{"level":"warn","ts":"2026-10-16T00:04:03.902508Z","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-16T00:04:03.902658Z","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-16T00:04:03.902787Z","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-16T00:04:03.902809Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-10-16T00:04:03.902837Z","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-16T00:04:03.904008Z","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-16T00:04:03.904117Z","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-16T00:04:03.90965Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.134492ms"} {"level":"info","ts":"2026-10-16T00:04:03.923983Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-10-16T00:04:03.92412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:04:03.92415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-10-16T00:04:03.924171Z","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-16T00:04:03.924181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-10-16T00:04:03.924238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-10-16T00:04:03.93262Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:04:03.934312Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:04:03.935921Z","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-16T00:04:03.93892Z","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-16T00:04:03.939184Z","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-16T00:04:03.93928Z","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-16T00:04:03.93944Z","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-16T00:04:03.939468Z","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-16T00:04:03.941196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-10-16T00:04:03.9419Z","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-16T00:04:03.944049Z","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-16T00:04:03.944207Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-10-16T00:04:03.944323Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-10-16T00:04:03.944381Z","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-16T00:04:03.944449Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:04:04.325523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:04:04.325601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:04:04.325688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-10-16T00:04:04.325732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:04:04.325783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-10-16T00:04:04.325825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-10-16T00:04:04.325847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-10-16T00:04:04.327202Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:04:04.327671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:04:04.327677Z","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-16T00:04:04.32771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:04:04.328057Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:04:04.328151Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:04:04.328473Z","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-16T00:04:04.328579Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:04:04.328615Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:04:04.330937Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-10-16T00:04:04.332363Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:04:20.458102Z","caller":"traceutil/trace.go:171","msg":"trace[1776326436] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"101.021139ms","start":"2026-10-16T00:04:20.357053Z","end":"2026-10-16T00:04:20.458074Z","steps":["trace[1776326436] 'read index received' (duration: 100.892986ms)","trace[1776326436] 'applied index is now lower than readState.Index' (duration: 126.733µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:04:20.458178Z","caller":"traceutil/trace.go:171","msg":"trace[1872977974] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"120.050139ms","start":"2026-10-16T00:04:20.338087Z","end":"2026-10-16T00:04:20.458137Z","steps":["trace[1872977974] 'process raft request' (duration: 119.835134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:04:20.458267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.193083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T00:04:20.458343Z","caller":"traceutil/trace.go:171","msg":"trace[1956016061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"101.317917ms","start":"2026-10-16T00:04:20.357014Z","end":"2026-10-16T00:04:20.458332Z","steps":["trace[1956016061] 'agreement among raft nodes before linearized reading' (duration: 101.192284ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:05:01.045711Z","caller":"traceutil/trace.go:171","msg":"trace[1840938371] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"361.926155ms","start":"2026-10-16T00:05:00.683759Z","end":"2026-10-16T00:05:01.045685Z","steps":["trace[1840938371] 'process raft request' (duration: 361.777591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:05:01.04639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:05:00.6837Z","time spent":"362.114759ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:05:01.06509Z","caller":"traceutil/trace.go:171","msg":"trace[2085780533] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"375.983537ms","start":"2026-10-16T00:05:00.689085Z","end":"2026-10-16T00:05:01.065069Z","steps":["trace[2085780533] 'process raft request' (duration: 375.843703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:05:01.065186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:05:00.689068Z","time spent":"376.074599ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:05:01.065545Z","caller":"traceutil/trace.go:171","msg":"trace[669955063] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"338.298897ms","start":"2026-10-16T00:05:00.727232Z","end":"2026-10-16T00:05:01.065531Z","steps":["trace[669955063] 'process raft request' (duration: 338.105192ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:05:01.065518Z","caller":"traceutil/trace.go:171","msg":"trace[973118986] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"376.315805ms","start":"2026-10-16T00:05:00.689148Z","end":"2026-10-16T00:05:01.065464Z","steps":["trace[973118986] 'process raft request' (duration: 375.846393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:05:01.065613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:05:00.727219Z","time spent":"338.363278ms","remote":"127.0.0.1:59672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:05:01.065665Z","caller":"traceutil/trace.go:171","msg":"trace[1487299021] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"256.82154ms","start":"2026-10-16T00:05:00.808805Z","end":"2026-10-16T00:05:01.065626Z","steps":["trace[1487299021] 'process raft request' (duration: 256.658206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:05:01.065764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:05:00.689128Z","time spent":"376.49342ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:05:01.621864Z","caller":"traceutil/trace.go:171","msg":"trace[119894508] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"504.460782ms","start":"2026-10-16T00:05:01.117373Z","end":"2026-10-16T00:05:01.621834Z","steps":["trace[119894508] 'process raft request' (duration: 489.461646ms)","trace[119894508] 'compare' (duration: 14.648657ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:05:01.622038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:05:01.117343Z","time spent":"504.601646ms","remote":"127.0.0.1:59504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:05:01.624528Z","caller":"traceutil/trace.go:171","msg":"trace[1385333049] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"150.358199ms","start":"2026-10-16T00:05:01.47414Z","end":"2026-10-16T00:05:01.624498Z","steps":["trace[1385333049] 'process raft request' (duration: 148.740848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:05:45.87313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.405952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:05:45.873267Z","caller":"traceutil/trace.go:171","msg":"trace[1274409898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"220.823573ms","start":"2026-10-16T00:05:45.652415Z","end":"2026-10-16T00:05:45.873239Z","steps":["trace[1274409898] 'range keys from in-memory index tree' (duration: 220.297379ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:05.827594Z","caller":"traceutil/trace.go:171","msg":"trace[1738653527] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"188.528417ms","start":"2026-10-16T00:06:05.639032Z","end":"2026-10-16T00:06:05.82756Z","steps":["trace[1738653527] 'process raft request' (duration: 151.64624ms)","trace[1738653527] 'compare' (duration: 36.61967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:06:05.827763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.474952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:06:05.827561Z","caller":"traceutil/trace.go:171","msg":"trace[789971701] linearizableReadLoop","detail":"{readStateIndex:1528; appliedIndex:1527; }","duration":"175.252116ms","start":"2026-10-16T00:06:05.652234Z","end":"2026-10-16T00:06:05.827486Z","steps":["trace[789971701] 'read index received' (duration: 138.419711ms)","trace[789971701] 'applied index is now lower than readState.Index' (duration: 36.830285ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:06:05.827822Z","caller":"traceutil/trace.go:171","msg":"trace[834515091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1480; }","duration":"175.639546ms","start":"2026-10-16T00:06:05.652166Z","end":"2026-10-16T00:06:05.827805Z","steps":["trace[834515091] 'agreement among raft nodes before linearized reading' (duration: 175.484162ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:33.885309Z","caller":"traceutil/trace.go:171","msg":"trace[602179372] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"307.992883ms","start":"2026-10-16T00:06:33.577221Z","end":"2026-10-16T00:06:33.885214Z","steps":["trace[602179372] 'process raft request' (duration: 306.410953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:33.885692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:06:33.577179Z","time spent":"308.319792ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:06:33.885761Z","caller":"traceutil/trace.go:171","msg":"trace[1469574993] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"293.661125ms","start":"2026-10-16T00:06:33.592051Z","end":"2026-10-16T00:06:33.885712Z","steps":["trace[1469574993] 'read index received' (duration: 291.651024ms)","trace[1469574993] 'applied index is now lower than readState.Index' (duration: 2.008751ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:06:33.886034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.958624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46f39698-ff12-4917-8172-9c2bf8bebf7e\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-16T00:06:33.886094Z","caller":"traceutil/trace.go:171","msg":"trace[1443234120] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-46f39698-ff12-4917-8172-9c2bf8bebf7e; range_end:; response_count:1; response_revision:1915; }","duration":"294.060996ms","start":"2026-10-16T00:06:33.592017Z","end":"2026-10-16T00:06:33.886078Z","steps":["trace[1443234120] 'agreement among raft nodes before linearized reading' (duration: 293.83373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:33.892401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.249075ms","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-16T00:06:33.892514Z","caller":"traceutil/trace.go:171","msg":"trace[1070774947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1916; }","duration":"277.424709ms","start":"2026-10-16T00:06:33.615066Z","end":"2026-10-16T00:06:33.89249Z","steps":["trace[1070774947] 'agreement among raft nodes before linearized reading' (duration: 277.07771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:33.892531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.575792ms","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-16T00:06:33.892589Z","caller":"traceutil/trace.go:171","msg":"trace[2103950658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1916; }","duration":"185.664735ms","start":"2026-10-16T00:06:33.706909Z","end":"2026-10-16T00:06:33.892573Z","steps":["trace[2103950658] 'agreement among raft nodes before linearized reading' (duration: 185.50667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:33.89293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.071345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-16T00:06:33.892891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.06802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:06:33.892994Z","caller":"traceutil/trace.go:171","msg":"trace[476354955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1916; }","duration":"262.193208ms","start":"2026-10-16T00:06:33.630787Z","end":"2026-10-16T00:06:33.892981Z","steps":["trace[476354955] 'agreement among raft nodes before linearized reading' (duration: 262.048584ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:33.893031Z","caller":"traceutil/trace.go:171","msg":"trace[735395964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1916; }","duration":"240.286296ms","start":"2026-10-16T00:06:33.652709Z","end":"2026-10-16T00:06:33.892995Z","steps":["trace[735395964] 'agreement among raft nodes before linearized reading' (duration: 240.016119ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:33.892683Z","caller":"traceutil/trace.go:171","msg":"trace[1083673069] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"249.960264ms","start":"2026-10-16T00:06:33.642651Z","end":"2026-10-16T00:06:33.892611Z","steps":["trace[1083673069] 'process raft request' (duration: 249.276757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:33.89344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.904515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-16T00:06:33.894339Z","caller":"traceutil/trace.go:171","msg":"trace[1816605527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1916; }","duration":"231.030049ms","start":"2026-10-16T00:06:33.662463Z","end":"2026-10-16T00:06:33.893493Z","steps":["trace[1816605527] 'agreement among raft nodes before linearized reading' (duration: 230.337191ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:34.02739Z","caller":"traceutil/trace.go:171","msg":"trace[927666173] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"129.833292ms","start":"2026-10-16T00:06:33.897507Z","end":"2026-10-16T00:06:34.027341Z","steps":["trace[927666173] 'process raft request' (duration: 30.709368ms)","trace[927666173] 'compare' (duration: 98.95867ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:06:34.095241Z","caller":"traceutil/trace.go:171","msg":"trace[1335847715] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"195.080626ms","start":"2026-10-16T00:06:33.900131Z","end":"2026-10-16T00:06:34.095212Z","steps":["trace[1335847715] 'process raft request' (duration: 195.042815ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:34.095504Z","caller":"traceutil/trace.go:171","msg":"trace[1530612229] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"197.028966ms","start":"2026-10-16T00:06:33.898416Z","end":"2026-10-16T00:06:34.095445Z","steps":["trace[1530612229] 'process raft request' (duration: 196.535764ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:34.095593Z","caller":"traceutil/trace.go:171","msg":"trace[552483066] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"196.78328ms","start":"2026-10-16T00:06:33.898727Z","end":"2026-10-16T00:06:34.09551Z","steps":["trace[552483066] 'process raft request' (duration: 196.365099ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:34.095616Z","caller":"traceutil/trace.go:171","msg":"trace[1821542407] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"196.489602ms","start":"2026-10-16T00:06:33.899091Z","end":"2026-10-16T00:06:34.09558Z","steps":["trace[1821542407] 'process raft request' (duration: 196.04964ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:34.096289Z","caller":"traceutil/trace.go:171","msg":"trace[1465979587] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"146.850768ms","start":"2026-10-16T00:06:33.9494Z","end":"2026-10-16T00:06:34.09625Z","steps":["trace[1465979587] 'process raft request' (duration: 146.55919ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:34.322469Z","caller":"traceutil/trace.go:171","msg":"trace[1318996015] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"209.475305ms","start":"2026-10-16T00:06:34.112947Z","end":"2026-10-16T00:06:34.322422Z","steps":["trace[1318996015] 'process raft request' (duration: 174.488357ms)","trace[1318996015] 'compare' (duration: 34.824294ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:06:46.877168Z","caller":"traceutil/trace.go:171","msg":"trace[725848947] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"132.770447ms","start":"2026-10-16T00:06:46.744369Z","end":"2026-10-16T00:06:46.87714Z","steps":["trace[725848947] 'process raft request' (duration: 132.51105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:47.065638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.977714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:06:47.06585Z","caller":"traceutil/trace.go:171","msg":"trace[84964877] linearizableReadLoop","detail":"{readStateIndex:2115; appliedIndex:2114; }","duration":"139.727645ms","start":"2026-10-16T00:06:46.926103Z","end":"2026-10-16T00:06:47.06583Z","steps":["trace[84964877] 'read index received' (duration: 27.223178ms)","trace[84964877] 'applied index is now lower than readState.Index' (duration: 112.503067ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:06:47.065898Z","caller":"traceutil/trace.go:171","msg":"trace[1689055960] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"178.663465ms","start":"2026-10-16T00:06:46.887174Z","end":"2026-10-16T00:06:47.065838Z","steps":["trace[1689055960] 'process raft request' (duration: 66.22026ms)","trace[1689055960] 'compare' (duration: 111.84544ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:06:47.066068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.948251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11978"} {"level":"info","ts":"2026-10-16T00:06:47.066115Z","caller":"traceutil/trace.go:171","msg":"trace[253419359] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2034; }","duration":"140.015513ms","start":"2026-10-16T00:06:46.926087Z","end":"2026-10-16T00:06:47.066103Z","steps":["trace[253419359] 'agreement among raft nodes before linearized reading' (duration: 139.790927ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:38.735686Z","caller":"traceutil/trace.go:171","msg":"trace[1867295255] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"182.596407ms","start":"2026-10-16T00:08:38.553055Z","end":"2026-10-16T00:08:38.735651Z","steps":["trace[1867295255] 'process raft request' (duration: 182.366741ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:38.740928Z","caller":"traceutil/trace.go:171","msg":"trace[1287234704] linearizableReadLoop","detail":"{readStateIndex:2953; appliedIndex:2951; }","duration":"138.430785ms","start":"2026-10-16T00:08:38.602463Z","end":"2026-10-16T00:08:38.740894Z","steps":["trace[1287234704] 'read index received' (duration: 133.115749ms)","trace[1287234704] 'applied index is now lower than readState.Index' (duration: 5.314006ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:38.740981Z","caller":"traceutil/trace.go:171","msg":"trace[1661078339] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"183.240744ms","start":"2026-10-16T00:08:38.557723Z","end":"2026-10-16T00:08:38.740964Z","steps":["trace[1661078339] 'process raft request' (duration: 182.964637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:38.741418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.915928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:08:38.741538Z","caller":"traceutil/trace.go:171","msg":"trace[464445925] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2842; }","duration":"139.102653ms","start":"2026-10-16T00:08:38.602403Z","end":"2026-10-16T00:08:38.741506Z","steps":["trace[464445925] 'agreement among raft nodes before linearized reading' (duration: 138.630461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:39.167621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.598712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:08:39.167803Z","caller":"traceutil/trace.go:171","msg":"trace[1460850412] linearizableReadLoop","detail":"{readStateIndex:2956; appliedIndex:2955; }","duration":"389.901237ms","start":"2026-10-16T00:08:38.777874Z","end":"2026-10-16T00:08:39.167776Z","steps":["trace[1460850412] 'read index received' (duration: 157.998147ms)","trace[1460850412] 'applied index is now lower than readState.Index' (duration: 231.9006ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:39.16791Z","caller":"traceutil/trace.go:171","msg":"trace[1239457516] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"392.062632ms","start":"2026-10-16T00:08:38.775796Z","end":"2026-10-16T00:08:39.167858Z","steps":["trace[1239457516] 'process raft request' (duration: 160.055719ms)","trace[1239457516] 'compare' (duration: 231.466499ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:08:39.168037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:38.775779Z","time spent":"392.193495ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:08:39.168061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.179364ms","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-16T00:08:39.168118Z","caller":"traceutil/trace.go:171","msg":"trace[274911336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2845; }","duration":"390.289576ms","start":"2026-10-16T00:08:38.777813Z","end":"2026-10-16T00:08:39.168102Z","steps":["trace[274911336] 'agreement among raft nodes before linearized reading' (duration: 390.075111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:39.168169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:38.777786Z","time spent":"390.371278ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-16T00:08:39.168466Z","caller":"traceutil/trace.go:171","msg":"trace[165002470] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"387.875484ms","start":"2026-10-16T00:08:38.780574Z","end":"2026-10-16T00:08:39.16845Z","steps":["trace[165002470] 'process raft request' (duration: 387.748691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:39.168551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:38.780555Z","time spent":"387.953386ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:08:39.168639Z","caller":"traceutil/trace.go:171","msg":"trace[796089315] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"260.42644ms","start":"2026-10-16T00:08:38.908174Z","end":"2026-10-16T00:08:39.1686Z","steps":["trace[796089315] 'process raft request' (duration: 260.233045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:39.16928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.781056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:08:39.169343Z","caller":"traceutil/trace.go:171","msg":"trace[422602115] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2847; }","duration":"321.504374ms","start":"2026-10-16T00:08:38.847823Z","end":"2026-10-16T00:08:39.169327Z","steps":["trace[422602115] 'agreement among raft nodes before linearized reading' (duration: 320.727705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:39.169389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:38.847811Z","time spent":"321.557897ms","remote":"127.0.0.1:57804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":19,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:11:19.423209Z","caller":"traceutil/trace.go:171","msg":"trace[1709921557] transaction","detail":"{read_only:false; response_revision:3831; number_of_response:1; }","duration":"106.69226ms","start":"2026-10-16T00:11:19.316495Z","end":"2026-10-16T00:11:19.423187Z","steps":["trace[1709921557] 'process raft request' (duration: 106.640138ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:19.423225Z","caller":"traceutil/trace.go:171","msg":"trace[970580859] transaction","detail":"{read_only:false; response_revision:3830; number_of_response:1; }","duration":"107.415938ms","start":"2026-10-16T00:11:19.315789Z","end":"2026-10-16T00:11:19.423205Z","steps":["trace[970580859] 'process raft request' (duration: 51.7767ms)","trace[970580859] 'compare' (duration: 55.464994ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:11:19.423307Z","caller":"traceutil/trace.go:171","msg":"trace[1540165792] linearizableReadLoop","detail":"{readStateIndex:3980; appliedIndex:3979; }","duration":"107.151261ms","start":"2026-10-16T00:11:19.316104Z","end":"2026-10-16T00:11:19.423256Z","steps":["trace[1540165792] 'read index received' (duration: 51.664407ms)","trace[1540165792] 'applied index is now lower than readState.Index' (duration: 55.480934ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:11:19.423768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.51029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/secretgen-controller/secretgen-controller-sa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:11:19.423833Z","caller":"traceutil/trace.go:171","msg":"trace[1746270352] range","detail":"{range_begin:/registry/serviceaccounts/secretgen-controller/secretgen-controller-sa; range_end:; response_count:0; response_revision:3831; }","duration":"107.753797ms","start":"2026-10-16T00:11:19.31606Z","end":"2026-10-16T00:11:19.423813Z","steps":["trace[1746270352] 'agreement among raft nodes before linearized reading' (duration: 107.4863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:52.442577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.485785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:11:52.442689Z","caller":"traceutil/trace.go:171","msg":"trace[608458240] linearizableReadLoop","detail":"{readStateIndex:4220; appliedIndex:4219; }","duration":"283.725582ms","start":"2026-10-16T00:11:52.158947Z","end":"2026-10-16T00:11:52.442673Z","steps":["trace[608458240] 'read index received' (duration: 177.015202ms)","trace[608458240] 'applied index is now lower than readState.Index' (duration: 106.7089ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:11:52.442771Z","caller":"traceutil/trace.go:171","msg":"trace[1759817223] transaction","detail":"{read_only:false; response_revision:4064; number_of_response:1; }","duration":"296.555329ms","start":"2026-10-16T00:11:52.146164Z","end":"2026-10-16T00:11:52.442719Z","steps":["trace[1759817223] 'process raft request' (duration: 189.845139ms)","trace[1759817223] 'compare' (duration: 106.29123ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:11:52.442897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.81267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4gbb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T00:11:52.442953Z","caller":"traceutil/trace.go:171","msg":"trace[1545697328] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4gbb; range_end:; response_count:1; response_revision:4064; }","duration":"273.887611ms","start":"2026-10-16T00:11:52.169055Z","end":"2026-10-16T00:11:52.442943Z","steps":["trace[1545697328] 'agreement among raft nodes before linearized reading' (duration: 273.724037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:52.442933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.601531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:11:52.443013Z","caller":"traceutil/trace.go:171","msg":"trace[1030828333] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:4064; }","duration":"123.734834ms","start":"2026-10-16T00:11:52.31926Z","end":"2026-10-16T00:11:52.442995Z","steps":["trace[1030828333] 'agreement among raft nodes before linearized reading' (duration: 123.55511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:52.443012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.001279ms","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-16T00:11:52.443195Z","caller":"traceutil/trace.go:171","msg":"trace[106048479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4064; }","duration":"284.209955ms","start":"2026-10-16T00:11:52.158921Z","end":"2026-10-16T00:11:52.443131Z","steps":["trace[106048479] 'agreement among raft nodes before linearized reading' (duration: 283.841796ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.102042Z","caller":"traceutil/trace.go:171","msg":"trace[1769655604] linearizableReadLoop","detail":"{readStateIndex:5312; appliedIndex:5311; }","duration":"134.202939ms","start":"2026-10-16T00:13:19.967817Z","end":"2026-10-16T00:13:20.10202Z","steps":["trace[1769655604] 'read index received' (duration: 134.008705ms)","trace[1769655604] 'applied index is now lower than readState.Index' (duration: 193.484µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:13:20.10216Z","caller":"traceutil/trace.go:171","msg":"trace[3887355] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"136.372715ms","start":"2026-10-16T00:13:19.965759Z","end":"2026-10-16T00:13:20.102132Z","steps":["trace[3887355] 'process raft request' (duration: 136.137509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:20.102454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.557838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-16T00:13:20.10258Z","caller":"traceutil/trace.go:171","msg":"trace[190953403] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5113; }","duration":"134.716953ms","start":"2026-10-16T00:13:19.967785Z","end":"2026-10-16T00:13:20.102502Z","steps":["trace[190953403] 'agreement among raft nodes before linearized reading' (duration: 134.360574ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.231662Z","caller":"traceutil/trace.go:171","msg":"trace[1971882345] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"121.476905ms","start":"2026-10-16T00:13:20.110164Z","end":"2026-10-16T00:13:20.231641Z","steps":["trace[1971882345] 'process raft request' (duration: 120.686575ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.231794Z","caller":"traceutil/trace.go:171","msg":"trace[1601841720] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"118.806318ms","start":"2026-10-16T00:13:20.112962Z","end":"2026-10-16T00:13:20.231769Z","steps":["trace[1601841720] 'process raft request' (duration: 118.733816ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.23176Z","caller":"traceutil/trace.go:171","msg":"trace[1647611617] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"120.138551ms","start":"2026-10-16T00:13:20.111566Z","end":"2026-10-16T00:13:20.231704Z","steps":["trace[1647611617] 'process raft request' (duration: 120.053219ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.231909Z","caller":"traceutil/trace.go:171","msg":"trace[1963314230] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"119.574067ms","start":"2026-10-16T00:13:20.112327Z","end":"2026-10-16T00:13:20.231901Z","steps":["trace[1963314230] 'process raft request' (duration: 119.333181ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.232177Z","caller":"traceutil/trace.go:171","msg":"trace[350213888] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"120.833869ms","start":"2026-10-16T00:13:20.110838Z","end":"2026-10-16T00:13:20.231672Z","steps":["trace[350213888] 'process raft request' (duration: 120.731296ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.240199Z","caller":"traceutil/trace.go:171","msg":"trace[1139248806] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"112.412515ms","start":"2026-10-16T00:13:20.127761Z","end":"2026-10-16T00:13:20.240173Z","steps":["trace[1139248806] 'process raft request' (duration: 112.197719ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.24031Z","caller":"traceutil/trace.go:171","msg":"trace[2082864802] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5313; }","duration":"111.950223ms","start":"2026-10-16T00:13:20.128341Z","end":"2026-10-16T00:13:20.240291Z","steps":["trace[2082864802] 'read index received' (duration: 102.528513ms)","trace[2082864802] 'applied index is now lower than readState.Index' (duration: 9.42054ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:13:20.240404Z","caller":"traceutil/trace.go:171","msg":"trace[1732675071] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"100.895121ms","start":"2026-10-16T00:13:20.139499Z","end":"2026-10-16T00:13:20.240394Z","steps":["trace[1732675071] 'process raft request' (duration: 100.617113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:20.240791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.442865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-16T00:13:20.240788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.279406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-16T00:13:20.240799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.814199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75504"} {"level":"info","ts":"2026-10-16T00:13:20.240837Z","caller":"traceutil/trace.go:171","msg":"trace[1012182517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5121; }","duration":"112.535397ms","start":"2026-10-16T00:13:20.12829Z","end":"2026-10-16T00:13:20.240825Z","steps":["trace[1012182517] 'agreement among raft nodes before linearized reading' (duration: 112.361923ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.240864Z","caller":"traceutil/trace.go:171","msg":"trace[1581494042] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:5121; }","duration":"110.002233ms","start":"2026-10-16T00:13:20.130846Z","end":"2026-10-16T00:13:20.240848Z","steps":["trace[1581494042] 'agreement among raft nodes before linearized reading' (duration: 109.577062ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:20.24086Z","caller":"traceutil/trace.go:171","msg":"trace[1137554732] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5121; }","duration":"102.382779ms","start":"2026-10-16T00:13:20.138455Z","end":"2026-10-16T00:13:20.240838Z","steps":["trace[1137554732] 'agreement among raft nodes before linearized reading' (duration: 102.159493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:25.020174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.427882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:13:25.020383Z","caller":"traceutil/trace.go:171","msg":"trace[1177370141] linearizableReadLoop","detail":"{readStateIndex:5482; appliedIndex:5479; }","duration":"368.595877ms","start":"2026-10-16T00:13:24.651771Z","end":"2026-10-16T00:13:25.020367Z","steps":["trace[1177370141] 'read index received' (duration: 148.119174ms)","trace[1177370141] 'applied index is now lower than readState.Index' (duration: 220.475783ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:13:25.02048Z","caller":"traceutil/trace.go:171","msg":"trace[1898431437] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"375.952265ms","start":"2026-10-16T00:13:24.644518Z","end":"2026-10-16T00:13:25.02047Z","steps":["trace[1898431437] 'process raft request' (duration: 375.76204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:25.020532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:13:24.644504Z","time spent":"376.003857ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:13:25.020769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.987397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:13:25.020876Z","caller":"traceutil/trace.go:171","msg":"trace[366231277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5276; }","duration":"369.149941ms","start":"2026-10-16T00:13:24.651677Z","end":"2026-10-16T00:13:25.020827Z","steps":["trace[366231277] 'agreement among raft nodes before linearized reading' (duration: 368.826103ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:25.020834Z","caller":"traceutil/trace.go:171","msg":"trace[1186400080] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"375.507094ms","start":"2026-10-16T00:13:24.6452Z","end":"2026-10-16T00:13:25.020708Z","steps":["trace[1186400080] 'process raft request' (duration: 375.132685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:25.020954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:13:24.651646Z","time spent":"369.271535ms","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T00:13:25.020981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.506125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b37b8fc-4401-4873-a45a-a5b9f8ea63de\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-10-16T00:13:25.02107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:13:24.645185Z","time spent":"375.778091ms","remote":"127.0.0.1:59798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:13:25.021099Z","caller":"traceutil/trace.go:171","msg":"trace[616480414] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b37b8fc-4401-4873-a45a-a5b9f8ea63de; range_end:; response_count:1; response_revision:5276; }","duration":"323.65854ms","start":"2026-10-16T00:13:24.697417Z","end":"2026-10-16T00:13:25.021076Z","steps":["trace[616480414] 'agreement among raft nodes before linearized reading' (duration: 323.404523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:25.020876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.658538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-2wl6t-tls\" ","response":"range_response_count:1 size:4431"} {"level":"warn","ts":"2026-10-16T00:13:25.021147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:13:24.697406Z","time spent":"323.730451ms","remote":"127.0.0.1:59504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b37b8fc-4401-4873-a45a-a5b9f8ea63de\" "} {"level":"info","ts":"2026-10-16T00:13:25.02116Z","caller":"traceutil/trace.go:171","msg":"trace[132167572] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-2wl6t-tls; range_end:; response_count:1; response_revision:5276; }","duration":"366.963115ms","start":"2026-10-16T00:13:24.654177Z","end":"2026-10-16T00:13:25.02114Z","steps":["trace[132167572] 'agreement among raft nodes before linearized reading' (duration: 366.451892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:25.021208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:13:24.654166Z","time spent":"367.021747ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":4455,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-2wl6t-tls\" "} {"level":"warn","ts":"2026-10-16T00:13:25.021309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.792997ms","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-16T00:13:25.021397Z","caller":"traceutil/trace.go:171","msg":"trace[889518712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5276; }","duration":"102.94376ms","start":"2026-10-16T00:13:24.918431Z","end":"2026-10-16T00:13:25.021375Z","steps":["trace[889518712] 'agreement among raft nodes before linearized reading' (duration: 102.661143ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:25.021955Z","caller":"traceutil/trace.go:171","msg":"trace[1654585585] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"376.702925ms","start":"2026-10-16T00:13:24.644192Z","end":"2026-10-16T00:13:25.020895Z","steps":["trace[1654585585] 'process raft request' (duration: 155.485453ms)","trace[1654585585] 'compare' (duration: 220.291358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:13:25.022059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:13:24.644165Z","time spent":"377.836413ms","remote":"127.0.0.1:59602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1401,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:13:25.021962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.366084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b37b8fc-4401-4873-a45a-a5b9f8ea63de\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-16T00:13:25.023638Z","caller":"traceutil/trace.go:171","msg":"trace[1723409345] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b37b8fc-4401-4873-a45a-a5b9f8ea63de; range_end:; response_count:1; response_revision:5276; }","duration":"329.025796ms","start":"2026-10-16T00:13:24.694577Z","end":"2026-10-16T00:13:25.023603Z","steps":["trace[1723409345] 'agreement among raft nodes before linearized reading' (duration: 326.483771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:25.023696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:13:24.6945Z","time spent":"329.17899ms","remote":"127.0.0.1:59504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b37b8fc-4401-4873-a45a-a5b9f8ea63de\" "} {"level":"info","ts":"2026-10-16T00:13:25.338046Z","caller":"traceutil/trace.go:171","msg":"trace[403850756] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"162.171603ms","start":"2026-10-16T00:13:25.175835Z","end":"2026-10-16T00:13:25.338006Z","steps":["trace[403850756] 'process raft request' (duration: 106.430709ms)","trace[403850756] 'compare' (duration: 55.553529ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:13:39.293102Z","caller":"traceutil/trace.go:171","msg":"trace[1360115250] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"134.295922ms","start":"2026-10-16T00:13:39.158769Z","end":"2026-10-16T00:13:39.293065Z","steps":["trace[1360115250] 'process raft request' (duration: 77.002888ms)","trace[1360115250] 'compare' (duration: 57.015057ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:13:39.293273Z","caller":"traceutil/trace.go:171","msg":"trace[1862850893] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"133.036049ms","start":"2026-10-16T00:13:39.160208Z","end":"2026-10-16T00:13:39.293244Z","steps":["trace[1862850893] 'process raft request' (duration: 132.779673ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:39.968595Z","caller":"traceutil/trace.go:171","msg":"trace[1559909931] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"144.906942ms","start":"2026-10-16T00:13:39.823654Z","end":"2026-10-16T00:13:39.968561Z","steps":["trace[1559909931] 'process raft request' (duration: 144.662536ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:39.97045Z","caller":"traceutil/trace.go:171","msg":"trace[1328168933] transaction","detail":"{read_only:false; response_revision:5545; number_of_response:1; }","duration":"144.347628ms","start":"2026-10-16T00:13:39.82608Z","end":"2026-10-16T00:13:39.970428Z","steps":["trace[1328168933] 'process raft request' (duration: 144.05214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:49.872751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.100629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:13:49.872851Z","caller":"traceutil/trace.go:171","msg":"trace[1366996905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5730; }","duration":"221.324005ms","start":"2026-10-16T00:13:49.651507Z","end":"2026-10-16T00:13:49.872831Z","steps":["trace[1366996905] 'range keys from in-memory index tree' (duration: 220.893673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:49.872843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.736645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:13:49.872897Z","caller":"traceutil/trace.go:171","msg":"trace[915306289] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5730; }","duration":"104.865159ms","start":"2026-10-16T00:13:49.768018Z","end":"2026-10-16T00:13:49.872884Z","steps":["trace[915306289] 'count revisions from in-memory index tree' (duration: 104.609362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:13:49.872812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.942515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-18f9b290-4890-4f92-9493-e2dae1ebe910\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-16T00:13:49.873036Z","caller":"traceutil/trace.go:171","msg":"trace[1468848468] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-18f9b290-4890-4f92-9493-e2dae1ebe910; range_end:; response_count:1; response_revision:5730; }","duration":"205.214663ms","start":"2026-10-16T00:13:49.66779Z","end":"2026-10-16T00:13:49.873005Z","steps":["trace[1468848468] 'range keys from in-memory index tree' (duration: 204.598647ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:50.196819Z","caller":"traceutil/trace.go:171","msg":"trace[36983303] transaction","detail":"{read_only:false; response_revision:5731; number_of_response:1; }","duration":"203.165691ms","start":"2026-10-16T00:13:49.993597Z","end":"2026-10-16T00:13:50.196763Z","steps":["trace[36983303] 'process raft request' (duration: 202.938715ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:50.216466Z","caller":"traceutil/trace.go:171","msg":"trace[1902143691] transaction","detail":"{read_only:false; response_revision:5732; number_of_response:1; }","duration":"207.640075ms","start":"2026-10-16T00:13:50.008801Z","end":"2026-10-16T00:13:50.216441Z","steps":["trace[1902143691] 'process raft request' (duration: 207.45669ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:50.216694Z","caller":"traceutil/trace.go:171","msg":"trace[2071764726] transaction","detail":"{read_only:false; response_revision:5733; number_of_response:1; }","duration":"199.99617ms","start":"2026-10-16T00:13:50.016659Z","end":"2026-10-16T00:13:50.216656Z","steps":["trace[2071764726] 'process raft request' (duration: 199.723403ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:14:04.388306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-10-16T00:14:04.476915Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"86.427369ms","hash":192321477,"current-db-size-bytes":55894016,"current-db-size":"56 MB","current-db-size-in-use-bytes":32460800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:14:04.477006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":192321477,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-10-16T00:19:04.404246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5892} {"level":"info","ts":"2026-10-16T00:19:04.497911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5892,"took":"91.695906ms","hash":3466892894,"current-db-size-bytes":55894016,"current-db-size":"56 MB","current-db-size-in-use-bytes":26705920,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T00:19:04.498001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3466892894,"revision":5892,"compact-revision":2984} {"level":"warn","ts":"2026-10-16T00:19:53.287659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.23244ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-10-16T00:20:04.955074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.254201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-16T00:20:04.955122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.898094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:20:04.955179Z","caller":"traceutil/trace.go:171","msg":"trace[1790094858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8590; }","duration":"253.416075ms","start":"2026-10-16T00:20:04.701741Z","end":"2026-10-16T00:20:04.955157Z","steps":["trace[1790094858] 'range keys from in-memory index tree' (duration: 253.045686ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:04.955198Z","caller":"traceutil/trace.go:171","msg":"trace[1996295119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8590; }","duration":"300.988886ms","start":"2026-10-16T00:20:04.65419Z","end":"2026-10-16T00:20:04.955179Z","steps":["trace[1996295119] 'range keys from in-memory index tree' (duration: 300.786812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:04.955239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:20:04.654106Z","time spent":"301.123341ms","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T00:20:04.956223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.103398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-16T00:20:04.956385Z","caller":"traceutil/trace.go:171","msg":"trace[617806598] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8590; }","duration":"350.328733ms","start":"2026-10-16T00:20:04.606027Z","end":"2026-10-16T00:20:04.956356Z","steps":["trace[617806598] 'range keys from in-memory index tree' (duration: 348.717393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:04.956433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:20:04.605998Z","time spent":"350.421306ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-16T00:20:05.516439Z","caller":"traceutil/trace.go:171","msg":"trace[1987453723] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"163.988358ms","start":"2026-10-16T00:20:05.352425Z","end":"2026-10-16T00:20:05.516413Z","steps":["trace[1987453723] 'process raft request' (duration: 163.633979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:05.930348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.312862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:20:05.930463Z","caller":"traceutil/trace.go:171","msg":"trace[1121158801] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"408.530476ms","start":"2026-10-16T00:20:05.52191Z","end":"2026-10-16T00:20:05.930441Z","steps":["trace[1121158801] 'process raft request' (duration: 272.036549ms)","trace[1121158801] 'compare' (duration: 136.19727ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:20:05.930547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:20:05.521884Z","time spent":"408.631519ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:20:05.97689Z","caller":"traceutil/trace.go:171","msg":"trace[1576546184] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"454.534949ms","start":"2026-10-16T00:20:05.522326Z","end":"2026-10-16T00:20:05.976861Z","steps":["trace[1576546184] 'process raft request' (duration: 454.22001ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:05.976909Z","caller":"traceutil/trace.go:171","msg":"trace[1877071106] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"220.482977ms","start":"2026-10-16T00:20:05.756394Z","end":"2026-10-16T00:20:05.976877Z","steps":["trace[1877071106] 'process raft request' (duration: 220.370154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:05.977047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:20:05.522302Z","time spent":"454.658401ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:20:05.977111Z","caller":"traceutil/trace.go:171","msg":"trace[1646034738] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"219.988004ms","start":"2026-10-16T00:20:05.757084Z","end":"2026-10-16T00:20:05.977072Z","steps":["trace[1646034738] 'process raft request' (duration: 219.731807ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:05.976989Z","caller":"traceutil/trace.go:171","msg":"trace[173549960] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"369.00142ms","start":"2026-10-16T00:20:05.607945Z","end":"2026-10-16T00:20:05.976946Z","steps":["trace[173549960] 'process raft request' (duration: 368.749633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:05.977625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:20:05.607914Z","time spent":"369.585035ms","remote":"127.0.0.1:59592","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-16T00:20:06.002272Z","caller":"traceutil/trace.go:171","msg":"trace[61098377] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"148.386399ms","start":"2026-10-16T00:20:05.853864Z","end":"2026-10-16T00:20:06.00225Z","steps":["trace[61098377] 'process raft request' (duration: 148.197864ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:35.46659Z","caller":"traceutil/trace.go:171","msg":"trace[1843545269] linearizableReadLoop","detail":"{readStateIndex:9125; appliedIndex:9124; }","duration":"315.747612ms","start":"2026-10-16T00:20:35.150803Z","end":"2026-10-16T00:20:35.46655Z","steps":["trace[1843545269] 'read index received' (duration: 315.509986ms)","trace[1843545269] 'applied index is now lower than readState.Index' (duration: 236.176µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:20:35.4668Z","caller":"traceutil/trace.go:171","msg":"trace[1793771719] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"325.265925ms","start":"2026-10-16T00:20:35.14152Z","end":"2026-10-16T00:20:35.466786Z","steps":["trace[1793771719] 'process raft request' (duration: 324.819104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:35.466984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:20:35.141495Z","time spent":"325.346478ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:20:35.467241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.40702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:20:35.467366Z","caller":"traceutil/trace.go:171","msg":"trace[1618714502] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8807; }","duration":"316.579064ms","start":"2026-10-16T00:20:35.150755Z","end":"2026-10-16T00:20:35.467334Z","steps":["trace[1618714502] 'agreement among raft nodes before linearized reading' (duration: 316.263916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:35.467474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:20:35.150736Z","time spent":"316.720108ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-16T00:20:41.646226Z","caller":"traceutil/trace.go:171","msg":"trace[5085676] transaction","detail":"{read_only:false; response_revision:8841; number_of_response:1; }","duration":"146.778249ms","start":"2026-10-16T00:20:41.499431Z","end":"2026-10-16T00:20:41.646209Z","steps":["trace[5085676] 'process raft request' (duration: 146.42778ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:41.646211Z","caller":"traceutil/trace.go:171","msg":"trace[2016779716] linearizableReadLoop","detail":"{readStateIndex:9160; appliedIndex:9159; }","duration":"144.306686ms","start":"2026-10-16T00:20:41.501874Z","end":"2026-10-16T00:20:41.64618Z","steps":["trace[2016779716] 'read index received' (duration: 144.011589ms)","trace[2016779716] 'applied index is now lower than readState.Index' (duration: 293.257µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:20:41.646444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.557672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-16T00:20:41.646499Z","caller":"traceutil/trace.go:171","msg":"trace[1922099502] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8841; }","duration":"144.681055ms","start":"2026-10-16T00:20:41.501806Z","end":"2026-10-16T00:20:41.646487Z","steps":["trace[1922099502] 'agreement among raft nodes before linearized reading' (duration: 144.459229ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:42.259626Z","caller":"traceutil/trace.go:171","msg":"trace[1343358123] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"117.552884ms","start":"2026-10-16T00:20:42.142031Z","end":"2026-10-16T00:20:42.259584Z","steps":["trace[1343358123] 'process raft request' (duration: 117.40771ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:42.259681Z","caller":"traceutil/trace.go:171","msg":"trace[1665729432] transaction","detail":"{read_only:false; response_revision:8848; number_of_response:1; }","duration":"107.320894ms","start":"2026-10-16T00:20:42.152334Z","end":"2026-10-16T00:20:42.259654Z","steps":["trace[1665729432] 'process raft request' (duration: 107.15369ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:42.259851Z","caller":"traceutil/trace.go:171","msg":"trace[357375600] transaction","detail":"{read_only:false; response_revision:8846; number_of_response:1; }","duration":"125.164488ms","start":"2026-10-16T00:20:42.13467Z","end":"2026-10-16T00:20:42.259835Z","steps":["trace[357375600] 'process raft request' (duration: 119.898775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:49.134954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.100682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:20:49.13507Z","caller":"traceutil/trace.go:171","msg":"trace[1666798434] transaction","detail":"{read_only:false; response_revision:8921; number_of_response:1; }","duration":"216.252479ms","start":"2026-10-16T00:20:48.918795Z","end":"2026-10-16T00:20:49.135048Z","steps":["trace[1666798434] 'process raft request' (duration: 111.965852ms)","trace[1666798434] 'compare' (duration: 103.963398ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:20:50.055881Z","caller":"traceutil/trace.go:171","msg":"trace[1452619202] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"294.50338ms","start":"2026-10-16T00:20:49.761355Z","end":"2026-10-16T00:20:50.055858Z","steps":["trace[1452619202] 'process raft request' (duration: 294.339236ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:20:50.081125Z","caller":"traceutil/trace.go:171","msg":"trace[902637870] linearizableReadLoop","detail":"{readStateIndex:9250; appliedIndex:9248; }","duration":"255.766535ms","start":"2026-10-16T00:20:49.825337Z","end":"2026-10-16T00:20:50.081103Z","steps":["trace[902637870] 'read index received' (duration: 230.351768ms)","trace[902637870] 'applied index is now lower than readState.Index' (duration: 25.413887ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:20:50.081233Z","caller":"traceutil/trace.go:171","msg":"trace[150876879] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"292.244694ms","start":"2026-10-16T00:20:49.788977Z","end":"2026-10-16T00:20:50.081222Z","steps":["trace[150876879] 'process raft request' (duration: 292.008899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:50.081449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.543258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4gbb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T00:20:50.08152Z","caller":"traceutil/trace.go:171","msg":"trace[888928155] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4gbb; range_end:; response_count:1; response_revision:8926; }","duration":"240.64516ms","start":"2026-10-16T00:20:49.84086Z","end":"2026-10-16T00:20:50.081505Z","steps":["trace[888928155] 'agreement among raft nodes before linearized reading' (duration: 240.469435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:50.081759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.338859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:20:50.081805Z","caller":"traceutil/trace.go:171","msg":"trace[1443020837] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8926; }","duration":"159.455602ms","start":"2026-10-16T00:20:49.922335Z","end":"2026-10-16T00:20:50.081791Z","steps":["trace[1443020837] 'agreement among raft nodes before linearized reading' (duration: 159.333859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:20:50.081904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.563246ms","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-16T00:20:50.081945Z","caller":"traceutil/trace.go:171","msg":"trace[2104512789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8926; }","duration":"256.626157ms","start":"2026-10-16T00:20:49.825309Z","end":"2026-10-16T00:20:50.081935Z","steps":["trace[2104512789] 'agreement among raft nodes before linearized reading' (duration: 256.528074ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:21:02.351319Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002493.wal"} {"level":"warn","ts":"2026-10-16T00:22:09.159624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.679412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-16T00:22:09.159732Z","caller":"traceutil/trace.go:171","msg":"trace[888212929] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:9628; }","duration":"159.820446ms","start":"2026-10-16T00:22:08.999871Z","end":"2026-10-16T00:22:09.159692Z","steps":["trace[888212929] 'range keys from in-memory index tree' (duration: 159.346263ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:12.34019Z","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-16T00:22:12.347864Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T00:22:12.348138Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T00:24:04.414927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8074} {"level":"info","ts":"2026-10-16T00:24:04.488223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8074,"took":"70.974446ms","hash":4125982177,"current-db-size-bytes":55894016,"current-db-size":"56 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-16T00:24:04.4883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4125982177,"revision":8074,"compact-revision":5892} {"level":"info","ts":"2026-10-16T00:25:49.577221Z","caller":"traceutil/trace.go:171","msg":"trace[949138125] transaction","detail":"{read_only:false; response_revision:11550; number_of_response:1; }","duration":"126.295312ms","start":"2026-10-16T00:25:49.450899Z","end":"2026-10-16T00:25:49.577194Z","steps":["trace[949138125] 'process raft request' (duration: 126.149028ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:25:49.578016Z","caller":"traceutil/trace.go:171","msg":"trace[2049663712] linearizableReadLoop","detail":"{readStateIndex:11953; appliedIndex:11953; }","duration":"116.579513ms","start":"2026-10-16T00:25:49.461408Z","end":"2026-10-16T00:25:49.577988Z","steps":["trace[2049663712] 'read index received' (duration: 116.567283ms)","trace[2049663712] 'applied index is now lower than readState.Index' (duration: 9.56µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:25:49.578261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.841339ms","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-16T00:25:49.578308Z","caller":"traceutil/trace.go:171","msg":"trace[112816134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11550; }","duration":"116.940142ms","start":"2026-10-16T00:25:49.461355Z","end":"2026-10-16T00:25:49.578295Z","steps":["trace[112816134] 'agreement among raft nodes before linearized reading' (duration: 116.750077ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:25:49.747336Z","caller":"traceutil/trace.go:171","msg":"trace[1037964535] transaction","detail":"{read_only:false; response_revision:11552; number_of_response:1; }","duration":"174.253499ms","start":"2026-10-16T00:25:49.573053Z","end":"2026-10-16T00:25:49.747307Z","steps":["trace[1037964535] 'process raft request' (duration: 174.158987ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:25:49.74734Z","caller":"traceutil/trace.go:171","msg":"trace[2138584729] transaction","detail":"{read_only:false; response_revision:11551; number_of_response:1; }","duration":"274.002952ms","start":"2026-10-16T00:25:49.473304Z","end":"2026-10-16T00:25:49.747307Z","steps":["trace[2138584729] 'process raft request' (duration: 254.965495ms)","trace[2138584729] 'compare' (duration: 18.731319ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:25:49.946558Z","caller":"traceutil/trace.go:171","msg":"trace[1741366574] linearizableReadLoop","detail":"{readStateIndex:11956; appliedIndex:11955; }","duration":"108.849575ms","start":"2026-10-16T00:25:49.837681Z","end":"2026-10-16T00:25:49.94653Z","steps":["trace[1741366574] 'read index received' (duration: 80.316335ms)","trace[1741366574] 'applied index is now lower than readState.Index' (duration: 28.53202ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:25:49.946659Z","caller":"traceutil/trace.go:171","msg":"trace[1888452201] transaction","detail":"{read_only:false; response_revision:11553; number_of_response:1; }","duration":"364.329032ms","start":"2026-10-16T00:25:49.582299Z","end":"2026-10-16T00:25:49.946628Z","steps":["trace[1888452201] 'process raft request' (duration: 335.7082ms)","trace[1888452201] 'compare' (duration: 28.321264ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:25:49.946824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.132292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T00:25:49.946868Z","caller":"traceutil/trace.go:171","msg":"trace[1028538196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11553; }","duration":"109.225615ms","start":"2026-10-16T00:25:49.837632Z","end":"2026-10-16T00:25:49.946858Z","steps":["trace[1028538196] 'agreement among raft nodes before linearized reading' (duration: 109.0221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:25:49.946981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:49.582285Z","time spent":"364.465626ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:25:49.947111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.889882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-16T00:25:49.947117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.797772ms","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-16T00:25:49.947183Z","caller":"traceutil/trace.go:171","msg":"trace[1343056949] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11553; }","duration":"100.992304ms","start":"2026-10-16T00:25:49.846157Z","end":"2026-10-16T00:25:49.94715Z","steps":["trace[1343056949] 'agreement among raft nodes before linearized reading' (duration: 100.83246ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:25:49.947218Z","caller":"traceutil/trace.go:171","msg":"trace[711824736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11553; }","duration":"107.193912ms","start":"2026-10-16T00:25:49.840001Z","end":"2026-10-16T00:25:49.947195Z","steps":["trace[711824736] 'agreement among raft nodes before linearized reading' (duration: 106.680689ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:29:04.432905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10554} {"level":"info","ts":"2026-10-16T00:29:04.524874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10554,"took":"90.103441ms","hash":3415910719,"current-db-size-bytes":55894016,"current-db-size":"56 MB","current-db-size-in-use-bytes":27402240,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T00:29:04.524957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3415910719,"revision":10554,"compact-revision":8074} {"level":"warn","ts":"2026-10-16T00:32:05.504532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.146466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-16T00:32:05.504622Z","caller":"traceutil/trace.go:171","msg":"trace[1905700104] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14078; }","duration":"263.290461ms","start":"2026-10-16T00:32:05.241311Z","end":"2026-10-16T00:32:05.504601Z","steps":["trace[1905700104] 'range keys from in-memory index tree' (duration: 262.971312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:32:22.561904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.210939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19754"} {"level":"info","ts":"2026-10-16T00:32:22.561995Z","caller":"traceutil/trace.go:171","msg":"trace[920033300] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14185; }","duration":"131.348232ms","start":"2026-10-16T00:32:22.430628Z","end":"2026-10-16T00:32:22.561976Z","steps":["trace[920033300] 'range keys from in-memory index tree' (duration: 130.946802ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:32:22.841317Z","caller":"traceutil/trace.go:171","msg":"trace[1939641117] transaction","detail":"{read_only:false; response_revision:14186; number_of_response:1; }","duration":"275.209774ms","start":"2026-10-16T00:32:22.566056Z","end":"2026-10-16T00:32:22.841266Z","steps":["trace[1939641117] 'process raft request' (duration: 275.002479ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:32:22.841401Z","caller":"traceutil/trace.go:171","msg":"trace[1385330975] linearizableReadLoop","detail":"{readStateIndex:14680; appliedIndex:14679; }","duration":"273.343757ms","start":"2026-10-16T00:32:22.56802Z","end":"2026-10-16T00:32:22.841364Z","steps":["trace[1385330975] 'read index received' (duration: 273.002468ms)","trace[1385330975] 'applied index is now lower than readState.Index' (duration: 338.249µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:32:22.841589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.588239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:32:22.841664Z","caller":"traceutil/trace.go:171","msg":"trace[1010048518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14186; }","duration":"189.691602ms","start":"2026-10-16T00:32:22.651952Z","end":"2026-10-16T00:32:22.841644Z","steps":["trace[1010048518] 'agreement among raft nodes before linearized reading' (duration: 189.568229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:32:22.841671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.635875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19754"} {"level":"info","ts":"2026-10-16T00:32:22.841894Z","caller":"traceutil/trace.go:171","msg":"trace[1944254418] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14186; }","duration":"273.863271ms","start":"2026-10-16T00:32:22.567965Z","end":"2026-10-16T00:32:22.841828Z","steps":["trace[1944254418] 'agreement among raft nodes before linearized reading' (duration: 273.521772ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:32:38.109129Z","caller":"traceutil/trace.go:171","msg":"trace[1748220913] linearizableReadLoop","detail":"{readStateIndex:14840; appliedIndex:14839; }","duration":"130.147532ms","start":"2026-10-16T00:32:37.978956Z","end":"2026-10-16T00:32:38.109103Z","steps":["trace[1748220913] 'read index received' (duration: 130.0552ms)","trace[1748220913] 'applied index is now lower than readState.Index' (duration: 90.992µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:32:38.109348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.355548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:32:38.109445Z","caller":"traceutil/trace.go:171","msg":"trace[1655025821] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:14334; }","duration":"130.500711ms","start":"2026-10-16T00:32:37.97892Z","end":"2026-10-16T00:32:38.109421Z","steps":["trace[1655025821] 'agreement among raft nodes before linearized reading' (duration: 130.313566ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:04.44128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12824} {"level":"info","ts":"2026-10-16T00:34:04.525892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12824,"took":"82.63026ms","hash":1226285059,"current-db-size-bytes":55894016,"current-db-size":"56 MB","current-db-size-in-use-bytes":30142464,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T00:34:04.525973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1226285059,"revision":12824,"compact-revision":10554} {"level":"warn","ts":"2026-10-16T00:34:14.992054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.348781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:34:14.992445Z","caller":"traceutil/trace.go:171","msg":"trace[148226720] transaction","detail":"{read_only:false; response_revision:15320; number_of_response:1; }","duration":"285.850837ms","start":"2026-10-16T00:34:14.706555Z","end":"2026-10-16T00:34:14.992405Z","steps":["trace[148226720] 'process raft request' (duration: 109.065794ms)","trace[148226720] 'compare' (duration: 176.129716ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:34:14.992889Z","caller":"traceutil/trace.go:171","msg":"trace[1124588148] linearizableReadLoop","detail":"{readStateIndex:15854; appliedIndex:15852; }","duration":"275.224726ms","start":"2026-10-16T00:34:14.717653Z","end":"2026-10-16T00:34:14.992878Z","steps":["trace[1124588148] 'read index received' (duration: 97.963851ms)","trace[1124588148] 'applied index is now lower than readState.Index' (duration: 177.260035ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:34:14.993114Z","caller":"traceutil/trace.go:171","msg":"trace[128857620] transaction","detail":"{read_only:false; response_revision:15321; number_of_response:1; }","duration":"285.855587ms","start":"2026-10-16T00:34:14.707248Z","end":"2026-10-16T00:34:14.993103Z","steps":["trace[128857620] 'process raft request' (duration: 285.077798ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:14.993325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.671907ms","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-16T00:34:14.99336Z","caller":"traceutil/trace.go:171","msg":"trace[246819130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15321; }","duration":"275.737979ms","start":"2026-10-16T00:34:14.717611Z","end":"2026-10-16T00:34:14.993349Z","steps":["trace[246819130] 'agreement among raft nodes before linearized reading' (duration: 275.617706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:14.993533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.814733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-16T00:34:14.993565Z","caller":"traceutil/trace.go:171","msg":"trace[1761569519] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15321; }","duration":"232.893395ms","start":"2026-10-16T00:34:14.760664Z","end":"2026-10-16T00:34:14.993557Z","steps":["trace[1761569519] 'agreement among raft nodes before linearized reading' (duration: 232.809583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:14.993997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.104974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4gbb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T00:34:14.994035Z","caller":"traceutil/trace.go:171","msg":"trace[1324424574] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4gbb; range_end:; response_count:1; response_revision:15321; }","duration":"183.165095ms","start":"2026-10-16T00:34:14.810861Z","end":"2026-10-16T00:34:14.994026Z","steps":["trace[1324424574] 'agreement among raft nodes before linearized reading' (duration: 183.079423ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:15.839104Z","caller":"traceutil/trace.go:171","msg":"trace[1691218766] transaction","detail":"{read_only:false; response_revision:15327; number_of_response:1; }","duration":"158.869415ms","start":"2026-10-16T00:34:15.680214Z","end":"2026-10-16T00:34:15.839084Z","steps":["trace[1691218766] 'process raft request' (duration: 158.768492ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:15.839689Z","caller":"traceutil/trace.go:171","msg":"trace[1321290365] linearizableReadLoop","detail":"{readStateIndex:15860; appliedIndex:15860; }","duration":"127.915866ms","start":"2026-10-16T00:34:15.711743Z","end":"2026-10-16T00:34:15.839659Z","steps":["trace[1321290365] 'read index received' (duration: 127.911535ms)","trace[1321290365] 'applied index is now lower than readState.Index' (duration: 3.44µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:34:15.839791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.907149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:34:15.839825Z","caller":"traceutil/trace.go:171","msg":"trace[1958813095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15327; }","duration":"126.972151ms","start":"2026-10-16T00:34:15.712844Z","end":"2026-10-16T00:34:15.839816Z","steps":["trace[1958813095] 'agreement among raft nodes before linearized reading' (duration: 126.907939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:15.839993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.243874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:34:15.840033Z","caller":"traceutil/trace.go:171","msg":"trace[1124349516] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:15327; }","duration":"128.329236ms","start":"2026-10-16T00:34:15.711691Z","end":"2026-10-16T00:34:15.84002Z","steps":["trace[1124349516] 'agreement among raft nodes before linearized reading' (duration: 128.270614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:15.84023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.912069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-16T00:34:15.840264Z","caller":"traceutil/trace.go:171","msg":"trace[579052861] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15327; }","duration":"115.95811ms","start":"2026-10-16T00:34:15.724295Z","end":"2026-10-16T00:34:15.840253Z","steps":["trace[579052861] 'agreement among raft nodes before linearized reading' (duration: 115.855747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:15.840448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.168778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-16T00:34:15.840474Z","caller":"traceutil/trace.go:171","msg":"trace[1113741926] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15327; }","duration":"122.212549ms","start":"2026-10-16T00:34:15.718254Z","end":"2026-10-16T00:34:15.840467Z","steps":["trace[1113741926] 'agreement among raft nodes before linearized reading' (duration: 122.133587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:15.840613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.392704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:34:15.840643Z","caller":"traceutil/trace.go:171","msg":"trace[732970515] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15327; }","duration":"122.436455ms","start":"2026-10-16T00:34:15.718199Z","end":"2026-10-16T00:34:15.840635Z","steps":["trace[732970515] 'agreement among raft nodes before linearized reading' (duration: 122.394824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:15.84083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.947758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-10-16T00:34:15.840861Z","caller":"traceutil/trace.go:171","msg":"trace[1071196839] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15327; }","duration":"122.99679ms","start":"2026-10-16T00:34:15.717857Z","end":"2026-10-16T00:34:15.840854Z","steps":["trace[1071196839] 'agreement among raft nodes before linearized reading' (duration: 122.913828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:15.840986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.141443ms","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-16T00:34:15.841018Z","caller":"traceutil/trace.go:171","msg":"trace[864103401] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15327; }","duration":"123.211515ms","start":"2026-10-16T00:34:15.717798Z","end":"2026-10-16T00:34:15.84101Z","steps":["trace[864103401] 'agreement among raft nodes before linearized reading' (duration: 123.168324ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:39:04.44868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15266} {"level":"info","ts":"2026-10-16T00:39:04.550788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15266,"took":"98.573875ms","hash":515177673,"current-db-size-bytes":55894016,"current-db-size":"56 MB","current-db-size-in-use-bytes":32002048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:39:04.550868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":515177673,"revision":15266,"compact-revision":12824} {"level":"info","ts":"2026-10-16T00:40:13.822765Z","caller":"traceutil/trace.go:171","msg":"trace[1913685669] transaction","detail":"{read_only:false; response_revision:18106; number_of_response:1; }","duration":"117.149581ms","start":"2026-10-16T00:40:13.705591Z","end":"2026-10-16T00:40:13.82274Z","steps":["trace[1913685669] 'process raft request' (duration: 116.918805ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:43.852623Z","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-16T00:42:43.860144Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T00:42:43.860268Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T00:44:04.457065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17488} {"level":"info","ts":"2026-10-16T00:44:04.564557Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17488,"took":"102.000682ms","hash":3741379280,"current-db-size-bytes":55894016,"current-db-size":"56 MB","current-db-size-in-use-bytes":34680832,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-16T00:44:04.564628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3741379280,"revision":17488,"compact-revision":15266} {"level":"info","ts":"2026-10-16T00:45:07.05458Z","caller":"traceutil/trace.go:171","msg":"trace[1898734928] transaction","detail":"{read_only:false; response_revision:20357; number_of_response:1; }","duration":"102.521275ms","start":"2026-10-16T00:45:06.952029Z","end":"2026-10-16T00:45:07.054551Z","steps":["trace[1898734928] 'process raft request' (duration: 102.254939ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:45:24.667444Z","caller":"traceutil/trace.go:171","msg":"trace[1500535458] linearizableReadLoop","detail":"{readStateIndex:21154; appliedIndex:21153; }","duration":"229.120597ms","start":"2026-10-16T00:45:24.438301Z","end":"2026-10-16T00:45:24.667422Z","steps":["trace[1500535458] 'read index received' (duration: 229.090826ms)","trace[1500535458] 'applied index is now lower than readState.Index' (duration: 28.69µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:45:24.667586Z","caller":"traceutil/trace.go:171","msg":"trace[1142123620] transaction","detail":"{read_only:false; response_revision:20452; number_of_response:1; }","duration":"257.546844ms","start":"2026-10-16T00:45:24.40996Z","end":"2026-10-16T00:45:24.667507Z","steps":["trace[1142123620] 'process raft request' (duration: 257.337148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:24.667661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.346583ms","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-16T00:45:24.667723Z","caller":"traceutil/trace.go:171","msg":"trace[615030167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20452; }","duration":"229.460986ms","start":"2026-10-16T00:45:24.438251Z","end":"2026-10-16T00:45:24.667712Z","steps":["trace[615030167] 'agreement among raft nodes before linearized reading' (duration: 229.298782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:24.69415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.064605ms","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-16T00:45:24.694235Z","caller":"traceutil/trace.go:171","msg":"trace[864366936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20453; }","duration":"220.23035ms","start":"2026-10-16T00:45:24.473985Z","end":"2026-10-16T00:45:24.694215Z","steps":["trace[864366936] 'agreement among raft nodes before linearized reading' (duration: 220.020204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:24.694618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.764094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7913"} {"level":"info","ts":"2026-10-16T00:45:24.694716Z","caller":"traceutil/trace.go:171","msg":"trace[1261046901] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:20453; }","duration":"165.908918ms","start":"2026-10-16T00:45:24.528789Z","end":"2026-10-16T00:45:24.694698Z","steps":["trace[1261046901] 'agreement among raft nodes before linearized reading' (duration: 165.624391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.005712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.284858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:45:25.006016Z","caller":"traceutil/trace.go:171","msg":"trace[448675784] linearizableReadLoop","detail":"{readStateIndex:21156; appliedIndex:21155; }","duration":"310.023068ms","start":"2026-10-16T00:45:24.695966Z","end":"2026-10-16T00:45:25.005989Z","steps":["trace[448675784] 'read index received' (duration: 150.3616ms)","trace[448675784] 'applied index is now lower than readState.Index' (duration: 159.659318ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:45:25.006088Z","caller":"traceutil/trace.go:171","msg":"trace[732665937] transaction","detail":"{read_only:false; response_revision:20454; number_of_response:1; }","duration":"333.673924ms","start":"2026-10-16T00:45:24.67235Z","end":"2026-10-16T00:45:25.006024Z","steps":["trace[732665937] 'process raft request' (duration: 173.937324ms)","trace[732665937] 'compare' (duration: 158.964691ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:45:25.006142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.174373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:45:25.006281Z","caller":"traceutil/trace.go:171","msg":"trace[789253013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20454; }","duration":"310.297585ms","start":"2026-10-16T00:45:24.695931Z","end":"2026-10-16T00:45:25.006229Z","steps":["trace[789253013] 'agreement among raft nodes before linearized reading' (duration: 310.158572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.006304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:45:24.67232Z","time spent":"333.87443ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:45:25.006359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:45:24.695919Z","time spent":"310.426749ms","remote":"127.0.0.1:59334","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-16T00:45:25.007995Z","caller":"traceutil/trace.go:171","msg":"trace[1216929855] transaction","detail":"{read_only:false; response_revision:20455; number_of_response:1; }","duration":"309.511865ms","start":"2026-10-16T00:45:24.69846Z","end":"2026-10-16T00:45:25.007972Z","steps":["trace[1216929855] 'process raft request' (duration: 309.373591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.008136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:45:24.698433Z","time spent":"309.627978ms","remote":"127.0.0.1:59592","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-16T00:45:25.008286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.232658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-16T00:45:25.008357Z","caller":"traceutil/trace.go:171","msg":"trace[1034332708] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20455; }","duration":"216.36425ms","start":"2026-10-16T00:45:24.791978Z","end":"2026-10-16T00:45:25.008342Z","steps":["trace[1034332708] 'agreement among raft nodes before linearized reading' (duration: 216.175336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.008424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.840272ms","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-16T00:45:25.00847Z","caller":"traceutil/trace.go:171","msg":"trace[766222543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20455; }","duration":"299.395696ms","start":"2026-10-16T00:45:24.709059Z","end":"2026-10-16T00:45:25.008455Z","steps":["trace[766222543] 'agreement among raft nodes before linearized reading' (duration: 299.248403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.008366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.835235ms","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-16T00:45:25.008844Z","caller":"traceutil/trace.go:171","msg":"trace[418133209] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20455; }","duration":"271.365439ms","start":"2026-10-16T00:45:24.737455Z","end":"2026-10-16T00:45:25.00882Z","steps":["trace[418133209] 'agreement among raft nodes before linearized reading' (duration: 270.835105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.008839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.439158ms","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-16T00:45:25.008901Z","caller":"traceutil/trace.go:171","msg":"trace[539339181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20455; }","duration":"308.52327ms","start":"2026-10-16T00:45:24.70036Z","end":"2026-10-16T00:45:25.008883Z","steps":["trace[539339181] 'agreement among raft nodes before linearized reading' (duration: 307.681829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.008941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:45:24.700344Z","time spent":"308.588642ms","remote":"127.0.0.1:59592","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":"info","ts":"2026-10-16T00:45:25.328719Z","caller":"traceutil/trace.go:171","msg":"trace[163963803] linearizableReadLoop","detail":"{readStateIndex:21160; appliedIndex:21158; }","duration":"181.58908ms","start":"2026-10-16T00:45:25.147106Z","end":"2026-10-16T00:45:25.328695Z","steps":["trace[163963803] 'read index received' (duration: 181.247361ms)","trace[163963803] 'applied index is now lower than readState.Index' (duration: 340.519µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:45:25.328738Z","caller":"traceutil/trace.go:171","msg":"trace[289566954] transaction","detail":"{read_only:false; response_revision:20457; number_of_response:1; }","duration":"314.674418ms","start":"2026-10-16T00:45:25.014024Z","end":"2026-10-16T00:45:25.328699Z","steps":["trace[289566954] 'process raft request' (duration: 314.397081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.328908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:45:25.013997Z","time spent":"314.834543ms","remote":"127.0.0.1:59592","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-16T00:45:25.329201Z","caller":"traceutil/trace.go:171","msg":"trace[1935469954] transaction","detail":"{read_only:false; response_revision:20458; number_of_response:1; }","duration":"314.524775ms","start":"2026-10-16T00:45:25.014663Z","end":"2026-10-16T00:45:25.329188Z","steps":["trace[1935469954] 'process raft request' (duration: 313.95686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.329282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:45:25.014651Z","time spent":"314.580756ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:45:25.329486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.379691ms","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-16T00:45:25.329533Z","caller":"traceutil/trace.go:171","msg":"trace[1729806180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20458; }","duration":"182.497613ms","start":"2026-10-16T00:45:25.147023Z","end":"2026-10-16T00:45:25.329521Z","steps":["trace[1729806180] 'agreement among raft nodes before linearized reading' (duration: 182.342699ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:45:25.787371Z","caller":"traceutil/trace.go:171","msg":"trace[1148571691] transaction","detail":"{read_only:false; response_revision:20461; number_of_response:1; }","duration":"369.478532ms","start":"2026-10-16T00:45:25.417868Z","end":"2026-10-16T00:45:25.787347Z","steps":["trace[1148571691] 'process raft request' (duration: 369.316698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:45:25.787556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:45:25.417848Z","time spent":"369.588494ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7896,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:49:04.481474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19983} {"level":"info","ts":"2026-10-16T00:49:04.628082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19983,"took":"143.15265ms","hash":1641520372,"current-db-size-bytes":55894016,"current-db-size":"56 MB","current-db-size-in-use-bytes":36065280,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T00:49:04.628153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1641520372,"revision":19983,"compact-revision":17488} {"level":"warn","ts":"2026-10-16T00:49:14.037578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.464303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:49:14.03775Z","caller":"traceutil/trace.go:171","msg":"trace[1362911905] transaction","detail":"{read_only:false; response_revision:22835; number_of_response:1; }","duration":"319.622353ms","start":"2026-10-16T00:49:13.718086Z","end":"2026-10-16T00:49:14.037708Z","steps":["trace[1362911905] 'process raft request' (duration: 61.81043ms)","trace[1362911905] 'compare' (duration: 256.737425ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:49:14.037954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:49:13.718066Z","time spent":"319.791527ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:52:34.692512Z","caller":"traceutil/trace.go:171","msg":"trace[543244738] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"269.479451ms","start":"2026-10-16T00:52:34.422998Z","end":"2026-10-16T00:52:34.692478Z","steps":["trace[543244738] 'process raft request' (duration: 211.890418ms)","trace[543244738] 'compare' (duration: 57.344316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:52:35.07325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.017181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:52:35.073601Z","caller":"traceutil/trace.go:171","msg":"trace[918903832] linearizableReadLoop","detail":"{readStateIndex:25666; appliedIndex:25664; }","duration":"127.46857ms","start":"2026-10-16T00:52:34.946114Z","end":"2026-10-16T00:52:35.073583Z","steps":["trace[918903832] 'read index received' (duration: 43.471µs)","trace[918903832] 'applied index is now lower than readState.Index' (duration: 127.424069ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:52:35.073667Z","caller":"traceutil/trace.go:171","msg":"trace[275750005] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"535.571055ms","start":"2026-10-16T00:52:34.53806Z","end":"2026-10-16T00:52:35.073631Z","steps":["trace[275750005] 'process raft request' (duration: 535.294328ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:52:35.073697Z","caller":"traceutil/trace.go:171","msg":"trace[1210635011] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"553.803181ms","start":"2026-10-16T00:52:34.519849Z","end":"2026-10-16T00:52:35.073652Z","steps":["trace[1210635011] 'process raft request' (duration: 368.301878ms)","trace[1210635011] 'compare' (duration: 184.276462ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:52:35.07385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:52:34.519819Z","time spent":"553.947014ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:52:35.073863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:52:34.538033Z","time spent":"535.726049ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:52:35.073878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.761347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-16T00:52:35.073968Z","caller":"traceutil/trace.go:171","msg":"trace[1199823108] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24841; }","duration":"127.847969ms","start":"2026-10-16T00:52:34.94606Z","end":"2026-10-16T00:52:35.073908Z","steps":["trace[1199823108] 'agreement among raft nodes before linearized reading' (duration: 127.580832ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:52:35.120849Z","caller":"traceutil/trace.go:171","msg":"trace[847730660] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"128.616708ms","start":"2026-10-16T00:52:34.992195Z","end":"2026-10-16T00:52:35.120812Z","steps":["trace[847730660] 'process raft request' (duration: 128.441224ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:52:35.375442Z","caller":"traceutil/trace.go:171","msg":"trace[1220831768] transaction","detail":"{read_only:false; response_revision:24843; number_of_response:1; }","duration":"121.819675ms","start":"2026-10-16T00:52:35.253596Z","end":"2026-10-16T00:52:35.375415Z","steps":["trace[1220831768] 'process raft request' (duration: 120.790109ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:40.211775Z","caller":"traceutil/trace.go:171","msg":"trace[325951947] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"127.721736ms","start":"2026-10-16T00:53:40.084027Z","end":"2026-10-16T00:53:40.211749Z","steps":["trace[325951947] 'process raft request' (duration: 127.47709ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:40.483924Z","caller":"traceutil/trace.go:171","msg":"trace[123116202] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"267.638103ms","start":"2026-10-16T00:53:40.216253Z","end":"2026-10-16T00:53:40.483891Z","steps":["trace[123116202] 'process raft request' (duration: 169.160165ms)","trace[123116202] 'compare' (duration: 98.320474ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:53:40.81265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.109118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:53:40.812853Z","caller":"traceutil/trace.go:171","msg":"trace[2105674815] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"593.443364ms","start":"2026-10-16T00:53:40.21939Z","end":"2026-10-16T00:53:40.812834Z","steps":["trace[2105674815] 'process raft request' (duration: 373.06604ms)","trace[2105674815] 'compare' (duration: 219.837821ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:53:40.81292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:40.219374Z","time spent":"593.514386ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:53:40.813642Z","caller":"traceutil/trace.go:171","msg":"trace[1292065510] linearizableReadLoop","detail":"{readStateIndex:26271; appliedIndex:26269; }","duration":"281.902479ms","start":"2026-10-16T00:53:40.531677Z","end":"2026-10-16T00:53:40.81358Z","steps":["trace[1292065510] 'read index received' (duration: 60.710923ms)","trace[1292065510] 'applied index is now lower than readState.Index' (duration: 221.190596ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:53:40.813658Z","caller":"traceutil/trace.go:171","msg":"trace[1660220569] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"572.475108ms","start":"2026-10-16T00:53:40.241159Z","end":"2026-10-16T00:53:40.813634Z","steps":["trace[1660220569] 'process raft request' (duration: 571.599056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:40.813846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.171835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-16T00:53:40.813885Z","caller":"traceutil/trace.go:171","msg":"trace[644579032] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25432; }","duration":"282.37254ms","start":"2026-10-16T00:53:40.5315Z","end":"2026-10-16T00:53:40.813872Z","steps":["trace[644579032] 'agreement among raft nodes before linearized reading' (duration: 282.213736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:40.814125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.887078ms","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-16T00:53:40.814194Z","caller":"traceutil/trace.go:171","msg":"trace[1189678155] 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:25432; }","duration":"132.995501ms","start":"2026-10-16T00:53:40.681181Z","end":"2026-10-16T00:53:40.814176Z","steps":["trace[1189678155] 'agreement among raft nodes before linearized reading' (duration: 132.872278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:40.814324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.622162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:53:40.814364Z","caller":"traceutil/trace.go:171","msg":"trace[998165339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25432; }","duration":"161.662013ms","start":"2026-10-16T00:53:40.652692Z","end":"2026-10-16T00:53:40.814354Z","steps":["trace[998165339] 'agreement among raft nodes before linearized reading' (duration: 161.596752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:40.814599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:40.241142Z","time spent":"572.753086ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:54:01.622932Z","caller":"traceutil/trace.go:171","msg":"trace[1599150960] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"175.98425ms","start":"2026-10-16T00:54:01.446925Z","end":"2026-10-16T00:54:01.622909Z","steps":["trace[1599150960] 'process raft request' (duration: 175.841986ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:01.623789Z","caller":"traceutil/trace.go:171","msg":"trace[105957589] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"155.483696ms","start":"2026-10-16T00:54:01.468293Z","end":"2026-10-16T00:54:01.623776Z","steps":["trace[105957589] 'process raft request' (duration: 155.358463ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:01.623905Z","caller":"traceutil/trace.go:171","msg":"trace[592477679] linearizableReadLoop","detail":"{readStateIndex:26426; appliedIndex:26424; }","duration":"132.520519ms","start":"2026-10-16T00:54:01.491355Z","end":"2026-10-16T00:54:01.623875Z","steps":["trace[592477679] 'read index received' (duration: 131.544954ms)","trace[592477679] 'applied index is now lower than readState.Index' (duration: 973.735µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:54:01.62403Z","caller":"traceutil/trace.go:171","msg":"trace[864240561] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"154.757567ms","start":"2026-10-16T00:54:01.469261Z","end":"2026-10-16T00:54:01.624018Z","steps":["trace[864240561] 'process raft request' (duration: 154.48102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:54:01.62415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.773015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-16T00:54:01.624214Z","caller":"traceutil/trace.go:171","msg":"trace[205221219] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25583; }","duration":"132.884998ms","start":"2026-10-16T00:54:01.491309Z","end":"2026-10-16T00:54:01.624194Z","steps":["trace[205221219] 'agreement among raft nodes before linearized reading' (duration: 132.655582ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:04.495404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22434} {"level":"info","ts":"2026-10-16T00:54:04.63815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22434,"took":"138.767248ms","hash":3355537482,"current-db-size-bytes":57581568,"current-db-size":"58 MB","current-db-size-in-use-bytes":45400064,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-16T00:54:04.638208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3355537482,"revision":22434,"compact-revision":19983} {"level":"info","ts":"2026-10-16T00:54:20.295667Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006826.wal"} {"level":"info","ts":"2026-10-16T00:57:05.87819Z","caller":"traceutil/trace.go:171","msg":"trace[1151117244] transaction","detail":"{read_only:false; response_revision:27464; number_of_response:1; }","duration":"118.000417ms","start":"2026-10-16T00:57:05.760158Z","end":"2026-10-16T00:57:05.878159Z","steps":["trace[1151117244] 'process raft request' (duration: 78.117997ms)","trace[1151117244] 'compare' (duration: 39.320086ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:57:05.878486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.509967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:57:05.878549Z","caller":"traceutil/trace.go:171","msg":"trace[1879163993] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:27464; }","duration":"112.588229ms","start":"2026-10-16T00:57:05.765944Z","end":"2026-10-16T00:57:05.878532Z","steps":["trace[1879163993] 'agreement among raft nodes before linearized reading' (duration: 112.428854ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:57:05.877822Z","caller":"traceutil/trace.go:171","msg":"trace[1435957034] linearizableReadLoop","detail":"{readStateIndex:28352; appliedIndex:28351; }","duration":"111.84212ms","start":"2026-10-16T00:57:05.765952Z","end":"2026-10-16T00:57:05.877794Z","steps":["trace[1435957034] 'read index received' (duration: 72.255427ms)","trace[1435957034] 'applied index is now lower than readState.Index' (duration: 39.581593ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:59:04.509649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25604} {"level":"info","ts":"2026-10-16T00:59:04.683475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25604,"took":"169.867328ms","hash":3312307528,"current-db-size-bytes":58429440,"current-db-size":"58 MB","current-db-size-in-use-bytes":35549184,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T00:59:04.683614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3312307528,"revision":25604,"compact-revision":22434} {"level":"info","ts":"2026-10-16T01:00:15.76249Z","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-16T01:00:15.769775Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T01:00:15.769896Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T01:04:04.517515Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28469} {"level":"info","ts":"2026-10-16T01:04:04.672155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28469,"took":"150.499961ms","hash":2412842815,"current-db-size-bytes":58429440,"current-db-size":"58 MB","current-db-size-in-use-bytes":31031296,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:04:04.672221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2412842815,"revision":28469,"compact-revision":25604} {"level":"info","ts":"2026-10-16T01:09:04.524164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30752} {"level":"info","ts":"2026-10-16T01:09:04.644064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30752,"took":"117.046934ms","hash":1775164391,"current-db-size-bytes":58429440,"current-db-size":"58 MB","current-db-size-in-use-bytes":31313920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:09:04.644134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1775164391,"revision":30752,"compact-revision":28469} {"level":"info","ts":"2026-10-16T01:14:04.53605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33048} {"level":"info","ts":"2026-10-16T01:14:04.708076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33048,"took":"167.306485ms","hash":4250312529,"current-db-size-bytes":59473920,"current-db-size":"60 MB","current-db-size-in-use-bytes":31014912,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:14:04.70815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4250312529,"revision":33048,"compact-revision":30752} {"level":"info","ts":"2026-10-16T01:19:04.544147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35338} {"level":"info","ts":"2026-10-16T01:19:04.684345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35338,"took":"135.976177ms","hash":2333457771,"current-db-size-bytes":59473920,"current-db-size":"60 MB","current-db-size-in-use-bytes":32342016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:19:04.684464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2333457771,"revision":35338,"compact-revision":33048} {"level":"info","ts":"2026-10-16T01:21:40.832948Z","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-16T01:21:40.840616Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T01:21:40.840949Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T01:24:04.550887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37566} {"level":"info","ts":"2026-10-16T01:24:04.684093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37566,"took":"129.02963ms","hash":3781658628,"current-db-size-bytes":59473920,"current-db-size":"60 MB","current-db-size-in-use-bytes":32149504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:24:04.684165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3781658628,"revision":37566,"compact-revision":35338}