{"level":"warn","ts":"2026-05-05T06:54:16.598904Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:54:16.599001Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T06:54:16.599066Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:54:16.599075Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-05T06:54:16.599095Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T06:54:16.59984Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-05T06:54:16.599913Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T06:54:16.604134Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.021945ms"} {"level":"info","ts":"2026-05-05T06:54:16.613173Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-05T06:54:16.613308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:54:16.613343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-05T06:54:16.613366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:54:16.613383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-05T06:54:16.613436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-05T06:54:16.620501Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:54:16.622772Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:54:16.624017Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T06:54:16.627156Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:54:16.628567Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:54:16.629551Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:54:16.629713Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:54:16.629788Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:54:16.629837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-05T06:54:16.630558Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-05T06:54:16.632041Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T06:54:16.632159Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-05T06:54:16.632195Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-05T06:54:16.632534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:54:16.632592Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:54:17.314643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:54:17.31471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:54:17.314749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-05T06:54:17.314784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:54:17.314824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-05T06:54:17.314844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-05T06:54:17.314865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-05T06:54:17.316125Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:54:17.317115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:54:17.317118Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:54:17.317197Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:54:17.317525Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:54:17.317627Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:54:17.317657Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:54:17.317806Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:54:17.317858Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:54:17.320548Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:54:17.321072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-05T06:54:30.836537Z","caller":"traceutil/trace.go:171","msg":"trace[2139909926] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"338.481753ms","start":"2026-05-05T06:54:30.498023Z","end":"2026-05-05T06:54:30.836505Z","steps":["trace[2139909926] 'read index received' (duration: 338.278748ms)","trace[2139909926] 'applied index is now lower than readState.Index' (duration: 201.775µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:54:30.83661Z","caller":"traceutil/trace.go:171","msg":"trace[639884281] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"482.405933ms","start":"2026-05-05T06:54:30.354161Z","end":"2026-05-05T06:54:30.836567Z","steps":["trace[639884281] 'process raft request' (duration: 482.137937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:30.836705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.654667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T06:54:30.836792Z","caller":"traceutil/trace.go:171","msg":"trace[1265849227] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:363; }","duration":"338.80081ms","start":"2026-05-05T06:54:30.49798Z","end":"2026-05-05T06:54:30.836781Z","steps":["trace[1265849227] 'agreement among raft nodes before linearized reading' (duration: 338.658367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:30.836836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:30.497957Z","time spent":"338.869961ms","remote":"127.0.0.1:40206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"warn","ts":"2026-05-05T06:54:30.837418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:30.354139Z","time spent":"482.563828ms","remote":"127.0.0.1:40340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:54:49.623491Z","caller":"traceutil/trace.go:171","msg":"trace[1994909960] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"152.925355ms","start":"2026-05-05T06:54:49.470522Z","end":"2026-05-05T06:54:49.623448Z","steps":["trace[1994909960] 'process raft request' (duration: 136.894364ms)","trace[1994909960] 'compare' (duration: 15.934558ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:54:49.628455Z","caller":"traceutil/trace.go:171","msg":"trace[1122017251] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"155.503126ms","start":"2026-05-05T06:54:49.472915Z","end":"2026-05-05T06:54:49.628418Z","steps":["trace[1122017251] 'process raft request' (duration: 155.108256ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.62851Z","caller":"traceutil/trace.go:171","msg":"trace[877844223] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"150.602119ms","start":"2026-05-05T06:54:49.47789Z","end":"2026-05-05T06:54:49.628492Z","steps":["trace[877844223] 'process raft request' (duration: 150.473926ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.628617Z","caller":"traceutil/trace.go:171","msg":"trace[1790448241] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"150.557478ms","start":"2026-05-05T06:54:49.47805Z","end":"2026-05-05T06:54:49.628608Z","steps":["trace[1790448241] 'process raft request' (duration: 150.427685ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.628467Z","caller":"traceutil/trace.go:171","msg":"trace[425580159] transaction","detail":"{read_only:false; number_of_response:1; response_revision:490; }","duration":"155.401423ms","start":"2026-05-05T06:54:49.473044Z","end":"2026-05-05T06:54:49.628445Z","steps":["trace[425580159] 'process raft request' (duration: 155.234909ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.628593Z","caller":"traceutil/trace.go:171","msg":"trace[1671140386] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"150.596619ms","start":"2026-05-05T06:54:49.477961Z","end":"2026-05-05T06:54:49.628558Z","steps":["trace[1671140386] 'process raft request' (duration: 150.476496ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.628691Z","caller":"traceutil/trace.go:171","msg":"trace[1572002678] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"146.181345ms","start":"2026-05-05T06:54:49.4825Z","end":"2026-05-05T06:54:49.628681Z","steps":["trace[1572002678] 'process raft request' (duration: 146.051402ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.628833Z","caller":"traceutil/trace.go:171","msg":"trace[277276516] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"142.276662ms","start":"2026-05-05T06:54:49.486543Z","end":"2026-05-05T06:54:49.62882Z","steps":["trace[277276516] 'process raft request' (duration: 142.054237ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.628877Z","caller":"traceutil/trace.go:171","msg":"trace[1932848330] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"141.35785ms","start":"2026-05-05T06:54:49.487508Z","end":"2026-05-05T06:54:49.628866Z","steps":["trace[1932848330] 'process raft request' (duration: 141.145525ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.629576Z","caller":"traceutil/trace.go:171","msg":"trace[133754336] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"141.963704ms","start":"2026-05-05T06:54:49.487571Z","end":"2026-05-05T06:54:49.629535Z","steps":["trace[133754336] 'process raft request' (duration: 141.287768ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.629804Z","caller":"traceutil/trace.go:171","msg":"trace[322903274] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"141.35078ms","start":"2026-05-05T06:54:49.488421Z","end":"2026-05-05T06:54:49.629772Z","steps":["trace[322903274] 'process raft request' (duration: 141.101994ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.630118Z","caller":"traceutil/trace.go:171","msg":"trace[1265111646] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"137.54053ms","start":"2026-05-05T06:54:49.492551Z","end":"2026-05-05T06:54:49.630091Z","steps":["trace[1265111646] 'process raft request' (duration: 137.13752ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:49.63044Z","caller":"traceutil/trace.go:171","msg":"trace[2051877004] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"133.421762ms","start":"2026-05-05T06:54:49.496994Z","end":"2026-05-05T06:54:49.630415Z","steps":["trace[2051877004] 'process raft request' (duration: 133.295958ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:50.169841Z","caller":"traceutil/trace.go:171","msg":"trace[1216135026] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"102.378146ms","start":"2026-05-05T06:54:50.06743Z","end":"2026-05-05T06:54:50.169808Z","steps":["trace[1216135026] 'process raft request' (duration: 16.88652ms)","trace[1216135026] 'compare' (duration: 85.188059ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:54:50.228719Z","caller":"traceutil/trace.go:171","msg":"trace[1825574541] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"123.021646ms","start":"2026-05-05T06:54:50.10565Z","end":"2026-05-05T06:54:50.228672Z","steps":["trace[1825574541] 'process raft request' (duration: 120.723692ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:50.238487Z","caller":"traceutil/trace.go:171","msg":"trace[1625984622] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:559; }","duration":"114.358741ms","start":"2026-05-05T06:54:50.124051Z","end":"2026-05-05T06:54:50.238409Z","steps":["trace[1625984622] 'read index received' (duration: 102.350175ms)","trace[1625984622] 'applied index is now lower than readState.Index' (duration: 12.007416ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:54:50.238518Z","caller":"traceutil/trace.go:171","msg":"trace[742899222] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"123.551939ms","start":"2026-05-05T06:54:50.114919Z","end":"2026-05-05T06:54:50.238471Z","steps":["trace[742899222] 'process raft request' (duration: 123.247201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:50.23875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.553156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T06:54:50.238805Z","caller":"traceutil/trace.go:171","msg":"trace[1392205748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"114.78844ms","start":"2026-05-05T06:54:50.124001Z","end":"2026-05-05T06:54:50.23879Z","steps":["trace[1392205748] 'agreement among raft nodes before linearized reading' (duration: 114.511494ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:55.540485Z","caller":"traceutil/trace.go:171","msg":"trace[823821674] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"188.690659ms","start":"2026-05-05T06:54:55.350188Z","end":"2026-05-05T06:54:55.538879Z","steps":["trace[823821674] 'process raft request' (duration: 180.562739ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:56.022428Z","caller":"traceutil/trace.go:171","msg":"trace[1820569272] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"149.6529ms","start":"2026-05-05T06:54:55.872742Z","end":"2026-05-05T06:54:56.022395Z","steps":["trace[1820569272] 'process raft request' (duration: 149.077906ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:56.224491Z","caller":"traceutil/trace.go:171","msg":"trace[889419973] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"143.918499ms","start":"2026-05-05T06:54:56.080554Z","end":"2026-05-05T06:54:56.224473Z","steps":["trace[889419973] 'read index received' (duration: 143.273614ms)","trace[889419973] 'applied index is now lower than readState.Index' (duration: 644.115µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:54:56.225407Z","caller":"traceutil/trace.go:171","msg":"trace[2038785732] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"191.647371ms","start":"2026-05-05T06:54:56.033749Z","end":"2026-05-05T06:54:56.225397Z","steps":["trace[2038785732] 'process raft request' (duration: 190.293727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.225757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.17086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:56.225889Z","caller":"traceutil/trace.go:171","msg":"trace[2138814352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"145.333644ms","start":"2026-05-05T06:54:56.080501Z","end":"2026-05-05T06:54:56.225834Z","steps":["trace[2138814352] 'agreement among raft nodes before linearized reading' (duration: 145.140869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.694674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.427157ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:56.694812Z","caller":"traceutil/trace.go:171","msg":"trace[2108504002] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:586; }","duration":"163.597811ms","start":"2026-05-05T06:54:56.531194Z","end":"2026-05-05T06:54:56.694792Z","steps":["trace[2108504002] 'range keys from in-memory index tree' (duration: 163.408397ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:56.694854Z","caller":"traceutil/trace.go:171","msg":"trace[140407322] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"424.570982ms","start":"2026-05-05T06:54:56.270268Z","end":"2026-05-05T06:54:56.694839Z","steps":["trace[140407322] 'process raft request' (duration: 398.83641ms)","trace[140407322] 'compare' (duration: 25.482106ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:54:56.694943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:56.270219Z","time spent":"424.668344ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:54:56.695036Z","caller":"traceutil/trace.go:171","msg":"trace[2098424394] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"312.963135ms","start":"2026-05-05T06:54:56.382046Z","end":"2026-05-05T06:54:56.695009Z","steps":["trace[2098424394] 'read index received' (duration: 287.029819ms)","trace[2098424394] 'applied index is now lower than readState.Index' (duration: 25.932216ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:54:56.695121Z","caller":"traceutil/trace.go:171","msg":"trace[1231522288] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"392.539117ms","start":"2026-05-05T06:54:56.30255Z","end":"2026-05-05T06:54:56.695089Z","steps":["trace[1231522288] 'process raft request' (duration: 392.2642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.695217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.155649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:56.695252Z","caller":"traceutil/trace.go:171","msg":"trace[912700662] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:588; }","duration":"313.250401ms","start":"2026-05-05T06:54:56.381991Z","end":"2026-05-05T06:54:56.695241Z","steps":["trace[912700662] 'agreement among raft nodes before linearized reading' (duration: 313.136939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.695262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.229073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:54:56.695279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:56.381969Z","time spent":"313.302362ms","remote":"127.0.0.1:35446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T06:54:56.695266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.152049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:56.695303Z","caller":"traceutil/trace.go:171","msg":"trace[2040294771] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:588; }","duration":"287.323396ms","start":"2026-05-05T06:54:56.407969Z","end":"2026-05-05T06:54:56.695293Z","steps":["trace[2040294771] 'agreement among raft nodes before linearized reading' (duration: 287.257764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.695306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.99426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:56.695343Z","caller":"traceutil/trace.go:171","msg":"trace[1232648129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"157.044951ms","start":"2026-05-05T06:54:56.538286Z","end":"2026-05-05T06:54:56.695331Z","steps":["trace[1232648129] 'agreement among raft nodes before linearized reading' (duration: 156.97755ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:56.695345Z","caller":"traceutil/trace.go:171","msg":"trace[830774142] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:588; }","duration":"313.233691ms","start":"2026-05-05T06:54:56.382085Z","end":"2026-05-05T06:54:56.695319Z","steps":["trace[830774142] 'agreement among raft nodes before linearized reading' (duration: 313.106828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.695394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:56.382019Z","time spent":"313.359824ms","remote":"127.0.0.1:35446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:54:56.695438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.438226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-brrcn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T06:54:56.695501Z","caller":"traceutil/trace.go:171","msg":"trace[1201972669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-brrcn; range_end:; response_count:1; response_revision:588; }","duration":"275.549318ms","start":"2026-05-05T06:54:56.419933Z","end":"2026-05-05T06:54:56.695482Z","steps":["trace[1201972669] 'agreement among raft nodes before linearized reading' (duration: 275.355552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.695554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.656328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:54:56.695568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.622644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:56.695585Z","caller":"traceutil/trace.go:171","msg":"trace[1277479234] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:588; }","duration":"312.686538ms","start":"2026-05-05T06:54:56.382889Z","end":"2026-05-05T06:54:56.695575Z","steps":["trace[1277479234] 'agreement among raft nodes before linearized reading' (duration: 312.644667ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:56.695593Z","caller":"traceutil/trace.go:171","msg":"trace[1143168206] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:588; }","duration":"287.672545ms","start":"2026-05-05T06:54:56.407913Z","end":"2026-05-05T06:54:56.695586Z","steps":["trace[1143168206] 'agreement among raft nodes before linearized reading' (duration: 287.630854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.695605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:56.382803Z","time spent":"312.797181ms","remote":"127.0.0.1:35446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-05-05T06:54:56.695559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.689056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:54:56.6956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:56.30252Z","time spent":"392.66587ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T06:54:56.695672Z","caller":"traceutil/trace.go:171","msg":"trace[1036876411] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:588; }","duration":"287.89687ms","start":"2026-05-05T06:54:56.407748Z","end":"2026-05-05T06:54:56.695645Z","steps":["trace[1036876411] 'agreement among raft nodes before linearized reading' (duration: 287.624092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:56.695669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.280028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:56.695815Z","caller":"traceutil/trace.go:171","msg":"trace[1087963988] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"157.520214ms","start":"2026-05-05T06:54:56.538271Z","end":"2026-05-05T06:54:56.695791Z","steps":["trace[1087963988] 'agreement among raft nodes before linearized reading' (duration: 157.00877ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:55:12.668833Z","caller":"traceutil/trace.go:171","msg":"trace[1276315985] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"141.991582ms","start":"2026-05-05T06:55:12.526801Z","end":"2026-05-05T06:55:12.668792Z","steps":["trace[1276315985] 'process raft request' (duration: 141.741416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:25.03395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.974307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-05-05T06:56:25.03406Z","caller":"traceutil/trace.go:171","msg":"trace[1276583369] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1805; }","duration":"126.159352ms","start":"2026-05-05T06:56:24.907882Z","end":"2026-05-05T06:56:25.034041Z","steps":["trace[1276583369] 'range keys from in-memory index tree' (duration: 125.765331ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:25.129859Z","caller":"traceutil/trace.go:171","msg":"trace[1823818419] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1879; }","duration":"169.031951ms","start":"2026-05-05T06:56:24.960795Z","end":"2026-05-05T06:56:25.129827Z","steps":["trace[1823818419] 'read index received' (duration: 168.97422ms)","trace[1823818419] 'applied index is now lower than readState.Index' (duration: 56.431µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:56:25.130048Z","caller":"traceutil/trace.go:171","msg":"trace[579700744] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"208.629004ms","start":"2026-05-05T06:56:24.921382Z","end":"2026-05-05T06:56:25.130011Z","steps":["trace[579700744] 'process raft request' (duration: 208.258966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:25.130175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.367179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a3093891-e327-43bf-a32f-24c50516f064\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T06:56:25.130244Z","caller":"traceutil/trace.go:171","msg":"trace[615953252] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a3093891-e327-43bf-a32f-24c50516f064; range_end:; response_count:1; response_revision:1806; }","duration":"169.497742ms","start":"2026-05-05T06:56:24.960729Z","end":"2026-05-05T06:56:25.130227Z","steps":["trace[615953252] 'agreement among raft nodes before linearized reading' (duration: 169.278317ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:25.186158Z","caller":"traceutil/trace.go:171","msg":"trace[254434743] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"214.355907ms","start":"2026-05-05T06:56:24.971775Z","end":"2026-05-05T06:56:25.186131Z","steps":["trace[254434743] 'process raft request' (duration: 214.096821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:56:25.186688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.379406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T06:56:25.186718Z","caller":"traceutil/trace.go:171","msg":"trace[192194551] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"141.53299ms","start":"2026-05-05T06:56:25.045158Z","end":"2026-05-05T06:56:25.186691Z","steps":["trace[192194551] 'process raft request' (duration: 140.906116ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:25.186766Z","caller":"traceutil/trace.go:171","msg":"trace[689973995] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1808; }","duration":"111.52018ms","start":"2026-05-05T06:56:25.075225Z","end":"2026-05-05T06:56:25.186745Z","steps":["trace[689973995] 'agreement among raft nodes before linearized reading' (duration: 111.240304ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:25.478593Z","caller":"traceutil/trace.go:171","msg":"trace[544738462] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"130.345299ms","start":"2026-05-05T06:56:25.348219Z","end":"2026-05-05T06:56:25.478564Z","steps":["trace[544738462] 'process raft request' (duration: 130.151684ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:15.13886Z","caller":"traceutil/trace.go:171","msg":"trace[725287680] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"138.422052ms","start":"2026-05-05T06:57:15.000407Z","end":"2026-05-05T06:57:15.138829Z","steps":["trace[725287680] 'process raft request' (duration: 134.526999ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:57:15.14323Z","caller":"traceutil/trace.go:171","msg":"trace[1771727948] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"142.06254ms","start":"2026-05-05T06:57:15.001127Z","end":"2026-05-05T06:57:15.143189Z","steps":["trace[1771727948] 'process raft request' (duration: 141.908396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:57:15.91327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.248238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:57:15.91351Z","caller":"traceutil/trace.go:171","msg":"trace[1053933025] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"177.231047ms","start":"2026-05-05T06:57:15.736209Z","end":"2026-05-05T06:57:15.91344Z","steps":["trace[1053933025] 'process raft request' (duration: 59.405277ms)","trace[1053933025] 'compare' (duration: 117.143004ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:58:07.465902Z","caller":"traceutil/trace.go:171","msg":"trace[1675094061] linearizableReadLoop","detail":"{readStateIndex:2691; appliedIndex:2690; }","duration":"129.796038ms","start":"2026-05-05T06:58:07.336078Z","end":"2026-05-05T06:58:07.465874Z","steps":["trace[1675094061] 'read index received' (duration: 129.636564ms)","trace[1675094061] 'applied index is now lower than readState.Index' (duration: 158.314µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:58:07.466035Z","caller":"traceutil/trace.go:171","msg":"trace[2141097278] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"184.803573ms","start":"2026-05-05T06:58:07.28119Z","end":"2026-05-05T06:58:07.465994Z","steps":["trace[2141097278] 'process raft request' (duration: 184.503486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:58:07.466188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.072664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:58:07.466242Z","caller":"traceutil/trace.go:171","msg":"trace[1131945568] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2587; }","duration":"130.195567ms","start":"2026-05-05T06:58:07.336031Z","end":"2026-05-05T06:58:07.466226Z","steps":["trace[1131945568] 'agreement among raft nodes before linearized reading' (duration: 129.968722ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:55.314596Z","caller":"traceutil/trace.go:171","msg":"trace[1010639651] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"110.601453ms","start":"2026-05-05T07:02:55.203946Z","end":"2026-05-05T07:02:55.314548Z","steps":["trace[1010639651] 'process raft request' (duration: 110.355877ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:55.314957Z","caller":"traceutil/trace.go:171","msg":"trace[795517673] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"109.627709ms","start":"2026-05-05T07:02:55.205282Z","end":"2026-05-05T07:02:55.314909Z","steps":["trace[795517673] 'process raft request' (duration: 109.181349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:59.485673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.598101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T07:02:59.485871Z","caller":"traceutil/trace.go:171","msg":"trace[989575963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5086; }","duration":"220.858407ms","start":"2026-05-05T07:02:59.264989Z","end":"2026-05-05T07:02:59.485848Z","steps":["trace[989575963] 'range keys from in-memory index tree' (duration: 220.351675ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:59.529364Z","caller":"traceutil/trace.go:171","msg":"trace[220372563] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"201.649988ms","start":"2026-05-05T07:02:59.327683Z","end":"2026-05-05T07:02:59.529333Z","steps":["trace[220372563] 'process raft request' (duration: 200.89096ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:59.548043Z","caller":"traceutil/trace.go:171","msg":"trace[1893001290] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"218.741556ms","start":"2026-05-05T07:02:59.329275Z","end":"2026-05-05T07:02:59.548016Z","steps":["trace[1893001290] 'process raft request' (duration: 218.455919ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:59.548053Z","caller":"traceutil/trace.go:171","msg":"trace[392600246] linearizableReadLoop","detail":"{readStateIndex:5289; appliedIndex:5287; }","duration":"209.789953ms","start":"2026-05-05T07:02:59.338222Z","end":"2026-05-05T07:02:59.548012Z","steps":["trace[392600246] 'read index received' (duration: 190.303849ms)","trace[392600246] 'applied index is now lower than readState.Index' (duration: 19.484834ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:02:59.548092Z","caller":"traceutil/trace.go:171","msg":"trace[1900286054] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"117.305092ms","start":"2026-05-05T07:02:59.430746Z","end":"2026-05-05T07:02:59.548051Z","steps":["trace[1900286054] 'process raft request' (duration: 117.175899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:59.548416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.440474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:02:59.548465Z","caller":"traceutil/trace.go:171","msg":"trace[957724084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5090; }","duration":"167.571738ms","start":"2026-05-05T07:02:59.380882Z","end":"2026-05-05T07:02:59.548454Z","steps":["trace[957724084] 'agreement among raft nodes before linearized reading' (duration: 167.327462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:59.548482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.245984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ede80fce-798d-4870-99fb-52747b685c36\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T07:02:59.548575Z","caller":"traceutil/trace.go:171","msg":"trace[618166547] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ede80fce-798d-4870-99fb-52747b685c36; range_end:; response_count:1; response_revision:5090; }","duration":"210.415337ms","start":"2026-05-05T07:02:59.338143Z","end":"2026-05-05T07:02:59.548558Z","steps":["trace[618166547] 'agreement among raft nodes before linearized reading' (duration: 210.135911ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:59.891173Z","caller":"traceutil/trace.go:171","msg":"trace[1498589318] linearizableReadLoop","detail":"{readStateIndex:5295; appliedIndex:5294; }","duration":"122.345082ms","start":"2026-05-05T07:02:59.768794Z","end":"2026-05-05T07:02:59.891139Z","steps":["trace[1498589318] 'read index received' (duration: 62.839515ms)","trace[1498589318] 'applied index is now lower than readState.Index' (duration: 59.504047ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:02:59.891263Z","caller":"traceutil/trace.go:171","msg":"trace[180142391] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"146.388745ms","start":"2026-05-05T07:02:59.744838Z","end":"2026-05-05T07:02:59.891226Z","steps":["trace[180142391] 'process raft request' (duration: 86.828287ms)","trace[180142391] 'compare' (duration: 59.297552ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:02:59.891537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.715471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T07:02:59.891586Z","caller":"traceutil/trace.go:171","msg":"trace[618288478] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5094; }","duration":"122.836893ms","start":"2026-05-05T07:02:59.768738Z","end":"2026-05-05T07:02:59.891575Z","steps":["trace[618288478] 'agreement among raft nodes before linearized reading' (duration: 122.577777ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:09.754247Z","caller":"traceutil/trace.go:171","msg":"trace[272014803] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"165.271564ms","start":"2026-05-05T07:03:09.588928Z","end":"2026-05-05T07:03:09.7542Z","steps":["trace[272014803] 'process raft request' (duration: 108.907092ms)","trace[272014803] 'compare' (duration: 56.161007ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:09.754394Z","caller":"traceutil/trace.go:171","msg":"trace[1787671147] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"164.082665ms","start":"2026-05-05T07:03:09.590269Z","end":"2026-05-05T07:03:09.754351Z","steps":["trace[1787671147] 'process raft request' (duration: 163.86407ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:09.754648Z","caller":"traceutil/trace.go:171","msg":"trace[1134940401] linearizableReadLoop","detail":"{readStateIndex:5499; appliedIndex:5497; }","duration":"151.701902ms","start":"2026-05-05T07:03:09.602928Z","end":"2026-05-05T07:03:09.75463Z","steps":["trace[1134940401] 'read index received' (duration: 94.700684ms)","trace[1134940401] 'applied index is now lower than readState.Index' (duration: 56.999268ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:03:09.755066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.113751ms","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-05-05T07:03:09.755168Z","caller":"traceutil/trace.go:171","msg":"trace[2026078667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5294; }","duration":"152.238925ms","start":"2026-05-05T07:03:09.602888Z","end":"2026-05-05T07:03:09.755127Z","steps":["trace[2026078667] 'agreement among raft nodes before linearized reading' (duration: 151.962518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:09.758448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.842181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:03:09.758488Z","caller":"traceutil/trace.go:171","msg":"trace[2056940015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5295; }","duration":"103.930183ms","start":"2026-05-05T07:03:09.654547Z","end":"2026-05-05T07:03:09.758477Z","steps":["trace[2056940015] 'agreement among raft nodes before linearized reading' (duration: 103.79047ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:10.111384Z","caller":"traceutil/trace.go:171","msg":"trace[412471629] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"127.122856ms","start":"2026-05-05T07:03:09.984235Z","end":"2026-05-05T07:03:10.111358Z","steps":["trace[412471629] 'process raft request' (duration: 126.588053ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:17.902615Z","caller":"traceutil/trace.go:171","msg":"trace[1462189469] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"119.920224ms","start":"2026-05-05T07:03:17.782668Z","end":"2026-05-05T07:03:17.902588Z","steps":["trace[1462189469] 'process raft request' (duration: 119.74298ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:17.940706Z","caller":"traceutil/trace.go:171","msg":"trace[648578079] linearizableReadLoop","detail":"{readStateIndex:5684; appliedIndex:5681; }","duration":"133.832785ms","start":"2026-05-05T07:03:17.80684Z","end":"2026-05-05T07:03:17.940672Z","steps":["trace[648578079] 'read index received' (duration: 95.664847ms)","trace[648578079] 'applied index is now lower than readState.Index' (duration: 38.166198ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:17.940902Z","caller":"traceutil/trace.go:171","msg":"trace[1345580704] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"156.968375ms","start":"2026-05-05T07:03:17.78392Z","end":"2026-05-05T07:03:17.940889Z","steps":["trace[1345580704] 'process raft request' (duration: 156.519415ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:17.941065Z","caller":"traceutil/trace.go:171","msg":"trace[798035196] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"155.911241ms","start":"2026-05-05T07:03:17.785141Z","end":"2026-05-05T07:03:17.941052Z","steps":["trace[798035196] 'process raft request' (duration: 155.479811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:17.941225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.981718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:03:17.941291Z","caller":"traceutil/trace.go:171","msg":"trace[1309620192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5472; }","duration":"118.107281ms","start":"2026-05-05T07:03:17.823167Z","end":"2026-05-05T07:03:17.941274Z","steps":["trace[1309620192] 'agreement among raft nodes before linearized reading' (duration: 117.942627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:17.941423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.959173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9mb66\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T07:03:17.941437Z","caller":"traceutil/trace.go:171","msg":"trace[73500060] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9mb66; range_end:; response_count:1; response_revision:5472; }","duration":"114.011284ms","start":"2026-05-05T07:03:17.82742Z","end":"2026-05-05T07:03:17.941431Z","steps":["trace[73500060] 'agreement among raft nodes before linearized reading' (duration: 113.960673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:17.941433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.573853ms","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-05-05T07:03:17.941481Z","caller":"traceutil/trace.go:171","msg":"trace[2094495743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5472; }","duration":"134.665455ms","start":"2026-05-05T07:03:17.806787Z","end":"2026-05-05T07:03:17.941453Z","steps":["trace[2094495743] 'agreement among raft nodes before linearized reading' (duration: 134.576953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:18.110702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.04397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:03:18.110932Z","caller":"traceutil/trace.go:171","msg":"trace[1253155127] linearizableReadLoop","detail":"{readStateIndex:5686; appliedIndex:5684; }","duration":"164.483925ms","start":"2026-05-05T07:03:17.946427Z","end":"2026-05-05T07:03:18.110911Z","steps":["trace[1253155127] 'read index received' (duration: 59.121807ms)","trace[1253155127] 'applied index is now lower than readState.Index' (duration: 105.360928ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:18.111053Z","caller":"traceutil/trace.go:171","msg":"trace[217344676] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"164.839433ms","start":"2026-05-05T07:03:17.9462Z","end":"2026-05-05T07:03:18.111039Z","steps":["trace[217344676] 'process raft request' (duration: 164.643068ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:18.111146Z","caller":"traceutil/trace.go:171","msg":"trace[186752660] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"165.749346ms","start":"2026-05-05T07:03:17.945357Z","end":"2026-05-05T07:03:18.111107Z","steps":["trace[186752660] 'process raft request' (duration: 60.192053ms)","trace[186752660] 'compare' (duration: 104.860516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:03:18.112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.406956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80200"} {"level":"info","ts":"2026-05-05T07:03:18.112203Z","caller":"traceutil/trace.go:171","msg":"trace[1730798840] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5474; }","duration":"165.775185ms","start":"2026-05-05T07:03:17.946405Z","end":"2026-05-05T07:03:18.11218Z","steps":["trace[1730798840] 'agreement among raft nodes before linearized reading' (duration: 164.919965ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:18.390597Z","caller":"traceutil/trace.go:171","msg":"trace[82203747] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"127.944814ms","start":"2026-05-05T07:03:18.262614Z","end":"2026-05-05T07:03:18.390558Z","steps":["trace[82203747] 'process raft request' (duration: 127.674848ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:18.548573Z","caller":"traceutil/trace.go:171","msg":"trace[1449482099] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"145.768879ms","start":"2026-05-05T07:03:18.402747Z","end":"2026-05-05T07:03:18.548516Z","steps":["trace[1449482099] 'process raft request' (duration: 110.865138ms)","trace[1449482099] 'compare' (duration: 34.742038ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:04:17.350059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-05-05T07:04:17.42512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"73.386214ms","hash":2080501288,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":33468416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T07:04:17.425194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2080501288,"revision":2988,"compact-revision":-1} {"level":"info","ts":"2026-05-05T07:09:17.35714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6039} {"level":"info","ts":"2026-05-05T07:09:17.457578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6039,"took":"98.043613ms","hash":3355968688,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":21536768,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-05T07:09:17.457666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3355968688,"revision":6039,"compact-revision":2988} {"level":"info","ts":"2026-05-05T07:09:49.543142Z","caller":"traceutil/trace.go:171","msg":"trace[273305936] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"158.952224ms","start":"2026-05-05T07:09:49.384156Z","end":"2026-05-05T07:09:49.543108Z","steps":["trace[273305936] 'process raft request' (duration: 158.76612ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:09:49.57894Z","caller":"traceutil/trace.go:171","msg":"trace[1957047321] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"156.092106ms","start":"2026-05-05T07:09:49.42282Z","end":"2026-05-05T07:09:49.578912Z","steps":["trace[1957047321] 'process raft request' (duration: 155.997834ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:09:49.579098Z","caller":"traceutil/trace.go:171","msg":"trace[367286903] linearizableReadLoop","detail":"{readStateIndex:8735; appliedIndex:8732; }","duration":"128.514029ms","start":"2026-05-05T07:09:49.450567Z","end":"2026-05-05T07:09:49.579081Z","steps":["trace[367286903] 'read index received' (duration: 92.592644ms)","trace[367286903] 'applied index is now lower than readState.Index' (duration: 35.919955ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:09:49.579329Z","caller":"traceutil/trace.go:171","msg":"trace[229148394] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"133.662212ms","start":"2026-05-05T07:09:49.445654Z","end":"2026-05-05T07:09:49.579316Z","steps":["trace[229148394] 'process raft request' (duration: 133.214991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:09:49.579304Z","caller":"traceutil/trace.go:171","msg":"trace[559342664] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"194.890209ms","start":"2026-05-05T07:09:49.384357Z","end":"2026-05-05T07:09:49.579247Z","steps":["trace[559342664] 'process raft request' (duration: 194.310056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:09:49.579385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.805176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-05T07:09:49.579468Z","caller":"traceutil/trace.go:171","msg":"trace[2027966307] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8431; }","duration":"128.924979ms","start":"2026-05-05T07:09:49.450498Z","end":"2026-05-05T07:09:49.579423Z","steps":["trace[2027966307] 'agreement among raft nodes before linearized reading' (duration: 128.657893ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:09:49.579549Z","caller":"traceutil/trace.go:171","msg":"trace[207426670] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"119.326211ms","start":"2026-05-05T07:09:49.46018Z","end":"2026-05-05T07:09:49.579506Z","steps":["trace[207426670] 'process raft request' (duration: 118.803739ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:09:49.721047Z","caller":"traceutil/trace.go:171","msg":"trace[1508484696] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"136.098169ms","start":"2026-05-05T07:09:49.584924Z","end":"2026-05-05T07:09:49.721023Z","steps":["trace[1508484696] 'process raft request' (duration: 135.951486ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:09:50.665587Z","caller":"traceutil/trace.go:171","msg":"trace[411764828] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"293.375554ms","start":"2026-05-05T07:09:50.372164Z","end":"2026-05-05T07:09:50.665539Z","steps":["trace[411764828] 'process raft request' (duration: 251.497608ms)","trace[411764828] 'compare' (duration: 41.451016ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:09:50.665694Z","caller":"traceutil/trace.go:171","msg":"trace[78121455] linearizableReadLoop","detail":"{readStateIndex:8741; appliedIndex:8740; }","duration":"278.093351ms","start":"2026-05-05T07:09:50.387543Z","end":"2026-05-05T07:09:50.665637Z","steps":["trace[78121455] 'read index received' (duration: 236.125732ms)","trace[78121455] 'applied index is now lower than readState.Index' (duration: 41.962118ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:09:50.665869Z","caller":"traceutil/trace.go:171","msg":"trace[1841363070] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"151.841795ms","start":"2026-05-05T07:09:50.514006Z","end":"2026-05-05T07:09:50.665848Z","steps":["trace[1841363070] 'process raft request' (duration: 151.552758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:09:50.666091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.535081ms","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-05-05T07:09:50.66618Z","caller":"traceutil/trace.go:171","msg":"trace[457751599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8436; }","duration":"278.625583ms","start":"2026-05-05T07:09:50.387518Z","end":"2026-05-05T07:09:50.666143Z","steps":["trace[457751599] 'agreement among raft nodes before linearized reading' (duration: 278.382527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:09:50.666086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.306632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T07:09:50.666282Z","caller":"traceutil/trace.go:171","msg":"trace[626282200] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:8436; }","duration":"117.520477ms","start":"2026-05-05T07:09:50.548743Z","end":"2026-05-05T07:09:50.666263Z","steps":["trace[626282200] 'agreement among raft nodes before linearized reading' (duration: 117.19656ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:09:51.825749Z","caller":"traceutil/trace.go:171","msg":"trace[1484685472] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"206.016724ms","start":"2026-05-05T07:09:51.619693Z","end":"2026-05-05T07:09:51.82571Z","steps":["trace[1484685472] 'process raft request' (duration: 179.485452ms)","trace[1484685472] 'compare' (duration: 25.667372ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:10:20.723236Z","caller":"traceutil/trace.go:171","msg":"trace[324261154] linearizableReadLoop","detail":"{readStateIndex:8967; appliedIndex:8966; }","duration":"172.016825ms","start":"2026-05-05T07:10:20.551184Z","end":"2026-05-05T07:10:20.723201Z","steps":["trace[324261154] 'read index received' (duration: 160.408818ms)","trace[324261154] 'applied index is now lower than readState.Index' (duration: 11.606517ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:10:20.723313Z","caller":"traceutil/trace.go:171","msg":"trace[529422666] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"190.223929ms","start":"2026-05-05T07:10:20.533062Z","end":"2026-05-05T07:10:20.723286Z","steps":["trace[529422666] 'process raft request' (duration: 178.606732ms)","trace[529422666] 'compare' (duration: 11.380621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:10:20.723535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.320611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T07:10:20.723629Z","caller":"traceutil/trace.go:171","msg":"trace[1346523334] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:8652; }","duration":"172.461095ms","start":"2026-05-05T07:10:20.551147Z","end":"2026-05-05T07:10:20.723608Z","steps":["trace[1346523334] 'agreement among raft nodes before linearized reading' (duration: 172.179839ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:10:23.906997Z","caller":"traceutil/trace.go:171","msg":"trace[896948987] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"119.569737ms","start":"2026-05-05T07:10:23.787397Z","end":"2026-05-05T07:10:23.906967Z","steps":["trace[896948987] 'process raft request' (duration: 119.359562ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:10:23.934344Z","caller":"traceutil/trace.go:171","msg":"trace[329912274] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"139.44294ms","start":"2026-05-05T07:10:23.794869Z","end":"2026-05-05T07:10:23.934312Z","steps":["trace[329912274] 'process raft request' (duration: 139.216305ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:10:23.934654Z","caller":"traceutil/trace.go:171","msg":"trace[1727310299] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"134.870121ms","start":"2026-05-05T07:10:23.799769Z","end":"2026-05-05T07:10:23.934639Z","steps":["trace[1727310299] 'process raft request' (duration: 134.500592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:10:23.934727Z","caller":"traceutil/trace.go:171","msg":"trace[1938268520] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"133.254072ms","start":"2026-05-05T07:10:23.80144Z","end":"2026-05-05T07:10:23.934694Z","steps":["trace[1938268520] 'process raft request' (duration: 133.078668ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:11:26.104016Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002548.wal"} {"level":"info","ts":"2026-05-05T07:11:53.131106Z","caller":"traceutil/trace.go:171","msg":"trace[240635551] linearizableReadLoop","detail":"{readStateIndex:9869; appliedIndex:9868; }","duration":"173.48171ms","start":"2026-05-05T07:11:52.957593Z","end":"2026-05-05T07:11:53.131075Z","steps":["trace[240635551] 'read index received' (duration: 173.263575ms)","trace[240635551] 'applied index is now lower than readState.Index' (duration: 216.735µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:11:53.131211Z","caller":"traceutil/trace.go:171","msg":"trace[533579326] transaction","detail":"{read_only:false; response_revision:9527; number_of_response:1; }","duration":"186.90587ms","start":"2026-05-05T07:11:52.944268Z","end":"2026-05-05T07:11:53.131174Z","steps":["trace[533579326] 'process raft request' (duration: 186.614933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:11:53.131386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.749207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T07:11:53.131527Z","caller":"traceutil/trace.go:171","msg":"trace[345758036] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:9527; }","duration":"173.962342ms","start":"2026-05-05T07:11:52.957538Z","end":"2026-05-05T07:11:53.1315Z","steps":["trace[345758036] 'agreement among raft nodes before linearized reading' (duration: 173.710336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:12:08.3494Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:12:08.355863Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T07:12:08.356029Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T07:14:17.365191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8087} {"level":"info","ts":"2026-05-05T07:14:17.42988Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8087,"took":"62.503662ms","hash":844632645,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":27811840,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T07:14:17.429965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":844632645,"revision":8087,"compact-revision":6039} {"level":"info","ts":"2026-05-05T07:17:11.712606Z","caller":"traceutil/trace.go:171","msg":"trace[1809754602] transaction","detail":"{read_only:false; response_revision:11848; number_of_response:1; }","duration":"143.958601ms","start":"2026-05-05T07:17:11.568366Z","end":"2026-05-05T07:17:11.712325Z","steps":["trace[1809754602] 'process raft request' (duration: 95.589921ms)","trace[1809754602] 'compare' (duration: 48.146124ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:17:11.712597Z","caller":"traceutil/trace.go:171","msg":"trace[1193427483] transaction","detail":"{read_only:false; response_revision:11849; number_of_response:1; }","duration":"140.59823ms","start":"2026-05-05T07:17:11.571963Z","end":"2026-05-05T07:17:11.712562Z","steps":["trace[1193427483] 'process raft request' (duration: 140.294613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:17:33.596967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.861726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T07:17:33.597724Z","caller":"traceutil/trace.go:171","msg":"trace[340074563] transaction","detail":"{read_only:false; response_revision:11967; number_of_response:1; }","duration":"126.910732ms","start":"2026-05-05T07:17:33.470756Z","end":"2026-05-05T07:17:33.597667Z","steps":["trace[340074563] 'compare' (duration: 117.746872ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:19:17.373433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10490} {"level":"info","ts":"2026-05-05T07:19:17.464976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10490,"took":"89.169127ms","hash":2786299456,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":25948160,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T07:19:17.465076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2786299456,"revision":10490,"compact-revision":8087} {"level":"info","ts":"2026-05-05T07:24:17.380869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12672} {"level":"info","ts":"2026-05-05T07:24:17.462163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12672,"took":"78.510447ms","hash":3188882839,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":27021312,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T07:24:17.462233Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3188882839,"revision":12672,"compact-revision":10490} {"level":"info","ts":"2026-05-05T07:29:17.386591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14640} {"level":"info","ts":"2026-05-05T07:29:17.459802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14640,"took":"70.482125ms","hash":1854181740,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":22798336,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T07:29:17.459863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1854181740,"revision":14640,"compact-revision":12672} {"level":"info","ts":"2026-05-05T07:34:17.393196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16163} {"level":"info","ts":"2026-05-05T07:34:17.46238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16163,"took":"66.314611ms","hash":624792290,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":20439040,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:34:17.462504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":624792290,"revision":16163,"compact-revision":14640} {"level":"info","ts":"2026-05-05T07:39:17.39974Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17683} {"level":"info","ts":"2026-05-05T07:39:17.465797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17683,"took":"62.987202ms","hash":2892638593,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":20439040,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:39:17.465871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2892638593,"revision":17683,"compact-revision":16163} {"level":"info","ts":"2026-05-05T07:39:34.755369Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:39:34.760562Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-05T07:39:34.76069Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-05T07:44:17.406624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19207} {"level":"info","ts":"2026-05-05T07:44:17.473445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19207,"took":"63.871429ms","hash":643353172,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":20545536,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:44:17.473528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":643353172,"revision":19207,"compact-revision":17683} {"level":"info","ts":"2026-05-05T07:49:17.416113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20749} {"level":"info","ts":"2026-05-05T07:49:17.492064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20749,"took":"73.203663ms","hash":3582498293,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":20512768,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:49:17.492155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3582498293,"revision":20749,"compact-revision":19207}