{"level":"warn","ts":"2026-04-10T01:07:23.955579Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-10T01:07:23.955673Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-10T01:07:23.955738Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-10T01:07:23.95575Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-10T01:07:23.955768Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-10T01:07:23.956383Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-10T01:07:23.956456Z","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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-10T01:07:23.960144Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.48764ms"} {"level":"info","ts":"2026-04-10T01:07:23.966119Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-10T01:07:23.966192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T01:07:23.966222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-10T01:07:23.966235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T01:07:23.966253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-10T01:07:23.966291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-10T01:07:23.974026Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T01:07:23.975657Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T01:07:23.976954Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-10T01:07:23.979042Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T01:07:23.979236Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T01:07:23.979379Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T01:07:23.979417Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T01:07:23.97916Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T01:07:23.981036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-10T01:07:23.981626Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-10T01:07:23.985355Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-10T01:07:23.985559Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-10T01:07:23.985684Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-10T01:07:23.985753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T01:07:23.985782Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T01:07:24.766728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T01:07:24.766817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T01:07:24.766866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-10T01:07:24.766889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-10T01:07:24.766907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-10T01:07:24.766922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-10T01:07:24.766936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-10T01:07:24.768082Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:07:24.769102Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T01:07:24.769099Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T01:07:24.769138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T01:07:24.7695Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T01:07:24.769568Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T01:07:24.76991Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:07:24.770019Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:07:24.770053Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:07:24.772596Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-10T01:07:24.772797Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-04-10T01:07:42.712848Z","caller":"traceutil/trace.go:171","msg":"trace[1348586886] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"176.951304ms","start":"2026-04-10T01:07:42.535872Z","end":"2026-04-10T01:07:42.712824Z","steps":["trace[1348586886] 'process raft request' (duration: 176.79961ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:50.898864Z","caller":"traceutil/trace.go:171","msg":"trace[1034890005] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"153.429084ms","start":"2026-04-10T01:07:50.745399Z","end":"2026-04-10T01:07:50.898828Z","steps":["trace[1034890005] 'process raft request' (duration: 153.23891ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.650211Z","caller":"traceutil/trace.go:171","msg":"trace[1337292909] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"287.929749ms","start":"2026-04-10T01:07:53.362245Z","end":"2026-04-10T01:07:53.650174Z","steps":["trace[1337292909] 'process raft request' (duration: 287.828106ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.650413Z","caller":"traceutil/trace.go:171","msg":"trace[2000798021] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"288.216076ms","start":"2026-04-10T01:07:53.362186Z","end":"2026-04-10T01:07:53.650402Z","steps":["trace[2000798021] 'process raft request' (duration: 219.534807ms)","trace[2000798021] 'compare' (duration: 68.171866ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:07:53.650543Z","caller":"traceutil/trace.go:171","msg":"trace[885283793] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"286.926782ms","start":"2026-04-10T01:07:53.363551Z","end":"2026-04-10T01:07:53.650477Z","steps":["trace[885283793] 'process raft request' (duration: 286.608485ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.650659Z","caller":"traceutil/trace.go:171","msg":"trace[912243012] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"287.022945ms","start":"2026-04-10T01:07:53.363627Z","end":"2026-04-10T01:07:53.65065Z","steps":["trace[912243012] 'process raft request' (duration: 286.601355ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.650668Z","caller":"traceutil/trace.go:171","msg":"trace[117554475] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"287.791404ms","start":"2026-04-10T01:07:53.362815Z","end":"2026-04-10T01:07:53.650606Z","steps":["trace[117554475] 'process raft request' (duration: 287.306553ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.650773Z","caller":"traceutil/trace.go:171","msg":"trace[52246057] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"286.908112ms","start":"2026-04-10T01:07:53.363858Z","end":"2026-04-10T01:07:53.650766Z","steps":["trace[52246057] 'process raft request' (duration: 286.43574ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.650806Z","caller":"traceutil/trace.go:171","msg":"trace[722452081] transaction","detail":"{read_only:false; number_of_response:0; response_revision:477; }","duration":"286.797449ms","start":"2026-04-10T01:07:53.36375Z","end":"2026-04-10T01:07:53.650548Z","steps":["trace[722452081] 'process raft request' (duration: 286.514282ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.650903Z","caller":"traceutil/trace.go:171","msg":"trace[1840291350] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"286.102391ms","start":"2026-04-10T01:07:53.364778Z","end":"2026-04-10T01:07:53.650881Z","steps":["trace[1840291350] 'process raft request' (duration: 285.573837ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.796839Z","caller":"traceutil/trace.go:171","msg":"trace[1446400288] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"431.064736ms","start":"2026-04-10T01:07:53.365746Z","end":"2026-04-10T01:07:53.796811Z","steps":["trace[1446400288] 'process raft request' (duration: 430.860021ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.797155Z","caller":"traceutil/trace.go:171","msg":"trace[1485628973] transaction","detail":"{read_only:false; number_of_response:1; response_revision:480; }","duration":"429.001643ms","start":"2026-04-10T01:07:53.368113Z","end":"2026-04-10T01:07:53.797115Z","steps":["trace[1485628973] 'process raft request' (duration: 428.653923ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.797341Z","caller":"traceutil/trace.go:171","msg":"trace[352405050] transaction","detail":"{read_only:false; number_of_response:1; response_revision:480; }","duration":"428.5391ms","start":"2026-04-10T01:07:53.368792Z","end":"2026-04-10T01:07:53.797331Z","steps":["trace[352405050] 'process raft request' (duration: 428.229073ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.797528Z","caller":"traceutil/trace.go:171","msg":"trace[663754180] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"426.340894ms","start":"2026-04-10T01:07:53.371172Z","end":"2026-04-10T01:07:53.797513Z","steps":["trace[663754180] 'process raft request' (duration: 426.047496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:07:53.79774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:07:53.371164Z","time spent":"426.530788ms","remote":"127.0.0.1:36618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:07:53.797788Z","caller":"traceutil/trace.go:171","msg":"trace[1400012953] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"424.178547ms","start":"2026-04-10T01:07:53.373572Z","end":"2026-04-10T01:07:53.79775Z","steps":["trace[1400012953] 'process raft request' (duration: 423.744346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:07:53.797925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:07:53.368777Z","time spent":"428.595301ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:07:53.79794Z","caller":"traceutil/trace.go:171","msg":"trace[958727221] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"421.15269ms","start":"2026-04-10T01:07:53.376743Z","end":"2026-04-10T01:07:53.797895Z","steps":["trace[958727221] 'process raft request' (duration: 420.838141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:07:53.797984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:07:53.373559Z","time spent":"424.350911ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:07:53.798052Z","caller":"traceutil/trace.go:171","msg":"trace[1689210147] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"404.387235ms","start":"2026-04-10T01:07:53.393654Z","end":"2026-04-10T01:07:53.798041Z","steps":["trace[1689210147] 'process raft request' (duration: 404.110958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:07:53.798059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:07:53.365733Z","time spent":"431.200629ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:07:53.798045Z","caller":"traceutil/trace.go:171","msg":"trace[739214583] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:498; }","duration":"144.310398ms","start":"2026-04-10T01:07:53.653711Z","end":"2026-04-10T01:07:53.798022Z","steps":["trace[739214583] 'read index received' (duration: 142.950913ms)","trace[739214583] 'applied index is now lower than readState.Index' (duration: 1.358515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:07:53.798101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:07:53.393577Z","time spent":"404.499617ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:07:53.798072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:07:53.376737Z","time spent":"421.270993ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:07:53.798124Z","caller":"traceutil/trace.go:171","msg":"trace[2062266424] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"262.143869ms","start":"2026-04-10T01:07:53.535967Z","end":"2026-04-10T01:07:53.79811Z","steps":["trace[2062266424] 'process raft request' (duration: 261.970505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:07:53.798196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:07:53.368094Z","time spent":"429.125305ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:07:53.79786Z","caller":"traceutil/trace.go:171","msg":"trace[439050965] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"422.688489ms","start":"2026-04-10T01:07:53.375145Z","end":"2026-04-10T01:07:53.797834Z","steps":["trace[439050965] 'process raft request' (duration: 422.239687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:07:53.798398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:07:53.37514Z","time spent":"423.222082ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:07:53.798247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.525453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-04-10T01:07:53.798673Z","caller":"traceutil/trace.go:171","msg":"trace[2114872146] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:486; }","duration":"144.996236ms","start":"2026-04-10T01:07:53.653655Z","end":"2026-04-10T01:07:53.798651Z","steps":["trace[2114872146] 'agreement among raft nodes before linearized reading' (duration: 144.472992ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.803903Z","caller":"traceutil/trace.go:171","msg":"trace[1810074940] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"136.798283ms","start":"2026-04-10T01:07:53.667085Z","end":"2026-04-10T01:07:53.803883Z","steps":["trace[1810074940] 'process raft request' (duration: 136.735981ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.80415Z","caller":"traceutil/trace.go:171","msg":"trace[2100062365] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"139.802252ms","start":"2026-04-10T01:07:53.664337Z","end":"2026-04-10T01:07:53.804139Z","steps":["trace[2100062365] 'process raft request' (duration: 139.392671ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:53.804223Z","caller":"traceutil/trace.go:171","msg":"trace[930098513] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"143.940659ms","start":"2026-04-10T01:07:53.660177Z","end":"2026-04-10T01:07:53.804118Z","steps":["trace[930098513] 'process raft request' (duration: 143.431265ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:59.818767Z","caller":"traceutil/trace.go:171","msg":"trace[1673527338] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"291.232194ms","start":"2026-04-10T01:07:59.527508Z","end":"2026-04-10T01:07:59.81874Z","steps":["trace[1673527338] 'process raft request' (duration: 290.278099ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:59.831393Z","caller":"traceutil/trace.go:171","msg":"trace[1301068947] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"163.161006ms","start":"2026-04-10T01:07:59.668213Z","end":"2026-04-10T01:07:59.831374Z","steps":["trace[1301068947] 'process raft request' (duration: 163.071434ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:08:00.102769Z","caller":"traceutil/trace.go:171","msg":"trace[1791956913] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"107.328869ms","start":"2026-04-10T01:07:59.9954Z","end":"2026-04-10T01:08:00.102729Z","steps":["trace[1791956913] 'process raft request' (duration: 46.34287ms)","trace[1791956913] 'compare' (duration: 60.864916ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:08:00.680982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.2009ms","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-04-10T01:08:00.681186Z","caller":"traceutil/trace.go:171","msg":"trace[631002746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"404.478328ms","start":"2026-04-10T01:08:00.276679Z","end":"2026-04-10T01:08:00.681157Z","steps":["trace[631002746] 'range keys from in-memory index tree' (duration: 403.979244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:00.681287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:08:00.276662Z","time spent":"404.606671ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-10T01:08:00.683824Z","caller":"traceutil/trace.go:171","msg":"trace[657512166] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"343.20338ms","start":"2026-04-10T01:08:00.340596Z","end":"2026-04-10T01:08:00.683799Z","steps":["trace[657512166] 'read index received' (duration: 342.228825ms)","trace[657512166] 'applied index is now lower than readState.Index' (duration: 973.615µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:08:00.683901Z","caller":"traceutil/trace.go:171","msg":"trace[1457051603] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"385.472376ms","start":"2026-04-10T01:08:00.298392Z","end":"2026-04-10T01:08:00.683864Z","steps":["trace[1457051603] 'process raft request' (duration: 384.87765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:00.683952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.350114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sw75m\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-10T01:08:00.684017Z","caller":"traceutil/trace.go:171","msg":"trace[1833758627] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sw75m; range_end:; response_count:1; response_revision:577; }","duration":"343.455367ms","start":"2026-04-10T01:08:00.340544Z","end":"2026-04-10T01:08:00.684Z","steps":["trace[1833758627] 'agreement among raft nodes before linearized reading' (duration: 343.335424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:00.684072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:08:00.340524Z","time spent":"343.535009ms","remote":"127.0.0.1:36314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-sw75m\" "} {"level":"warn","ts":"2026-04-10T01:08:00.684197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:08:00.298363Z","time spent":"385.66585ms","remote":"127.0.0.1:36220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:08:16.243345Z","caller":"traceutil/trace.go:171","msg":"trace[1525872555] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"221.985771ms","start":"2026-04-10T01:08:16.021335Z","end":"2026-04-10T01:08:16.243321Z","steps":["trace[1525872555] 'process raft request' (duration: 221.773136ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:08:16.419509Z","caller":"traceutil/trace.go:171","msg":"trace[1893358059] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"171.737654ms","start":"2026-04-10T01:08:16.247747Z","end":"2026-04-10T01:08:16.419485Z","steps":["trace[1893358059] 'process raft request' (duration: 140.510067ms)","trace[1893358059] 'compare' (duration: 31.130524ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:08:46.568952Z","caller":"traceutil/trace.go:171","msg":"trace[1621038093] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"178.233203ms","start":"2026-04-10T01:08:46.390696Z","end":"2026-04-10T01:08:46.568929Z","steps":["trace[1621038093] 'process raft request' (duration: 178.084809ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.161678Z","caller":"traceutil/trace.go:171","msg":"trace[592024665] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1868; }","duration":"311.177456ms","start":"2026-04-10T01:09:30.850414Z","end":"2026-04-10T01:09:31.161592Z","steps":["trace[592024665] 'read index received' (duration: 310.950421ms)","trace[592024665] 'applied index is now lower than readState.Index' (duration: 225.885µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:09:31.161735Z","caller":"traceutil/trace.go:171","msg":"trace[1179947535] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"341.105729ms","start":"2026-04-10T01:09:30.820591Z","end":"2026-04-10T01:09:31.161697Z","steps":["trace[1179947535] 'process raft request' (duration: 340.779051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:31.161944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:09:30.820562Z","time spent":"341.275403ms","remote":"127.0.0.1:36314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10966,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:09:31.162104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.369845ms","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":"warn","ts":"2026-04-10T01:09:31.162016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.568487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-10T01:09:31.162184Z","caller":"traceutil/trace.go:171","msg":"trace[1312347231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1802; }","duration":"284.451307ms","start":"2026-04-10T01:09:30.877715Z","end":"2026-04-10T01:09:31.162166Z","steps":["trace[1312347231] 'agreement among raft nodes before linearized reading' (duration: 284.214341ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.162194Z","caller":"traceutil/trace.go:171","msg":"trace[1481924357] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1802; }","duration":"311.806104ms","start":"2026-04-10T01:09:30.850363Z","end":"2026-04-10T01:09:31.162169Z","steps":["trace[1481924357] 'agreement among raft nodes before linearized reading' (duration: 311.479855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:31.162251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:09:30.850342Z","time spent":"311.898316ms","remote":"127.0.0.1:48766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-10T01:09:31.162298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.893197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-10T01:09:31.162425Z","caller":"traceutil/trace.go:171","msg":"trace[2127348985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1802; }","duration":"207.044931ms","start":"2026-04-10T01:09:30.955341Z","end":"2026-04-10T01:09:31.162386Z","steps":["trace[2127348985] 'agreement among raft nodes before linearized reading' (duration: 206.800275ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.326584Z","caller":"traceutil/trace.go:171","msg":"trace[1547153279] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"157.843081ms","start":"2026-04-10T01:09:31.16871Z","end":"2026-04-10T01:09:31.326553Z","steps":["trace[1547153279] 'process raft request' (duration: 157.509282ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.326618Z","caller":"traceutil/trace.go:171","msg":"trace[1642985098] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"157.810251ms","start":"2026-04-10T01:09:31.168783Z","end":"2026-04-10T01:09:31.326593Z","steps":["trace[1642985098] 'process raft request' (duration: 157.660138ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.327254Z","caller":"traceutil/trace.go:171","msg":"trace[32968399] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1869; }","duration":"157.641477ms","start":"2026-04-10T01:09:31.169597Z","end":"2026-04-10T01:09:31.327238Z","steps":["trace[32968399] 'read index received' (duration: 80.908038ms)","trace[32968399] 'applied index is now lower than readState.Index' (duration: 76.732399ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:09:31.327294Z","caller":"traceutil/trace.go:171","msg":"trace[1687344360] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"157.372579ms","start":"2026-04-10T01:09:31.169907Z","end":"2026-04-10T01:09:31.327279Z","steps":["trace[1687344360] 'process raft request' (duration: 157.231875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:31.327349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.738959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:09:31.327386Z","caller":"traceutil/trace.go:171","msg":"trace[1252999792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1810; }","duration":"157.823521ms","start":"2026-04-10T01:09:31.169553Z","end":"2026-04-10T01:09:31.327377Z","steps":["trace[1252999792] 'agreement among raft nodes before linearized reading' (duration: 157.7504ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.327412Z","caller":"traceutil/trace.go:171","msg":"trace[655974780] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"157.604025ms","start":"2026-04-10T01:09:31.169797Z","end":"2026-04-10T01:09:31.327401Z","steps":["trace[655974780] 'process raft request' (duration: 157.291456ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.327526Z","caller":"traceutil/trace.go:171","msg":"trace[357333568] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"158.5564ms","start":"2026-04-10T01:09:31.168951Z","end":"2026-04-10T01:09:31.327508Z","steps":["trace[357333568] 'process raft request' (duration: 157.577495ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.327577Z","caller":"traceutil/trace.go:171","msg":"trace[61067233] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"158.330164ms","start":"2026-04-10T01:09:31.169212Z","end":"2026-04-10T01:09:31.327542Z","steps":["trace[61067233] 'process raft request' (duration: 157.80109ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.327621Z","caller":"traceutil/trace.go:171","msg":"trace[827097872] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"153.465239ms","start":"2026-04-10T01:09:31.174123Z","end":"2026-04-10T01:09:31.327588Z","steps":["trace[827097872] 'process raft request' (duration: 153.066109ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:31.625622Z","caller":"traceutil/trace.go:171","msg":"trace[261082711] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"226.03164ms","start":"2026-04-10T01:09:31.399525Z","end":"2026-04-10T01:09:31.625557Z","steps":["trace[261082711] 'process raft request' (duration: 223.360151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:43.319884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.910031ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-10T01:09:47.571586Z","caller":"traceutil/trace.go:171","msg":"trace[1320955412] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"141.307045ms","start":"2026-04-10T01:09:47.430254Z","end":"2026-04-10T01:09:47.571561Z","steps":["trace[1320955412] 'read index received' (duration: 141.040158ms)","trace[1320955412] 'applied index is now lower than readState.Index' (duration: 265.787µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:09:47.571649Z","caller":"traceutil/trace.go:171","msg":"trace[233833062] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"145.467633ms","start":"2026-04-10T01:09:47.426146Z","end":"2026-04-10T01:09:47.571614Z","steps":["trace[233833062] 'process raft request' (duration: 145.236176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:47.571841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.564112ms","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-04-10T01:09:47.5719Z","caller":"traceutil/trace.go:171","msg":"trace[1486553945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1938; }","duration":"141.661123ms","start":"2026-04-10T01:09:47.430229Z","end":"2026-04-10T01:09:47.57189Z","steps":["trace[1486553945] 'agreement among raft nodes before linearized reading' (duration: 141.465979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:09:47.571921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.025632ms","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-04-10T01:09:47.572034Z","caller":"traceutil/trace.go:171","msg":"trace[158157125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1938; }","duration":"140.179805ms","start":"2026-04-10T01:09:47.431832Z","end":"2026-04-10T01:09:47.572012Z","steps":["trace[158157125] 'agreement among raft nodes before linearized reading' (duration: 139.95588ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:09:48.011757Z","caller":"traceutil/trace.go:171","msg":"trace[1235147450] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"164.703028ms","start":"2026-04-10T01:09:47.847028Z","end":"2026-04-10T01:09:48.011731Z","steps":["trace[1235147450] 'process raft request' (duration: 164.501453ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:14.217457Z","caller":"traceutil/trace.go:171","msg":"trace[1473421237] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"113.305203ms","start":"2026-04-10T01:10:14.104122Z","end":"2026-04-10T01:10:14.217428Z","steps":["trace[1473421237] 'process raft request' (duration: 113.139399ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:19.862781Z","caller":"traceutil/trace.go:171","msg":"trace[1818811656] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"125.177389ms","start":"2026-04-10T01:10:19.737567Z","end":"2026-04-10T01:10:19.862745Z","steps":["trace[1818811656] 'process raft request' (duration: 124.951942ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:19.967147Z","caller":"traceutil/trace.go:171","msg":"trace[909451444] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"104.453054ms","start":"2026-04-10T01:10:19.862672Z","end":"2026-04-10T01:10:19.967125Z","steps":["trace[909451444] 'read index received' (duration: 72.816218ms)","trace[909451444] 'applied index is now lower than readState.Index' (duration: 31.635846ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:10:19.967208Z","caller":"traceutil/trace.go:171","msg":"trace[825818538] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"138.968655ms","start":"2026-04-10T01:10:19.828209Z","end":"2026-04-10T01:10:19.967177Z","steps":["trace[825818538] 'process raft request' (duration: 107.212417ms)","trace[825818538] 'compare' (duration: 31.580664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:10:19.967395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.058074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T01:10:19.96747Z","caller":"traceutil/trace.go:171","msg":"trace[1286847544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2122; }","duration":"116.173457ms","start":"2026-04-10T01:10:19.85128Z","end":"2026-04-10T01:10:19.967453Z","steps":["trace[1286847544] 'agreement among raft nodes before linearized reading' (duration: 115.926531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:19.967562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.65348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-10T01:10:19.967681Z","caller":"traceutil/trace.go:171","msg":"trace[368738978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2122; }","duration":"118.813615ms","start":"2026-04-10T01:10:19.848851Z","end":"2026-04-10T01:10:19.967665Z","steps":["trace[368738978] 'agreement among raft nodes before linearized reading' (duration: 118.384404ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:25.952944Z","caller":"traceutil/trace.go:171","msg":"trace[932532637] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"103.086561ms","start":"2026-04-10T01:10:25.84983Z","end":"2026-04-10T01:10:25.952917Z","steps":["trace[932532637] 'process raft request' (duration: 102.824644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:26.267688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.655754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:10:26.267869Z","caller":"traceutil/trace.go:171","msg":"trace[1626877740] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"310.554978ms","start":"2026-04-10T01:10:25.957269Z","end":"2026-04-10T01:10:26.267824Z","steps":["trace[1626877740] 'process raft request' (duration: 150.669198ms)","trace[1626877740] 'compare' (duration: 159.559021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:10:26.268019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:25.957249Z","time spent":"310.698121ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:10:26.268023Z","caller":"traceutil/trace.go:171","msg":"trace[869176698] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2230; }","duration":"275.382912ms","start":"2026-04-10T01:10:25.992625Z","end":"2026-04-10T01:10:26.268007Z","steps":["trace[869176698] 'read index received' (duration: 115.269427ms)","trace[869176698] 'applied index is now lower than readState.Index' (duration: 160.112295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:10:26.268222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.592488ms","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-04-10T01:10:26.268262Z","caller":"traceutil/trace.go:171","msg":"trace[66769499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2153; }","duration":"275.633239ms","start":"2026-04-10T01:10:25.992619Z","end":"2026-04-10T01:10:26.268253Z","steps":["trace[66769499] 'agreement among raft nodes before linearized reading' (duration: 275.521247ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:26.56754Z","caller":"traceutil/trace.go:171","msg":"trace[245905670] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"308.486356ms","start":"2026-04-10T01:10:26.259013Z","end":"2026-04-10T01:10:26.567499Z","steps":["trace[245905670] 'process raft request' (duration: 308.240279ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:26.567418Z","caller":"traceutil/trace.go:171","msg":"trace[895781990] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"299.334705ms","start":"2026-04-10T01:10:26.268057Z","end":"2026-04-10T01:10:26.567392Z","steps":["trace[895781990] 'read index received' (duration: 299.214201ms)","trace[895781990] 'applied index is now lower than readState.Index' (duration: 119.624µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:10:26.567612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.417492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:10:26.56764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.351275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130724"} {"level":"info","ts":"2026-04-10T01:10:26.567684Z","caller":"traceutil/trace.go:171","msg":"trace[556357662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2154; }","duration":"399.544495ms","start":"2026-04-10T01:10:26.168119Z","end":"2026-04-10T01:10:26.567663Z","steps":["trace[556357662] 'agreement among raft nodes before linearized reading' (duration: 399.389451ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:10:26.567704Z","caller":"traceutil/trace.go:171","msg":"trace[488032480] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2154; }","duration":"542.437007ms","start":"2026-04-10T01:10:26.025251Z","end":"2026-04-10T01:10:26.567688Z","steps":["trace[488032480] 'agreement among raft nodes before linearized reading' (duration: 542.183081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:26.567689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.587439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-10T01:10:26.56772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:26.168099Z","time spent":"399.615377ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:10:26.567741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:26.025241Z","time spent":"542.492168ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130747,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-10T01:10:26.567576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.056403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-10T01:10:26.56775Z","caller":"traceutil/trace.go:171","msg":"trace[1722737014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2154; }","duration":"563.692681ms","start":"2026-04-10T01:10:26.004041Z","end":"2026-04-10T01:10:26.567734Z","steps":["trace[1722737014] 'agreement among raft nodes before linearized reading' (duration: 563.512667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:26.567757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:26.258992Z","time spent":"308.646869ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:10:26.567798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:26.004015Z","time spent":"563.775073ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-10T01:10:26.567915Z","caller":"traceutil/trace.go:171","msg":"trace[1155664704] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2154; }","duration":"545.393191ms","start":"2026-04-10T01:10:26.022489Z","end":"2026-04-10T01:10:26.567882Z","steps":["trace[1155664704] 'agreement among raft nodes before linearized reading' (duration: 545.000241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:26.568181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:26.022453Z","time spent":"545.538964ms","remote":"127.0.0.1:36196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-10T01:10:26.56827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.256131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T01:10:26.568456Z","caller":"traceutil/trace.go:171","msg":"trace[922118784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2154; }","duration":"569.360434ms","start":"2026-04-10T01:10:25.998978Z","end":"2026-04-10T01:10:26.568339Z","steps":["trace[922118784] 'agreement among raft nodes before linearized reading' (duration: 568.665996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:26.568503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:25.998967Z","time spent":"569.525377ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-10T01:10:26.58527Z","caller":"traceutil/trace.go:171","msg":"trace[1987845285] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"313.781438ms","start":"2026-04-10T01:10:26.271472Z","end":"2026-04-10T01:10:26.585253Z","steps":["trace[1987845285] 'process raft request' (duration: 313.673595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:10:26.5854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:10:26.271453Z","time spent":"313.86698ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:10:26.585544Z","caller":"traceutil/trace.go:171","msg":"trace[2068555907] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"204.46721ms","start":"2026-04-10T01:10:26.381054Z","end":"2026-04-10T01:10:26.585522Z","steps":["trace[2068555907] 'process raft request' (duration: 204.166083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:55.940772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675952423982445,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T01:11:56.420487Z","caller":"traceutil/trace.go:171","msg":"trace[1521687812] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"980.500419ms","start":"2026-04-10T01:11:55.439957Z","end":"2026-04-10T01:11:56.420458Z","steps":["trace[1521687812] 'process raft request' (duration: 980.290304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.420724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.439938Z","time spent":"980.668243ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:11:56.42492Z","caller":"traceutil/trace.go:171","msg":"trace[673491657] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"984.734173ms","start":"2026-04-10T01:11:55.440174Z","end":"2026-04-10T01:11:56.424908Z","steps":["trace[673491657] 'process raft request' (duration: 984.679462ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:11:56.424921Z","caller":"traceutil/trace.go:171","msg":"trace[111815068] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"984.742554ms","start":"2026-04-10T01:11:55.440156Z","end":"2026-04-10T01:11:56.424899Z","steps":["trace[111815068] 'process raft request' (duration: 984.631201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.424991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.440157Z","time spent":"984.798065ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:11:56.424993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.440151Z","time spent":"984.808955ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:11:56.425249Z","caller":"traceutil/trace.go:171","msg":"trace[1227513852] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"982.749555ms","start":"2026-04-10T01:11:55.44246Z","end":"2026-04-10T01:11:56.425209Z","steps":["trace[1227513852] 'process raft request' (duration: 982.421906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.42541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.325578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-10T01:11:56.425436Z","caller":"traceutil/trace.go:171","msg":"trace[1830922642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2827; }","duration":"985.362609ms","start":"2026-04-10T01:11:55.440067Z","end":"2026-04-10T01:11:56.425429Z","steps":["trace[1830922642] 'agreement among raft nodes before linearized reading' (duration: 985.268607ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:11:56.425444Z","caller":"traceutil/trace.go:171","msg":"trace[2007440108] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"981.517444ms","start":"2026-04-10T01:11:55.44391Z","end":"2026-04-10T01:11:56.425427Z","steps":["trace[2007440108] 'process raft request' (duration: 981.33455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.425459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.44006Z","time spent":"985.394051ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T01:11:56.42544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.442451Z","time spent":"982.913878ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:11:56.425498Z","caller":"traceutil/trace.go:171","msg":"trace[2128197907] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"982.15294ms","start":"2026-04-10T01:11:55.443328Z","end":"2026-04-10T01:11:56.425481Z","steps":["trace[2128197907] 'process raft request' (duration: 981.815912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.425512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.443904Z","time spent":"981.571765ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:11:56.425317Z","caller":"traceutil/trace.go:171","msg":"trace[2066941153] linearizableReadLoop","detail":"{readStateIndex:2933; appliedIndex:2927; }","duration":"985.227125ms","start":"2026-04-10T01:11:55.44008Z","end":"2026-04-10T01:11:56.425307Z","steps":["trace[2066941153] 'read index received' (duration: 980.104678ms)","trace[2066941153] 'applied index is now lower than readState.Index' (duration: 5.121897ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:11:56.425572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.443321Z","time spent":"982.211631ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:11:56.42567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.317213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:11:56.425697Z","caller":"traceutil/trace.go:171","msg":"trace[21659379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2827; }","duration":"982.352074ms","start":"2026-04-10T01:11:55.443337Z","end":"2026-04-10T01:11:56.425689Z","steps":["trace[21659379] 'agreement among raft nodes before linearized reading' (duration: 982.308723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.425724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.443332Z","time spent":"982.379495ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:11:56.425751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.930843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-10T01:11:56.425804Z","caller":"traceutil/trace.go:171","msg":"trace[1426393074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2827; }","duration":"984.005405ms","start":"2026-04-10T01:11:55.441784Z","end":"2026-04-10T01:11:56.42579Z","steps":["trace[1426393074] 'agreement among raft nodes before linearized reading' (duration: 983.852952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.425897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.441779Z","time spent":"984.111059ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:11:56.427927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.648722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-04-10T01:11:56.427964Z","caller":"traceutil/trace.go:171","msg":"trace[2094362178] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2827; }","duration":"984.693153ms","start":"2026-04-10T01:11:55.443262Z","end":"2026-04-10T01:11:56.427955Z","steps":["trace[2094362178] 'agreement among raft nodes before linearized reading' (duration: 984.60422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.427984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.443257Z","time spent":"984.720923ms","remote":"127.0.0.1:36324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1468,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-04-10T01:11:56.428248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.302582ms","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-04-10T01:11:56.42831Z","caller":"traceutil/trace.go:171","msg":"trace[401319618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2827; }","duration":"986.399295ms","start":"2026-04-10T01:11:55.441894Z","end":"2026-04-10T01:11:56.428294Z","steps":["trace[401319618] 'agreement among raft nodes before linearized reading' (duration: 986.21276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:11:56.428339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:11:55.44188Z","time spent":"986.453556ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T01:13:54.576168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.902745ms","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-04-10T01:13:54.576314Z","caller":"traceutil/trace.go:171","msg":"trace[524106620] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:3615; }","duration":"102.096349ms","start":"2026-04-10T01:13:54.47419Z","end":"2026-04-10T01:13:54.576286Z","steps":["trace[524106620] 'count revisions from in-memory index tree' (duration: 101.801291ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:15:44.826541Z","caller":"traceutil/trace.go:171","msg":"trace[405876412] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4773; }","duration":"172.839673ms","start":"2026-04-10T01:15:44.653669Z","end":"2026-04-10T01:15:44.826509Z","steps":["trace[405876412] 'process raft request' (duration: 140.579194ms)","trace[405876412] 'compare' (duration: 31.932241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:15:44.8267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.718031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T01:15:44.826771Z","caller":"traceutil/trace.go:171","msg":"trace[626636481] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4773; }","duration":"116.844924ms","start":"2026-04-10T01:15:44.709911Z","end":"2026-04-10T01:15:44.826756Z","steps":["trace[626636481] 'agreement among raft nodes before linearized reading' (duration: 116.705791ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:15:44.826397Z","caller":"traceutil/trace.go:171","msg":"trace[1567351250] linearizableReadLoop","detail":"{readStateIndex:4954; appliedIndex:4953; }","duration":"116.417963ms","start":"2026-04-10T01:15:44.70995Z","end":"2026-04-10T01:15:44.826368Z","steps":["trace[1567351250] 'read index received' (duration: 84.393479ms)","trace[1567351250] 'applied index is now lower than readState.Index' (duration: 32.023614ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:15:46.939528Z","caller":"traceutil/trace.go:171","msg":"trace[1996643485] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5024; }","duration":"139.792369ms","start":"2026-04-10T01:15:46.799703Z","end":"2026-04-10T01:15:46.939495Z","steps":["trace[1996643485] 'read index received' (duration: 125.524677ms)","trace[1996643485] 'applied index is now lower than readState.Index' (duration: 14.266412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:15:46.939774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.26245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-04-10T01:15:46.939863Z","caller":"traceutil/trace.go:171","msg":"trace[1960175231] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4839; }","duration":"148.363783ms","start":"2026-04-10T01:15:46.791482Z","end":"2026-04-10T01:15:46.939846Z","steps":["trace[1960175231] 'agreement among raft nodes before linearized reading' (duration: 148.168818ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:15:46.939634Z","caller":"traceutil/trace.go:171","msg":"trace[1832124430] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4839; }","duration":"148.052984ms","start":"2026-04-10T01:15:46.791528Z","end":"2026-04-10T01:15:46.939581Z","steps":["trace[1832124430] 'process raft request' (duration: 133.68425ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:15:46.961955Z","caller":"traceutil/trace.go:171","msg":"trace[1090347542] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"112.88579ms","start":"2026-04-10T01:15:46.849034Z","end":"2026-04-10T01:15:46.961919Z","steps":["trace[1090347542] 'process raft request' (duration: 112.607722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:46.962105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.161728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-10T01:15:46.96221Z","caller":"traceutil/trace.go:171","msg":"trace[1829386509] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4840; }","duration":"158.28256ms","start":"2026-04-10T01:15:46.803893Z","end":"2026-04-10T01:15:46.962175Z","steps":["trace[1829386509] 'agreement among raft nodes before linearized reading' (duration: 157.973453ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:15:47.126952Z","caller":"traceutil/trace.go:171","msg":"trace[282757973] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"145.533919ms","start":"2026-04-10T01:15:46.981383Z","end":"2026-04-10T01:15:47.126917Z","steps":["trace[282757973] 'process raft request' (duration: 65.653049ms)","trace[282757973] 'compare' (duration: 79.651624ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:15:47.127078Z","caller":"traceutil/trace.go:171","msg":"trace[648112712] linearizableReadLoop","detail":"{readStateIndex:5029; appliedIndex:5027; }","duration":"103.809302ms","start":"2026-04-10T01:15:47.023236Z","end":"2026-04-10T01:15:47.127045Z","steps":["trace[648112712] 'read index received' (duration: 23.729717ms)","trace[648112712] 'applied index is now lower than readState.Index' (duration: 80.064245ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:15:47.127107Z","caller":"traceutil/trace.go:171","msg":"trace[507519200] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"142.826678ms","start":"2026-04-10T01:15:46.98424Z","end":"2026-04-10T01:15:47.127067Z","steps":["trace[507519200] 'process raft request' (duration: 142.593453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:47.127379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.87451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-04-10T01:15:47.127427Z","caller":"traceutil/trace.go:171","msg":"trace[1213420187] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4843; }","duration":"109.988953ms","start":"2026-04-10T01:15:47.017423Z","end":"2026-04-10T01:15:47.127412Z","steps":["trace[1213420187] 'agreement among raft nodes before linearized reading' (duration: 109.791678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:47.127439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.064555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-10T01:15:47.127509Z","caller":"traceutil/trace.go:171","msg":"trace[392497692] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4843; }","duration":"100.170028ms","start":"2026-04-10T01:15:47.027321Z","end":"2026-04-10T01:15:47.127491Z","steps":["trace[392497692] 'agreement among raft nodes before linearized reading' (duration: 99.946752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:47.127575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.540136ms","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:7"} {"level":"info","ts":"2026-04-10T01:15:47.127639Z","caller":"traceutil/trace.go:171","msg":"trace[617738422] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4843; }","duration":"118.647268ms","start":"2026-04-10T01:15:47.008973Z","end":"2026-04-10T01:15:47.12762Z","steps":["trace[617738422] 'agreement among raft nodes before linearized reading' (duration: 118.518265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:52.07963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.441301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-167cac6d-a74d-40e7-b42d-7351d6e3595a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-10T01:15:52.079737Z","caller":"traceutil/trace.go:171","msg":"trace[439548219] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-167cac6d-a74d-40e7-b42d-7351d6e3595a; range_end:; response_count:1; response_revision:5012; }","duration":"222.598375ms","start":"2026-04-10T01:15:51.857116Z","end":"2026-04-10T01:15:52.079715Z","steps":["trace[439548219] 'range keys from in-memory index tree' (duration: 222.224385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:52.079758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.591507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-04-10T01:15:52.079879Z","caller":"traceutil/trace.go:171","msg":"trace[1236327693] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5012; }","duration":"272.766111ms","start":"2026-04-10T01:15:51.807086Z","end":"2026-04-10T01:15:52.079852Z","steps":["trace[1236327693] 'range keys from in-memory index tree' (duration: 272.245667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:15:52.079995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.216666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777674"} {"level":"info","ts":"2026-04-10T01:15:52.080114Z","caller":"traceutil/trace.go:171","msg":"trace[261395541] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5012; }","duration":"146.37422ms","start":"2026-04-10T01:15:51.933714Z","end":"2026-04-10T01:15:52.080088Z","steps":["trace[261395541] 'range keys from in-memory index tree' (duration: 145.629651ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:15:53.852558Z","caller":"traceutil/trace.go:171","msg":"trace[391002065] linearizableReadLoop","detail":"{readStateIndex:5233; appliedIndex:5232; }","duration":"134.921383ms","start":"2026-04-10T01:15:53.717519Z","end":"2026-04-10T01:15:53.85244Z","steps":["trace[391002065] 'read index received' (duration: 67.20803ms)","trace[391002065] 'applied index is now lower than readState.Index' (duration: 67.711953ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:15:53.852582Z","caller":"traceutil/trace.go:171","msg":"trace[1800870577] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"146.657467ms","start":"2026-04-10T01:15:53.70589Z","end":"2026-04-10T01:15:53.852547Z","steps":["trace[1800870577] 'process raft request' (duration: 78.810071ms)","trace[1800870577] 'compare' (duration: 67.519058ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:15:53.852834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.283161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-04-10T01:15:53.852851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.756728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T01:15:53.85291Z","caller":"traceutil/trace.go:171","msg":"trace[815012209] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5042; }","duration":"134.891432ms","start":"2026-04-10T01:15:53.718003Z","end":"2026-04-10T01:15:53.852895Z","steps":["trace[815012209] 'agreement among raft nodes before linearized reading' (duration: 134.716807ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:15:53.852918Z","caller":"traceutil/trace.go:171","msg":"trace[1446435571] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5042; }","duration":"135.379195ms","start":"2026-04-10T01:15:53.717512Z","end":"2026-04-10T01:15:53.852891Z","steps":["trace[1446435571] 'agreement among raft nodes before linearized reading' (duration: 135.145678ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:16:11.121928Z","caller":"traceutil/trace.go:171","msg":"trace[1313029023] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"247.559464ms","start":"2026-04-10T01:16:10.874345Z","end":"2026-04-10T01:16:11.121905Z","steps":["trace[1313029023] 'process raft request' (duration: 247.40822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:16:17.951395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.875829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:16:17.951519Z","caller":"traceutil/trace.go:171","msg":"trace[1290580882] linearizableReadLoop","detail":"{readStateIndex:5714; appliedIndex:5713; }","duration":"157.576152ms","start":"2026-04-10T01:16:17.793927Z","end":"2026-04-10T01:16:17.951504Z","steps":["trace[1290580882] 'read index received' (duration: 34.495639ms)","trace[1290580882] 'applied index is now lower than readState.Index' (duration: 123.079073ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:16:17.951547Z","caller":"traceutil/trace.go:171","msg":"trace[1765685229] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"158.793083ms","start":"2026-04-10T01:16:17.792717Z","end":"2026-04-10T01:16:17.95151Z","steps":["trace[1765685229] 'process raft request' (duration: 35.70225ms)","trace[1765685229] 'compare' (duration: 122.733424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:16:17.951652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.719486ms","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-04-10T01:16:17.95169Z","caller":"traceutil/trace.go:171","msg":"trace[1880753488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5512; }","duration":"157.797598ms","start":"2026-04-10T01:16:17.793882Z","end":"2026-04-10T01:16:17.951679Z","steps":["trace[1880753488] 'agreement among raft nodes before linearized reading' (duration: 157.691386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:16:17.951695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.075074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-10T01:16:17.951725Z","caller":"traceutil/trace.go:171","msg":"trace[1451719326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5512; }","duration":"156.133114ms","start":"2026-04-10T01:16:17.79558Z","end":"2026-04-10T01:16:17.951713Z","steps":["trace[1451719326] 'agreement among raft nodes before linearized reading' (duration: 156.013941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:16:17.951859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.612921ms","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-04-10T01:16:17.951882Z","caller":"traceutil/trace.go:171","msg":"trace[556629160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5512; }","duration":"150.653341ms","start":"2026-04-10T01:16:17.801222Z","end":"2026-04-10T01:16:17.951876Z","steps":["trace[556629160] 'agreement among raft nodes before linearized reading' (duration: 150.59036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:16:17.952042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.415297ms","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-04-10T01:16:17.952139Z","caller":"traceutil/trace.go:171","msg":"trace[1405546390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5512; }","duration":"147.55094ms","start":"2026-04-10T01:16:17.804564Z","end":"2026-04-10T01:16:17.952115Z","steps":["trace[1405546390] 'agreement among raft nodes before linearized reading' (duration: 147.210582ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:17:24.795685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-04-10T01:17:24.865021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"68.060531ms","hash":1914264202,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":33685504,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-10T01:17:24.865092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1914264202,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-04-10T01:22:24.802117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6080} {"level":"info","ts":"2026-04-10T01:22:24.898414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6080,"took":"94.089284ms","hash":413365394,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":23089152,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-10T01:22:24.898529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":413365394,"revision":6080,"compact-revision":2984} {"level":"info","ts":"2026-04-10T01:22:30.140977Z","caller":"traceutil/trace.go:171","msg":"trace[170142090] linearizableReadLoop","detail":"{readStateIndex:8585; appliedIndex:8584; }","duration":"321.209144ms","start":"2026-04-10T01:22:29.819743Z","end":"2026-04-10T01:22:30.140952Z","steps":["trace[170142090] 'read index received' (duration: 320.987409ms)","trace[170142090] 'applied index is now lower than readState.Index' (duration: 220.725µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:22:30.14113Z","caller":"traceutil/trace.go:171","msg":"trace[1741894830] transaction","detail":"{read_only:false; response_revision:8293; number_of_response:1; }","duration":"321.841029ms","start":"2026-04-10T01:22:29.819247Z","end":"2026-04-10T01:22:30.141088Z","steps":["trace[1741894830] 'process raft request' (duration: 321.542742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.141216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.455381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-10T01:22:30.141262Z","caller":"traceutil/trace.go:171","msg":"trace[1875905820] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8293; }","duration":"321.540403ms","start":"2026-04-10T01:22:29.81971Z","end":"2026-04-10T01:22:30.14125Z","steps":["trace[1875905820] 'agreement among raft nodes before linearized reading' (duration: 321.375598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.141302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:22:29.819699Z","time spent":"321.594744ms","remote":"127.0.0.1:36314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-10T01:22:30.141318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:22:29.81923Z","time spent":"321.989784ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:22:30.141353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.286845ms","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-04-10T01:22:30.141887Z","caller":"traceutil/trace.go:171","msg":"trace[383840173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8293; }","duration":"319.860258ms","start":"2026-04-10T01:22:29.822008Z","end":"2026-04-10T01:22:30.141868Z","steps":["trace[383840173] 'agreement among raft nodes before linearized reading' (duration: 319.15181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.141448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.006092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-10T01:22:30.142012Z","caller":"traceutil/trace.go:171","msg":"trace[1628405389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8293; }","duration":"318.584877ms","start":"2026-04-10T01:22:29.823412Z","end":"2026-04-10T01:22:30.141997Z","steps":["trace[1628405389] 'agreement among raft nodes before linearized reading' (duration: 317.913119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.142045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:22:29.823406Z","time spent":"318.630568ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:22:30.141567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.991216ms","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-04-10T01:22:30.142213Z","caller":"traceutil/trace.go:171","msg":"trace[1427659768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8293; }","duration":"317.593691ms","start":"2026-04-10T01:22:29.824513Z","end":"2026-04-10T01:22:30.142107Z","steps":["trace[1427659768] 'agreement among raft nodes before linearized reading' (duration: 316.938914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.142319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:22:29.821998Z","time spent":"319.968063ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-10T01:22:30.141564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.882315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-10T01:22:30.142319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:22:29.824492Z","time spent":"317.800917ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-10T01:22:30.142475Z","caller":"traceutil/trace.go:171","msg":"trace[1541847163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8293; }","duration":"319.797888ms","start":"2026-04-10T01:22:29.822648Z","end":"2026-04-10T01:22:30.142446Z","steps":["trace[1541847163] 'agreement among raft nodes before linearized reading' (duration: 318.771883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.142542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:22:29.822642Z","time spent":"319.8831ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-10T01:22:30.276223Z","caller":"traceutil/trace.go:171","msg":"trace[1128328401] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"129.636359ms","start":"2026-04-10T01:22:30.145964Z","end":"2026-04-10T01:22:30.2756Z","steps":["trace[1128328401] 'process raft request' (duration: 63.823404ms)","trace[1128328401] 'compare' (duration: 65.693693ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:22:30.338965Z","caller":"traceutil/trace.go:171","msg":"trace[1044607491] linearizableReadLoop","detail":"{readStateIndex:8589; appliedIndex:8585; }","duration":"170.696038ms","start":"2026-04-10T01:22:30.168251Z","end":"2026-04-10T01:22:30.338947Z","steps":["trace[1044607491] 'read index received' (duration: 41.55071ms)","trace[1044607491] 'applied index is now lower than readState.Index' (duration: 129.144588ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:22:30.339045Z","caller":"traceutil/trace.go:171","msg":"trace[1630483463] transaction","detail":"{read_only:false; response_revision:8296; number_of_response:1; }","duration":"191.698159ms","start":"2026-04-10T01:22:30.14734Z","end":"2026-04-10T01:22:30.339038Z","steps":["trace[1630483463] 'process raft request' (duration: 191.555496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.339267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.988305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:22:30.339331Z","caller":"traceutil/trace.go:171","msg":"trace[1930239732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8297; }","duration":"171.122718ms","start":"2026-04-10T01:22:30.168193Z","end":"2026-04-10T01:22:30.339316Z","steps":["trace[1930239732] 'agreement among raft nodes before linearized reading' (duration: 170.987105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.339363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.001508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T01:22:30.339386Z","caller":"traceutil/trace.go:171","msg":"trace[1965460730] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8297; }","duration":"116.05705ms","start":"2026-04-10T01:22:30.223322Z","end":"2026-04-10T01:22:30.339379Z","steps":["trace[1965460730] 'agreement among raft nodes before linearized reading' (duration: 116.014138ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:22:30.339343Z","caller":"traceutil/trace.go:171","msg":"trace[1577541604] transaction","detail":"{read_only:false; response_revision:8297; number_of_response:1; }","duration":"191.950525ms","start":"2026-04-10T01:22:30.147338Z","end":"2026-04-10T01:22:30.339289Z","steps":["trace[1577541604] 'process raft request' (duration: 191.580616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:30.339295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.200353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:22:30.339467Z","caller":"traceutil/trace.go:171","msg":"trace[546441777] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8297; }","duration":"142.373918ms","start":"2026-04-10T01:22:30.197072Z","end":"2026-04-10T01:22:30.339446Z","steps":["trace[546441777] 'agreement among raft nodes before linearized reading' (duration: 142.182802ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:22:30.339196Z","caller":"traceutil/trace.go:171","msg":"trace[168036797] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"192.960801ms","start":"2026-04-10T01:22:30.146037Z","end":"2026-04-10T01:22:30.338998Z","steps":["trace[168036797] 'process raft request' (duration: 192.747126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:22:32.275147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.771895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:22:32.275248Z","caller":"traceutil/trace.go:171","msg":"trace[2121595839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8302; }","duration":"107.887779ms","start":"2026-04-10T01:22:32.167337Z","end":"2026-04-10T01:22:32.275225Z","steps":["trace[2121595839] 'range keys from in-memory index tree' (duration: 107.708614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:23:00.163931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.170606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T01:23:00.16402Z","caller":"traceutil/trace.go:171","msg":"trace[2105706325] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8507; }","duration":"372.30486ms","start":"2026-04-10T01:22:59.791699Z","end":"2026-04-10T01:23:00.164003Z","steps":["trace[2105706325] 'count revisions from in-memory index tree' (duration: 372.107265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:23:00.164062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:22:59.791683Z","time spent":"372.369001ms","remote":"127.0.0.1:36334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":117,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-10T01:23:03.226561Z","caller":"traceutil/trace.go:171","msg":"trace[547361208] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"125.36157ms","start":"2026-04-10T01:23:03.101172Z","end":"2026-04-10T01:23:03.226534Z","steps":["trace[547361208] 'process raft request' (duration: 125.212266ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:03.239515Z","caller":"traceutil/trace.go:171","msg":"trace[122990512] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"134.267998ms","start":"2026-04-10T01:23:03.105224Z","end":"2026-04-10T01:23:03.239492Z","steps":["trace[122990512] 'process raft request' (duration: 134.126225ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:03.239662Z","caller":"traceutil/trace.go:171","msg":"trace[1431294248] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"133.171092ms","start":"2026-04-10T01:23:03.106459Z","end":"2026-04-10T01:23:03.239631Z","steps":["trace[1431294248] 'process raft request' (duration: 132.995646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:23:12.287121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.602855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:23:12.287223Z","caller":"traceutil/trace.go:171","msg":"trace[916322231] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8614; }","duration":"111.764169ms","start":"2026-04-10T01:23:12.175441Z","end":"2026-04-10T01:23:12.287205Z","steps":["trace[916322231] 'count revisions from in-memory index tree' (duration: 111.499183ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:12.648363Z","caller":"traceutil/trace.go:171","msg":"trace[872404702] linearizableReadLoop","detail":"{readStateIndex:8920; appliedIndex:8918; }","duration":"254.547708ms","start":"2026-04-10T01:23:12.3938Z","end":"2026-04-10T01:23:12.648348Z","steps":["trace[872404702] 'read index received' (duration: 35.693509ms)","trace[872404702] 'applied index is now lower than readState.Index' (duration: 218.853349ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:23:12.648449Z","caller":"traceutil/trace.go:171","msg":"trace[1318744667] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"311.32493ms","start":"2026-04-10T01:23:12.337115Z","end":"2026-04-10T01:23:12.64844Z","steps":["trace[1318744667] 'process raft request' (duration: 311.136925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:23:12.648518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:23:12.337081Z","time spent":"311.384952ms","remote":"127.0.0.1:36314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:23:12.648732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.419074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-10T01:23:12.648825Z","caller":"traceutil/trace.go:171","msg":"trace[1595131100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8616; }","duration":"122.563048ms","start":"2026-04-10T01:23:12.526242Z","end":"2026-04-10T01:23:12.648805Z","steps":["trace[1595131100] 'agreement among raft nodes before linearized reading' (duration: 122.296831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:23:12.648872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.058031ms","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-04-10T01:23:12.64896Z","caller":"traceutil/trace.go:171","msg":"trace[1241661661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8616; }","duration":"255.179574ms","start":"2026-04-10T01:23:12.393758Z","end":"2026-04-10T01:23:12.648937Z","steps":["trace[1241661661] 'agreement among raft nodes before linearized reading' (duration: 254.934637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:23:12.649013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.55613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-10T01:23:12.649049Z","caller":"traceutil/trace.go:171","msg":"trace[1853750215] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8616; }","duration":"105.628592ms","start":"2026-04-10T01:23:12.543412Z","end":"2026-04-10T01:23:12.649041Z","steps":["trace[1853750215] 'agreement among raft nodes before linearized reading' (duration: 105.5571ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:12.80709Z","caller":"traceutil/trace.go:171","msg":"trace[1218191992] transaction","detail":"{read_only:false; response_revision:8618; number_of_response:1; }","duration":"152.530338ms","start":"2026-04-10T01:23:12.654539Z","end":"2026-04-10T01:23:12.807069Z","steps":["trace[1218191992] 'process raft request' (duration: 143.395483ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:12.807376Z","caller":"traceutil/trace.go:171","msg":"trace[1393425432] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8920; }","duration":"137.634306ms","start":"2026-04-10T01:23:12.669723Z","end":"2026-04-10T01:23:12.807358Z","steps":["trace[1393425432] 'read index received' (duration: 39.994441ms)","trace[1393425432] 'applied index is now lower than readState.Index' (duration: 97.639105ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:23:12.807389Z","caller":"traceutil/trace.go:171","msg":"trace[1389915412] transaction","detail":"{read_only:false; response_revision:8619; number_of_response:1; }","duration":"152.682712ms","start":"2026-04-10T01:23:12.654679Z","end":"2026-04-10T01:23:12.807361Z","steps":["trace[1389915412] 'process raft request' (duration: 152.358704ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:12.807402Z","caller":"traceutil/trace.go:171","msg":"trace[1734969328] transaction","detail":"{read_only:false; response_revision:8620; number_of_response:1; }","duration":"152.692353ms","start":"2026-04-10T01:23:12.654702Z","end":"2026-04-10T01:23:12.807395Z","steps":["trace[1734969328] 'process raft request' (duration: 152.519579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:23:12.807572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.837051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-10T01:23:12.807622Z","caller":"traceutil/trace.go:171","msg":"trace[1975923351] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8621; }","duration":"137.912942ms","start":"2026-04-10T01:23:12.669688Z","end":"2026-04-10T01:23:12.8076Z","steps":["trace[1975923351] 'agreement among raft nodes before linearized reading' (duration: 137.753378ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:12.807573Z","caller":"traceutil/trace.go:171","msg":"trace[253436660] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"152.684603ms","start":"2026-04-10T01:23:12.654747Z","end":"2026-04-10T01:23:12.807432Z","steps":["trace[253436660] 'process raft request' (duration: 152.56639ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:13.42813Z","caller":"traceutil/trace.go:171","msg":"trace[443918727] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"154.620503ms","start":"2026-04-10T01:23:13.273486Z","end":"2026-04-10T01:23:13.428107Z","steps":["trace[443918727] 'process raft request' (duration: 154.573312ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:23:13.428178Z","caller":"traceutil/trace.go:171","msg":"trace[2141426842] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"155.911456ms","start":"2026-04-10T01:23:13.272231Z","end":"2026-04-10T01:23:13.428143Z","steps":["trace[2141426842] 'process raft request' (duration: 79.824306ms)","trace[2141426842] 'compare' (duration: 75.905595ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:23:56.728283Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002476.wal"} {"level":"info","ts":"2026-04-10T01:25:09.339816Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-10T01:25:09.350716Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-10T01:25:09.350817Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-10T01:26:11.661228Z","caller":"traceutil/trace.go:171","msg":"trace[617710402] linearizableReadLoop","detail":"{readStateIndex:10462; appliedIndex:10461; }","duration":"493.130722ms","start":"2026-04-10T01:26:11.168072Z","end":"2026-04-10T01:26:11.661203Z","steps":["trace[617710402] 'read index received' (duration: 492.943738ms)","trace[617710402] 'applied index is now lower than readState.Index' (duration: 185.814µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:26:11.661264Z","caller":"traceutil/trace.go:171","msg":"trace[1093172050] transaction","detail":"{read_only:false; response_revision:10111; number_of_response:1; }","duration":"517.091942ms","start":"2026-04-10T01:26:11.144148Z","end":"2026-04-10T01:26:11.66124Z","steps":["trace[1093172050] 'process raft request' (duration: 516.896997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:11.661367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.282905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:26:11.6614Z","caller":"traceutil/trace.go:171","msg":"trace[477636331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10111; }","duration":"493.363848ms","start":"2026-04-10T01:26:11.168027Z","end":"2026-04-10T01:26:11.66139Z","steps":["trace[477636331] 'agreement among raft nodes before linearized reading' (duration: 493.277366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:11.661415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:11.144128Z","time spent":"517.198904ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:26:11.661436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:11.168013Z","time spent":"493.41756ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:26:12.72972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.558074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-c9d9b\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-10T01:26:12.72983Z","caller":"traceutil/trace.go:171","msg":"trace[2035313212] range","detail":"{range_begin:/registry/pods/openstack/keepalived-c9d9b; range_end:; response_count:1; response_revision:10119; }","duration":"658.722349ms","start":"2026-04-10T01:26:12.071081Z","end":"2026-04-10T01:26:12.729803Z","steps":["trace[2035313212] 'range keys from in-memory index tree' (duration: 658.346388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:12.729873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:12.071055Z","time spent":"658.80873ms","remote":"127.0.0.1:36314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-c9d9b\" "} {"level":"info","ts":"2026-04-10T01:26:12.769745Z","caller":"traceutil/trace.go:171","msg":"trace[729523209] transaction","detail":"{read_only:false; response_revision:10120; number_of_response:1; }","duration":"689.701048ms","start":"2026-04-10T01:26:12.080024Z","end":"2026-04-10T01:26:12.769725Z","steps":["trace[729523209] 'process raft request' (duration: 689.537794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:12.76989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:12.080006Z","time spent":"689.800412ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:26:12.773289Z","caller":"traceutil/trace.go:171","msg":"trace[1660306395] transaction","detail":"{read_only:false; response_revision:10121; number_of_response:1; }","duration":"693.165348ms","start":"2026-04-10T01:26:12.08009Z","end":"2026-04-10T01:26:12.773255Z","steps":["trace[1660306395] 'process raft request' (duration: 692.866601ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:26:12.773321Z","caller":"traceutil/trace.go:171","msg":"trace[1165196979] transaction","detail":"{read_only:false; response_revision:10122; number_of_response:1; }","duration":"447.224327ms","start":"2026-04-10T01:26:12.32607Z","end":"2026-04-10T01:26:12.773294Z","steps":["trace[1165196979] 'process raft request' (duration: 447.093634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:12.773478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:12.080057Z","time spent":"693.328273ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:26:12.773527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:12.32604Z","time spent":"447.35543ms","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:26:14.174807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675952424001015,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:26:14.675507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675952424001015,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:26:15.175934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675952424001015,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:26:15.676858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675952424001015,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:26:16.168276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000473152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-10T01:26:16.168375Z","caller":"traceutil/trace.go:171","msg":"trace[2000331951] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000594355s","start":"2026-04-10T01:26:14.167759Z","end":"2026-04-10T01:26:16.168353Z","steps":["trace[2000331951] 'agreement among raft nodes before linearized reading' (duration: 2.000468752s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:16.168437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:14.1677Z","time spent":"2.000723678s","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:26:16.177908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675952424001015,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:26:17.559592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675952424001015,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:26:17.595928Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.102041593s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-10T01:26:17.616236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.907235622s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:26:17.616451Z","caller":"traceutil/trace.go:171","msg":"trace[671052735] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:10122; }","duration":"1.907465129s","start":"2026-04-10T01:26:15.708966Z","end":"2026-04-10T01:26:17.616431Z","steps":["trace[671052735] 'range keys from in-memory index tree' (duration: 1.907216372s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.630161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.713757445s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-10T01:26:17.630687Z","caller":"traceutil/trace.go:171","msg":"trace[983474411] linearizableReadLoop","detail":"{readStateIndex:10479; appliedIndex:10473; }","duration":"3.956674468s","start":"2026-04-10T01:26:13.673999Z","end":"2026-04-10T01:26:17.630673Z","steps":["trace[983474411] 'read index received' (duration: 3.922170549s)","trace[983474411] 'applied index is now lower than readState.Index' (duration: 34.503239ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:26:17.630882Z","caller":"traceutil/trace.go:171","msg":"trace[311918764] transaction","detail":"{read_only:false; response_revision:10127; number_of_response:1; }","duration":"2.852661963s","start":"2026-04-10T01:26:14.778178Z","end":"2026-04-10T01:26:17.63084Z","steps":["trace[311918764] 'process raft request' (duration: 2.852472439s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.630951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.956955355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-10T01:26:17.630986Z","caller":"traceutil/trace.go:171","msg":"trace[1708212023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10127; }","duration":"3.957036647s","start":"2026-04-10T01:26:13.673933Z","end":"2026-04-10T01:26:17.63097Z","steps":["trace[1708212023] 'agreement among raft nodes before linearized reading' (duration: 3.956928784s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.631022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:13.673898Z","time spent":"3.957116609s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T01:26:17.631058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:14.778154Z","time spent":"2.852812416s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:26:17.631153Z","caller":"traceutil/trace.go:171","msg":"trace[1415892647] transaction","detail":"{read_only:false; response_revision:10124; number_of_response:1; }","duration":"3.873970892s","start":"2026-04-10T01:26:13.757157Z","end":"2026-04-10T01:26:17.631128Z","steps":["trace[1415892647] 'process raft request' (duration: 3.873393537s)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:26:17.631327Z","caller":"traceutil/trace.go:171","msg":"trace[944900812] transaction","detail":"{read_only:false; response_revision:10125; number_of_response:1; }","duration":"3.663852698s","start":"2026-04-10T01:26:13.967461Z","end":"2026-04-10T01:26:17.631314Z","steps":["trace[944900812] 'process raft request' (duration: 3.66313952s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.631332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:13.757135Z","time spent":"3.874113595s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:26:17.63144Z","caller":"traceutil/trace.go:171","msg":"trace[569757433] transaction","detail":"{read_only:false; response_revision:10126; number_of_response:1; }","duration":"2.855923928s","start":"2026-04-10T01:26:14.775507Z","end":"2026-04-10T01:26:17.631431Z","steps":["trace[569757433] 'process raft request' (duration: 2.855115987s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.631494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:14.775477Z","time spent":"2.855992219s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:26:17.631399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:13.967431Z","time spent":"3.66393218s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:26:17.63162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362240971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-10T01:26:17.631649Z","caller":"traceutil/trace.go:171","msg":"trace[2053934767] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10127; }","duration":"1.362323613s","start":"2026-04-10T01:26:16.269316Z","end":"2026-04-10T01:26:17.631639Z","steps":["trace[2053934767] 'agreement among raft nodes before linearized reading' (duration: 1.362250761s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.631672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:16.269277Z","time spent":"1.362389594s","remote":"127.0.0.1:36284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-10T01:26:17.631681Z","caller":"traceutil/trace.go:171","msg":"trace[770037973] transaction","detail":"{read_only:false; response_revision:10123; number_of_response:1; }","duration":"4.137893287s","start":"2026-04-10T01:26:13.493777Z","end":"2026-04-10T01:26:17.631671Z","steps":["trace[770037973] 'process raft request' (duration: 4.136496731s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.631731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:13.493748Z","time spent":"4.137955837s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:26:17.631214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.890352994s","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-04-10T01:26:17.631819Z","caller":"traceutil/trace.go:171","msg":"trace[2041057657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10127; }","duration":"3.890991372s","start":"2026-04-10T01:26:13.740817Z","end":"2026-04-10T01:26:17.631809Z","steps":["trace[2041057657] 'agreement among raft nodes before linearized reading' (duration: 3.890345235s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.631829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.890843108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-10T01:26:17.631857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:13.740793Z","time spent":"3.891055344s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T01:26:17.631861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.797779255s","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-04-10T01:26:17.631897Z","caller":"traceutil/trace.go:171","msg":"trace[259571671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10127; }","duration":"3.890961611s","start":"2026-04-10T01:26:13.740916Z","end":"2026-04-10T01:26:17.631878Z","steps":["trace[259571671] 'agreement among raft nodes before linearized reading' (duration: 3.890812487s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.631936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:13.740886Z","time spent":"3.891041473s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:26:17.631995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463185567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:26:17.632031Z","caller":"traceutil/trace.go:171","msg":"trace[526468449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10127; }","duration":"1.463258979s","start":"2026-04-10T01:26:16.168762Z","end":"2026-04-10T01:26:17.632021Z","steps":["trace[526468449] 'agreement among raft nodes before linearized reading' (duration: 1.463189746s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.632043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997577265s","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":"warn","ts":"2026-04-10T01:26:17.632049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:16.168741Z","time spent":"1.463304561s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T01:26:17.63207Z","caller":"traceutil/trace.go:171","msg":"trace[1766653992] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:10127; }","duration":"1.997651567s","start":"2026-04-10T01:26:15.634412Z","end":"2026-04-10T01:26:17.632063Z","steps":["trace[1766653992] 'agreement among raft nodes before linearized reading' (duration: 1.997545625s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.632067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.78090526s","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-04-10T01:26:17.631932Z","caller":"traceutil/trace.go:171","msg":"trace[1372044923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10127; }","duration":"3.797901937s","start":"2026-04-10T01:26:13.834011Z","end":"2026-04-10T01:26:17.631913Z","steps":["trace[1372044923] 'agreement among raft nodes before linearized reading' (duration: 3.797650031s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.632075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.255230287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-04-10T01:26:17.632098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:15.634386Z","time spent":"1.997703279s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":88,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-10T01:26:17.632114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:13.833989Z","time spent":"3.798117364s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-10T01:26:17.632119Z","caller":"traceutil/trace.go:171","msg":"trace[893791273] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10127; }","duration":"3.255311769s","start":"2026-04-10T01:26:14.376797Z","end":"2026-04-10T01:26:17.632109Z","steps":["trace[893791273] 'agreement among raft nodes before linearized reading' (duration: 3.255135814s)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:26:17.632111Z","caller":"traceutil/trace.go:171","msg":"trace[442470847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10127; }","duration":"3.780963732s","start":"2026-04-10T01:26:13.851129Z","end":"2026-04-10T01:26:17.632093Z","steps":["trace[442470847] 'agreement among raft nodes before linearized reading' (duration: 3.780826118s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.632132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.849810309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-c9d9b\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-04-10T01:26:17.632164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:13.851119Z","time spent":"3.781036613s","remote":"127.0.0.1:36396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-10T01:26:17.632153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:14.376764Z","time spent":"3.255382981s","remote":"127.0.0.1:36314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-10T01:26:17.6322Z","caller":"traceutil/trace.go:171","msg":"trace[1522392255] range","detail":"{range_begin:/registry/pods/openstack/keepalived-c9d9b; range_end:; response_count:1; response_revision:10127; }","duration":"2.849944552s","start":"2026-04-10T01:26:14.782241Z","end":"2026-04-10T01:26:17.632185Z","steps":["trace[1522392255] 'agreement among raft nodes before linearized reading' (duration: 2.849678295s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:17.632241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:26:14.782196Z","time spent":"2.850038774s","remote":"127.0.0.1:36314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-c9d9b\" "} {"level":"info","ts":"2026-04-10T01:26:20.530499Z","caller":"traceutil/trace.go:171","msg":"trace[1948377138] linearizableReadLoop","detail":"{readStateIndex:10501; appliedIndex:10500; }","duration":"236.316962ms","start":"2026-04-10T01:26:20.29415Z","end":"2026-04-10T01:26:20.530467Z","steps":["trace[1948377138] 'read index received' (duration: 235.669895ms)","trace[1948377138] 'applied index is now lower than readState.Index' (duration: 645.917µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:26:20.530735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.557667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-10T01:26:20.53073Z","caller":"traceutil/trace.go:171","msg":"trace[748070649] transaction","detail":"{read_only:false; response_revision:10148; number_of_response:1; }","duration":"251.447732ms","start":"2026-04-10T01:26:20.279257Z","end":"2026-04-10T01:26:20.530705Z","steps":["trace[748070649] 'process raft request' (duration: 251.02168ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:26:20.530769Z","caller":"traceutil/trace.go:171","msg":"trace[237375787] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:10148; }","duration":"236.621729ms","start":"2026-04-10T01:26:20.294133Z","end":"2026-04-10T01:26:20.530754Z","steps":["trace[237375787] 'agreement among raft nodes before linearized reading' (duration: 236.441655ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:26:23.823746Z","caller":"traceutil/trace.go:171","msg":"trace[421974328] transaction","detail":"{read_only:false; response_revision:10167; number_of_response:1; }","duration":"115.174625ms","start":"2026-04-10T01:26:23.708546Z","end":"2026-04-10T01:26:23.823721Z","steps":["trace[421974328] 'process raft request' (duration: 114.976909ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:26:23.824099Z","caller":"traceutil/trace.go:171","msg":"trace[486889936] transaction","detail":"{read_only:false; response_revision:10168; number_of_response:1; }","duration":"114.2361ms","start":"2026-04-10T01:26:23.709805Z","end":"2026-04-10T01:26:23.824041Z","steps":["trace[486889936] 'process raft request' (duration: 113.873791ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:26:23.824164Z","caller":"traceutil/trace.go:171","msg":"trace[978149672] transaction","detail":"{read_only:false; response_revision:10169; number_of_response:1; }","duration":"100.936266ms","start":"2026-04-10T01:26:23.723197Z","end":"2026-04-10T01:26:23.824133Z","steps":["trace[978149672] 'process raft request' (duration: 100.756411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:23.824249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.071574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-10T01:26:23.824302Z","caller":"traceutil/trace.go:171","msg":"trace[1769888608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10169; }","duration":"100.149487ms","start":"2026-04-10T01:26:23.72414Z","end":"2026-04-10T01:26:23.824289Z","steps":["trace[1769888608] 'agreement among raft nodes before linearized reading' (duration: 100.009292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:23.824467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.340721ms","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-04-10T01:26:23.82453Z","caller":"traceutil/trace.go:171","msg":"trace[577901422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10169; }","duration":"100.448434ms","start":"2026-04-10T01:26:23.724067Z","end":"2026-04-10T01:26:23.824515Z","steps":["trace[577901422] 'agreement among raft nodes before linearized reading' (duration: 100.28859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:26:23.824644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.282449ms","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-04-10T01:26:23.824698Z","caller":"traceutil/trace.go:171","msg":"trace[829522944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10169; }","duration":"100.361841ms","start":"2026-04-10T01:26:23.724323Z","end":"2026-04-10T01:26:23.824685Z","steps":["trace[829522944] 'agreement among raft nodes before linearized reading' (duration: 100.203677ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:27:24.809319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8266} {"level":"info","ts":"2026-04-10T01:27:24.90082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8266,"took":"88.312434ms","hash":3977540213,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":24834048,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-10T01:27:24.900906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3977540213,"revision":8266,"compact-revision":6080} {"level":"info","ts":"2026-04-10T01:32:24.81748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10647} {"level":"info","ts":"2026-04-10T01:32:24.906475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10647,"took":"85.602619ms","hash":1730959161,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":20205568,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-10T01:32:24.906562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1730959161,"revision":10647,"compact-revision":8266}