{"level":"warn","ts":"2026-04-03T05:01:56.534755Z","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-03T05:01:56.534831Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-03T05:01:56.534889Z","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-03T05:01:56.534896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-03T05:01:56.53491Z","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-03T05:01:56.535525Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-04-03T05:01:56.535646Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-03T05:01:56.539604Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.769271ms"} {"level":"info","ts":"2026-04-03T05:01:56.547339Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-04-03T05:01:56.547441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T05:01:56.547469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-04-03T05:01:56.547491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T05:01:56.547506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-04-03T05:01:56.547555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-04-03T05:01:56.562507Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T05:01:56.56402Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T05:01:56.565304Z","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-03T05:01:56.568051Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T05:01:56.568474Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T05:01:56.568427Z","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-03T05:01:56.568574Z","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-03T05:01:56.568595Z","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-03T05:01:56.569378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-04-03T05:01:56.569468Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-03T05:01:56.573624Z","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-03T05:01:56.573894Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T05:01:56.573943Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T05:01:56.574076Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-03T05:01:56.574336Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-03T05:01:57.048002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T05:01:57.048079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T05:01:57.048133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-04-03T05:01:57.048164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-04-03T05:01:57.04818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-03T05:01:57.048201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-04-03T05:01:57.048219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-03T05:01:57.080598Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:01:57.107697Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T05:01:57.107933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T05:01:57.108182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T05:01:57.108776Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T05:01:57.108815Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T05:01:57.110034Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:01:57.111026Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:01:57.111068Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T05:01:57.114216Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-04-03T05:01:57.119782Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T05:02:08.143866Z","caller":"traceutil/trace.go:171","msg":"trace[176868745] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"113.086599ms","start":"2026-04-03T05:02:08.030739Z","end":"2026-04-03T05:02:08.143826Z","steps":["trace[176868745] 'process raft request' (duration: 112.438121ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:12.691344Z","caller":"traceutil/trace.go:171","msg":"trace[1925109000] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"123.809367ms","start":"2026-04-03T05:02:12.567494Z","end":"2026-04-03T05:02:12.691303Z","steps":["trace[1925109000] 'read index received' (duration: 123.673901ms)","trace[1925109000] 'applied index is now lower than readState.Index' (duration: 134.706µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:12.691365Z","caller":"traceutil/trace.go:171","msg":"trace[1739435285] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"128.124468ms","start":"2026-04-03T05:02:12.563213Z","end":"2026-04-03T05:02:12.691338Z","steps":["trace[1739435285] 'process raft request' (duration: 127.92371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:12.691473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.954563ms","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-03T05:02:12.691533Z","caller":"traceutil/trace.go:171","msg":"trace[417224584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:409; }","duration":"124.053027ms","start":"2026-04-03T05:02:12.567469Z","end":"2026-04-03T05:02:12.691522Z","steps":["trace[417224584] 'agreement among raft nodes before linearized reading' (duration: 123.945982ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:13.848386Z","caller":"traceutil/trace.go:171","msg":"trace[355680526] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"125.251729ms","start":"2026-04-03T05:02:13.723108Z","end":"2026-04-03T05:02:13.84836Z","steps":["trace[355680526] 'process raft request' (duration: 55.842378ms)","trace[355680526] 'compare' (duration: 69.276246ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:15.418058Z","caller":"traceutil/trace.go:171","msg":"trace[1636419365] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:448; }","duration":"222.316853ms","start":"2026-04-03T05:02:15.19572Z","end":"2026-04-03T05:02:15.418037Z","steps":["trace[1636419365] 'read index received' (duration: 32.948527ms)","trace[1636419365] 'applied index is now lower than readState.Index' (duration: 189.367516ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:02:15.418218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.470669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-04-03T05:02:15.418182Z","caller":"traceutil/trace.go:171","msg":"trace[945467542] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"223.848815ms","start":"2026-04-03T05:02:15.194284Z","end":"2026-04-03T05:02:15.418132Z","steps":["trace[945467542] 'process raft request' (duration: 223.694929ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:15.41826Z","caller":"traceutil/trace.go:171","msg":"trace[14035890] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:439; }","duration":"222.560473ms","start":"2026-04-03T05:02:15.19569Z","end":"2026-04-03T05:02:15.41825Z","steps":["trace[14035890] 'agreement among raft nodes before linearized reading' (duration: 222.459869ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:15.418217Z","caller":"traceutil/trace.go:171","msg":"trace[1517835743] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"223.96833ms","start":"2026-04-03T05:02:15.194192Z","end":"2026-04-03T05:02:15.41816Z","steps":["trace[1517835743] 'process raft request' (duration: 164.654283ms)","trace[1517835743] 'compare' (duration: 58.926032ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:15.425345Z","caller":"traceutil/trace.go:171","msg":"trace[1792396333] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"189.240051ms","start":"2026-04-03T05:02:15.236088Z","end":"2026-04-03T05:02:15.425328Z","steps":["trace[1792396333] 'process raft request' (duration: 189.20709ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:15.425635Z","caller":"traceutil/trace.go:171","msg":"trace[1764410568] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"190.428619ms","start":"2026-04-03T05:02:15.235195Z","end":"2026-04-03T05:02:15.425624Z","steps":["trace[1764410568] 'process raft request' (duration: 190.015532ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:15.425888Z","caller":"traceutil/trace.go:171","msg":"trace[933801466] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"189.929259ms","start":"2026-04-03T05:02:15.235949Z","end":"2026-04-03T05:02:15.425878Z","steps":["trace[933801466] 'process raft request' (duration: 189.307823ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:15.425997Z","caller":"traceutil/trace.go:171","msg":"trace[1345357073] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"191.078335ms","start":"2026-04-03T05:02:15.234905Z","end":"2026-04-03T05:02:15.425984Z","steps":["trace[1345357073] 'process raft request' (duration: 190.206279ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:15.547565Z","caller":"traceutil/trace.go:171","msg":"trace[1590237597] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"114.013868ms","start":"2026-04-03T05:02:15.433526Z","end":"2026-04-03T05:02:15.54754Z","steps":["trace[1590237597] 'process raft request' (duration: 106.644339ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:20.926702Z","caller":"traceutil/trace.go:171","msg":"trace[698401191] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"121.780687ms","start":"2026-04-03T05:02:20.804886Z","end":"2026-04-03T05:02:20.926666Z","steps":["trace[698401191] 'process raft request' (duration: 121.482516ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:24.03394Z","caller":"traceutil/trace.go:171","msg":"trace[1006176015] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"368.262133ms","start":"2026-04-03T05:02:23.665654Z","end":"2026-04-03T05:02:24.033917Z","steps":["trace[1006176015] 'process raft request' (duration: 368.092966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:24.034615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:23.665627Z","time spent":"368.392407ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:02:27.467065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741170226874147,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T05:02:27.607908Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019991928s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T05:02:27.608839Z","caller":"traceutil/trace.go:171","msg":"trace[478965839] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"642.146113ms","start":"2026-04-03T05:02:26.966673Z","end":"2026-04-03T05:02:27.608819Z","steps":["trace[478965839] 'read index received' (duration: 641.528421ms)","trace[478965839] 'applied index is now lower than readState.Index' (duration: 616.972µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:27.608951Z","caller":"traceutil/trace.go:171","msg":"trace[1800156934] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"1.022145866s","start":"2026-04-03T05:02:26.586755Z","end":"2026-04-03T05:02:27.608901Z","steps":["trace[1800156934] 'process raft request' (duration: 1.021583926s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:27.609087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.185476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-03T05:02:27.609119Z","caller":"traceutil/trace.go:171","msg":"trace[342528547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"405.242688ms","start":"2026-04-03T05:02:27.203866Z","end":"2026-04-03T05:02:27.609109Z","steps":["trace[342528547] 'agreement among raft nodes before linearized reading' (duration: 405.179596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:27.609143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:27.203848Z","time spent":"405.29041ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T05:02:27.609119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.430102ms","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-03T05:02:27.609178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:26.586723Z","time spent":"1.022384634s","remote":"127.0.0.1:59160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:27.609203Z","caller":"traceutil/trace.go:171","msg":"trace[2051518878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"642.753985ms","start":"2026-04-03T05:02:26.966429Z","end":"2026-04-03T05:02:27.609183Z","steps":["trace[2051518878] 'agreement among raft nodes before linearized reading' (duration: 642.509556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:27.609247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:26.966406Z","time spent":"642.832167ms","remote":"127.0.0.1:59338","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":"warn","ts":"2026-04-03T05:02:27.609014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.211963ms","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-03T05:02:27.609489Z","caller":"traceutil/trace.go:171","msg":"trace[1669920626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:472; }","duration":"605.734351ms","start":"2026-04-03T05:02:27.003746Z","end":"2026-04-03T05:02:27.60948Z","steps":["trace[1669920626] 'agreement among raft nodes before linearized reading' (duration: 605.196082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:27.609514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:27.003721Z","time spent":"605.787163ms","remote":"127.0.0.1:59338","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-03T05:02:29.735994Z","caller":"traceutil/trace.go:171","msg":"trace[1272933575] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"480.569388ms","start":"2026-04-03T05:02:29.255397Z","end":"2026-04-03T05:02:29.735967Z","steps":["trace[1272933575] 'process raft request' (duration: 480.400972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:29.736187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.255376Z","time spent":"480.717112ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:30.209056Z","caller":"traceutil/trace.go:171","msg":"trace[1875441079] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"905.238583ms","start":"2026-04-03T05:02:29.303787Z","end":"2026-04-03T05:02:30.209026Z","steps":["trace[1875441079] 'process raft request' (duration: 905.029696ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.2091Z","caller":"traceutil/trace.go:171","msg":"trace[1366033627] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"894.714506ms","start":"2026-04-03T05:02:29.314366Z","end":"2026-04-03T05:02:30.20908Z","steps":["trace[1366033627] 'process raft request' (duration: 894.55746ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.209132Z","caller":"traceutil/trace.go:171","msg":"trace[998370102] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"894.652493ms","start":"2026-04-03T05:02:29.314411Z","end":"2026-04-03T05:02:30.209064Z","steps":["trace[998370102] 'process raft request' (duration: 894.538819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.209212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.314348Z","time spent":"894.806089ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.209188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.303762Z","time spent":"905.362947ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:30.209226Z","caller":"traceutil/trace.go:171","msg":"trace[362930538] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"870.867124ms","start":"2026-04-03T05:02:29.338318Z","end":"2026-04-03T05:02:30.209185Z","steps":["trace[362930538] 'process raft request' (duration: 870.749879ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.209218Z","caller":"traceutil/trace.go:171","msg":"trace[1965385659] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"888.744717ms","start":"2026-04-03T05:02:29.320407Z","end":"2026-04-03T05:02:30.209152Z","steps":["trace[1965385659] 'process raft request' (duration: 888.612673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.209267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.314408Z","time spent":"894.794218ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:30.209257Z","caller":"traceutil/trace.go:171","msg":"trace[1316948618] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"877.831207ms","start":"2026-04-03T05:02:29.331404Z","end":"2026-04-03T05:02:30.209235Z","steps":["trace[1316948618] 'process raft request' (duration: 877.639881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.209334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.338296Z","time spent":"870.985398ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:30.209382Z","caller":"traceutil/trace.go:171","msg":"trace[612195526] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"856.676799ms","start":"2026-04-03T05:02:29.352698Z","end":"2026-04-03T05:02:30.209374Z","steps":["trace[612195526] 'process raft request' (duration: 856.426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.209388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.33138Z","time spent":"877.949001ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.209418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.352677Z","time spent":"856.7267ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.20941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.3204Z","time spent":"888.904523ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:30.209454Z","caller":"traceutil/trace.go:171","msg":"trace[1404420812] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:524; }","duration":"680.140349ms","start":"2026-04-03T05:02:29.529292Z","end":"2026-04-03T05:02:30.209433Z","steps":["trace[1404420812] 'read index received' (duration: 206.548607ms)","trace[1404420812] 'applied index is now lower than readState.Index' (duration: 473.591102ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:30.209531Z","caller":"traceutil/trace.go:171","msg":"trace[376903616] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"867.241687ms","start":"2026-04-03T05:02:29.342274Z","end":"2026-04-03T05:02:30.209516Z","steps":["trace[376903616] 'process raft request' (duration: 866.815762ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.209526Z","caller":"traceutil/trace.go:171","msg":"trace[823248503] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"890.117395ms","start":"2026-04-03T05:02:29.319345Z","end":"2026-04-03T05:02:30.209463Z","steps":["trace[823248503] 'process raft request' (duration: 889.644519ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.209564Z","caller":"traceutil/trace.go:171","msg":"trace[826915918] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"853.483268ms","start":"2026-04-03T05:02:29.356076Z","end":"2026-04-03T05:02:30.20956Z","steps":["trace[826915918] 'process raft request' (duration: 853.083754ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.209559Z","caller":"traceutil/trace.go:171","msg":"trace[605557874] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"710.659154ms","start":"2026-04-03T05:02:29.498885Z","end":"2026-04-03T05:02:30.209544Z","steps":["trace[605557874] 'process raft request' (duration: 710.467017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.209596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.356057Z","time spent":"853.524518ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.209594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.342256Z","time spent":"867.30963ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.209617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.318995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:02:30.209619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.319326Z","time spent":"890.2523ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:30.209642Z","caller":"traceutil/trace.go:171","msg":"trace[537378085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"680.393388ms","start":"2026-04-03T05:02:29.529241Z","end":"2026-04-03T05:02:30.209634Z","steps":["trace[537378085] 'agreement among raft nodes before linearized reading' (duration: 680.310355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.209627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.498861Z","time spent":"710.736167ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.209668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.529218Z","time spent":"680.444439ms","remote":"127.0.0.1:59062","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-03T05:02:30.2097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.031992ms","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-03T05:02:30.209543Z","caller":"traceutil/trace.go:171","msg":"trace[581470819] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"838.413262ms","start":"2026-04-03T05:02:29.37112Z","end":"2026-04-03T05:02:30.209533Z","steps":["trace[581470819] 'process raft request' (duration: 838.135542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.20974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.347302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-03T05:02:30.209763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.600842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-03T05:02:30.209796Z","caller":"traceutil/trace.go:171","msg":"trace[1615298207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"571.672644ms","start":"2026-04-03T05:02:29.638114Z","end":"2026-04-03T05:02:30.209787Z","steps":["trace[1615298207] 'agreement among raft nodes before linearized reading' (duration: 571.584981ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.209803Z","caller":"traceutil/trace.go:171","msg":"trace[22115246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"573.435595ms","start":"2026-04-03T05:02:29.636348Z","end":"2026-04-03T05:02:30.209783Z","steps":["trace[22115246] 'agreement among raft nodes before linearized reading' (duration: 573.27291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.209881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.638099Z","time spent":"571.766317ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T05:02:30.209897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.636328Z","time spent":"573.55421ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T05:02:30.209809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.371095Z","time spent":"838.680171ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:30.20975Z","caller":"traceutil/trace.go:171","msg":"trace[1667795622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"571.069363ms","start":"2026-04-03T05:02:29.638642Z","end":"2026-04-03T05:02:30.209711Z","steps":["trace[1667795622] 'agreement among raft nodes before linearized reading' (duration: 571.036722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.209973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:29.638631Z","time spent":"571.326922ms","remote":"127.0.0.1:59338","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":"warn","ts":"2026-04-03T05:02:30.59508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.012508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:02:30.595366Z","caller":"traceutil/trace.go:171","msg":"trace[1316648466] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"374.852692ms","start":"2026-04-03T05:02:30.220493Z","end":"2026-04-03T05:02:30.595345Z","steps":["trace[1316648466] 'process raft request' (duration: 374.718827ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.595417Z","caller":"traceutil/trace.go:171","msg":"trace[945659812] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"377.224984ms","start":"2026-04-03T05:02:30.21817Z","end":"2026-04-03T05:02:30.595395Z","steps":["trace[945659812] 'read index received' (duration: 211.820452ms)","trace[945659812] 'applied index is now lower than readState.Index' (duration: 165.399161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:02:30.595458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.22048Z","time spent":"374.934834ms","remote":"127.0.0.1:59338","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-03T05:02:30.595494Z","caller":"traceutil/trace.go:171","msg":"trace[1262635266] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"369.629932ms","start":"2026-04-03T05:02:30.225834Z","end":"2026-04-03T05:02:30.595464Z","steps":["trace[1262635266] 'process raft request' (duration: 369.540949ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.595556Z","caller":"traceutil/trace.go:171","msg":"trace[492005872] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"371.61663ms","start":"2026-04-03T05:02:30.223929Z","end":"2026-04-03T05:02:30.595546Z","steps":["trace[492005872] 'process raft request' (duration: 371.394042ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.59561Z","caller":"traceutil/trace.go:171","msg":"trace[1413110133] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"371.573659ms","start":"2026-04-03T05:02:30.224029Z","end":"2026-04-03T05:02:30.595603Z","steps":["trace[1413110133] 'process raft request' (duration: 371.31291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.59566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.224024Z","time spent":"371.62193ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:30.595714Z","caller":"traceutil/trace.go:171","msg":"trace[1249543192] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"373.220245ms","start":"2026-04-03T05:02:30.222488Z","end":"2026-04-03T05:02:30.595708Z","steps":["trace[1249543192] 'process raft request' (duration: 372.808081ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.595733Z","caller":"traceutil/trace.go:171","msg":"trace[911605552] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"375.132412ms","start":"2026-04-03T05:02:30.22059Z","end":"2026-04-03T05:02:30.595722Z","steps":["trace[911605552] 'process raft request' (duration: 374.658255ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.595497Z","caller":"traceutil/trace.go:171","msg":"trace[57020595] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"378.321011ms","start":"2026-04-03T05:02:30.217095Z","end":"2026-04-03T05:02:30.595416Z","steps":["trace[57020595] 'process raft request' (duration: 212.834957ms)","trace[57020595] 'compare' (duration: 164.920175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:02:30.595779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.222482Z","time spent":"373.253616ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:02:30.595803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.220575Z","time spent":"375.194043ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:30.595847Z","caller":"traceutil/trace.go:171","msg":"trace[1670398685] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"371.777106ms","start":"2026-04-03T05:02:30.224063Z","end":"2026-04-03T05:02:30.59584Z","steps":["trace[1670398685] 'process raft request' (duration: 371.295479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.595901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.224059Z","time spent":"371.826938ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.595556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.225806Z","time spent":"369.723665ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:30.595987Z","caller":"traceutil/trace.go:171","msg":"trace[1943564875] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"375.06683ms","start":"2026-04-03T05:02:30.22091Z","end":"2026-04-03T05:02:30.595977Z","steps":["trace[1943564875] 'process raft request' (duration: 374.356685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.595665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.223912Z","time spent":"371.674772ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.596034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.220885Z","time spent":"375.126461ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.596153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.979609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:02:30.596202Z","caller":"traceutil/trace.go:171","msg":"trace[1841593865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"378.070853ms","start":"2026-04-03T05:02:30.218123Z","end":"2026-04-03T05:02:30.596194Z","steps":["trace[1841593865] 'agreement among raft nodes before linearized reading' (duration: 377.380259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.596238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.218112Z","time spent":"378.120144ms","remote":"127.0.0.1:59076","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-03T05:02:30.596248Z","caller":"traceutil/trace.go:171","msg":"trace[655375030] transaction","detail":"{read_only:false; number_of_response:1; response_revision:531; }","duration":"370.15714ms","start":"2026-04-03T05:02:30.226076Z","end":"2026-04-03T05:02:30.596234Z","steps":["trace[655375030] 'process raft request' (duration: 369.360062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.596335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.226067Z","time spent":"370.225043ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:30.596457Z","caller":"traceutil/trace.go:171","msg":"trace[1515491155] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"369.744406ms","start":"2026-04-03T05:02:30.22667Z","end":"2026-04-03T05:02:30.596415Z","steps":["trace[1515491155] 'process raft request' (duration: 369.58411ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.59648Z","caller":"traceutil/trace.go:171","msg":"trace[1405126432] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"370.278524ms","start":"2026-04-03T05:02:30.226156Z","end":"2026-04-03T05:02:30.596435Z","steps":["trace[1405126432] 'process raft request' (duration: 369.985054ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.596488Z","caller":"traceutil/trace.go:171","msg":"trace[1106382766] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"369.658093ms","start":"2026-04-03T05:02:30.226823Z","end":"2026-04-03T05:02:30.596481Z","steps":["trace[1106382766] 'process raft request' (duration: 369.471067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.596552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.22614Z","time spent":"370.378087ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:02:30.596574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.226664Z","time spent":"369.853399ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:02:30.596574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.226818Z","time spent":"369.737836ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T05:02:30.596549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.217077Z","time spent":"378.912362ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:02:30.596742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.59117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T05:02:30.596782Z","caller":"traceutil/trace.go:171","msg":"trace[714395608] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"314.759446ms","start":"2026-04-03T05:02:30.281984Z","end":"2026-04-03T05:02:30.596743Z","steps":["trace[714395608] 'process raft request' (duration: 314.483487ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.596793Z","caller":"traceutil/trace.go:171","msg":"trace[1683457581] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"316.657181ms","start":"2026-04-03T05:02:30.280118Z","end":"2026-04-03T05:02:30.596775Z","steps":["trace[1683457581] 'process raft request' (duration: 316.239777ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.596849Z","caller":"traceutil/trace.go:171","msg":"trace[413784460] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"281.965625ms","start":"2026-04-03T05:02:30.314874Z","end":"2026-04-03T05:02:30.59684Z","steps":["trace[413784460] 'process raft request' (duration: 281.723707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.596871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.281963Z","time spent":"314.86548ms","remote":"127.0.0.1:59124","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-03T05:02:30.596908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.280101Z","time spent":"316.749865ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:30.59681Z","caller":"traceutil/trace.go:171","msg":"trace[1839381489] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:537; }","duration":"316.683663ms","start":"2026-04-03T05:02:30.28011Z","end":"2026-04-03T05:02:30.596794Z","steps":["trace[1839381489] 'agreement among raft nodes before linearized reading' (duration: 316.560229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.597077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:30.280095Z","time spent":"316.960672ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"info","ts":"2026-04-03T05:02:30.832875Z","caller":"traceutil/trace.go:171","msg":"trace[790927228] transaction","detail":"{read_only:false; number_of_response:1; response_revision:539; }","duration":"200.265296ms","start":"2026-04-03T05:02:30.632569Z","end":"2026-04-03T05:02:30.832834Z","steps":["trace[790927228] 'process raft request' (duration: 194.393183ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.833009Z","caller":"traceutil/trace.go:171","msg":"trace[971528357] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"194.929751ms","start":"2026-04-03T05:02:30.638055Z","end":"2026-04-03T05:02:30.832985Z","steps":["trace[971528357] 'process raft request' (duration: 194.683442ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.833253Z","caller":"traceutil/trace.go:171","msg":"trace[361493958] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"187.239675ms","start":"2026-04-03T05:02:30.64593Z","end":"2026-04-03T05:02:30.83317Z","steps":["trace[361493958] 'process raft request' (duration: 187.013337ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.833316Z","caller":"traceutil/trace.go:171","msg":"trace[2032623977] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"190.986495ms","start":"2026-04-03T05:02:30.642299Z","end":"2026-04-03T05:02:30.833286Z","steps":["trace[2032623977] 'process raft request' (duration: 190.525589ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.833542Z","caller":"traceutil/trace.go:171","msg":"trace[482532346] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"180.299406ms","start":"2026-04-03T05:02:30.653221Z","end":"2026-04-03T05:02:30.83352Z","steps":["trace[482532346] 'process raft request' (duration: 179.867271ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.833591Z","caller":"traceutil/trace.go:171","msg":"trace[651627157] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:560; }","duration":"191.529504ms","start":"2026-04-03T05:02:30.642048Z","end":"2026-04-03T05:02:30.833577Z","steps":["trace[651627157] 'read index received' (duration: 36.841973ms)","trace[651627157] 'applied index is now lower than readState.Index' (duration: 154.686721ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:30.833653Z","caller":"traceutil/trace.go:171","msg":"trace[491866365] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"177.310512ms","start":"2026-04-03T05:02:30.656328Z","end":"2026-04-03T05:02:30.833638Z","steps":["trace[491866365] 'process raft request' (duration: 177.146707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.833856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.746982ms","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-03T05:02:30.833918Z","caller":"traceutil/trace.go:171","msg":"trace[1762218635] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:544; }","duration":"191.876225ms","start":"2026-04-03T05:02:30.642024Z","end":"2026-04-03T05:02:30.8339Z","steps":["trace[1762218635] 'agreement among raft nodes before linearized reading' (duration: 191.639817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.837851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.694328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-03T05:02:30.837989Z","caller":"traceutil/trace.go:171","msg":"trace[1713936268] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:546; }","duration":"195.893734ms","start":"2026-04-03T05:02:30.642069Z","end":"2026-04-03T05:02:30.837962Z","steps":["trace[1713936268] 'agreement among raft nodes before linearized reading' (duration: 195.627625ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.838573Z","caller":"traceutil/trace.go:171","msg":"trace[545992870] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"149.538074ms","start":"2026-04-03T05:02:30.68902Z","end":"2026-04-03T05:02:30.838558Z","steps":["trace[545992870] 'process raft request' (duration: 148.285591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.838753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.631609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-04-03T05:02:30.838825Z","caller":"traceutil/trace.go:171","msg":"trace[821917050] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:546; }","duration":"196.730063ms","start":"2026-04-03T05:02:30.642081Z","end":"2026-04-03T05:02:30.838811Z","steps":["trace[821917050] 'agreement among raft nodes before linearized reading' (duration: 196.494075ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:30.838994Z","caller":"traceutil/trace.go:171","msg":"trace[1941714126] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"149.148851ms","start":"2026-04-03T05:02:30.689835Z","end":"2026-04-03T05:02:30.838984Z","steps":["trace[1941714126] 'process raft request' (duration: 147.783623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:30.839153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.981639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-03T05:02:30.839214Z","caller":"traceutil/trace.go:171","msg":"trace[1624159024] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:546; }","duration":"159.070472ms","start":"2026-04-03T05:02:30.680133Z","end":"2026-04-03T05:02:30.839204Z","steps":["trace[1624159024] 'agreement among raft nodes before linearized reading' (duration: 158.961058ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:31.008403Z","caller":"traceutil/trace.go:171","msg":"trace[1843963981] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"118.108408ms","start":"2026-04-03T05:02:30.890264Z","end":"2026-04-03T05:02:31.008372Z","steps":["trace[1843963981] 'process raft request' (duration: 118.023675ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:31.0085Z","caller":"traceutil/trace.go:171","msg":"trace[694488261] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"159.01013ms","start":"2026-04-03T05:02:30.849477Z","end":"2026-04-03T05:02:31.008487Z","steps":["trace[694488261] 'process raft request' (duration: 158.71911ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:31.008406Z","caller":"traceutil/trace.go:171","msg":"trace[529635290] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"163.220425ms","start":"2026-04-03T05:02:30.845141Z","end":"2026-04-03T05:02:31.008362Z","steps":["trace[529635290] 'process raft request' (duration: 134.264107ms)","trace[529635290] 'compare' (duration: 28.606266ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:31.573801Z","caller":"traceutil/trace.go:171","msg":"trace[921862109] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"548.479595ms","start":"2026-04-03T05:02:31.025281Z","end":"2026-04-03T05:02:31.57376Z","steps":["trace[921862109] 'process raft request' (duration: 515.962093ms)","trace[921862109] 'compare' (duration: 32.252772ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:02:31.574021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:31.025255Z","time spent":"548.660071ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:31.574274Z","caller":"traceutil/trace.go:171","msg":"trace[1682033602] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"491.224738ms","start":"2026-04-03T05:02:31.08304Z","end":"2026-04-03T05:02:31.574264Z","steps":["trace[1682033602] 'process raft request' (duration: 491.126694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:31.574355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:31.083007Z","time spent":"491.29491ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:31.686073Z","caller":"traceutil/trace.go:171","msg":"trace[1831172085] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:573; }","duration":"159.289304ms","start":"2026-04-03T05:02:31.526735Z","end":"2026-04-03T05:02:31.686024Z","steps":["trace[1831172085] 'read index received' (duration: 14.710123ms)","trace[1831172085] 'applied index is now lower than readState.Index' (duration: 144.578041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:02:31.686278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.530362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:02:31.686372Z","caller":"traceutil/trace.go:171","msg":"trace[814606530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"159.634705ms","start":"2026-04-03T05:02:31.526716Z","end":"2026-04-03T05:02:31.686351Z","steps":["trace[814606530] 'agreement among raft nodes before linearized reading' (duration: 159.4936ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:31.686273Z","caller":"traceutil/trace.go:171","msg":"trace[666324112] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"570.088873ms","start":"2026-04-03T05:02:31.116044Z","end":"2026-04-03T05:02:31.686133Z","steps":["trace[666324112] 'process raft request' (duration: 568.218959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:31.686561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:31.116025Z","time spent":"570.444195ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:31.866184Z","caller":"traceutil/trace.go:171","msg":"trace[1302728518] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"100.187884ms","start":"2026-04-03T05:02:31.765972Z","end":"2026-04-03T05:02:31.86616Z","steps":["trace[1302728518] 'process raft request' (duration: 100.049289ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:32.121774Z","caller":"traceutil/trace.go:171","msg":"trace[482618879] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"145.465961ms","start":"2026-04-03T05:02:31.976262Z","end":"2026-04-03T05:02:32.121728Z","steps":["trace[482618879] 'process raft request' (duration: 144.177667ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:32.167775Z","caller":"traceutil/trace.go:171","msg":"trace[707120561] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"162.778137ms","start":"2026-04-03T05:02:32.004972Z","end":"2026-04-03T05:02:32.167751Z","steps":["trace[707120561] 'process raft request' (duration: 162.665253ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:32.168513Z","caller":"traceutil/trace.go:171","msg":"trace[1840420073] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"187.436028ms","start":"2026-04-03T05:02:31.981071Z","end":"2026-04-03T05:02:32.168507Z","steps":["trace[1840420073] 'process raft request' (duration: 186.350242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:39.029399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741170226874463,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T05:02:39.099406Z","caller":"traceutil/trace.go:171","msg":"trace[1767730861] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"970.035647ms","start":"2026-04-03T05:02:38.129341Z","end":"2026-04-03T05:02:39.099376Z","steps":["trace[1767730861] 'process raft request' (duration: 969.882262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:39.099689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:38.129309Z","time spent":"970.186202ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:39.813365Z","caller":"traceutil/trace.go:171","msg":"trace[397396952] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"1.284732724s","start":"2026-04-03T05:02:38.528603Z","end":"2026-04-03T05:02:39.813336Z","steps":["trace[397396952] 'read index received' (duration: 570.709203ms)","trace[397396952] 'applied index is now lower than readState.Index' (duration: 714.022371ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:39.813508Z","caller":"traceutil/trace.go:171","msg":"trace[197261163] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.63323146s","start":"2026-04-03T05:02:38.180224Z","end":"2026-04-03T05:02:39.813455Z","steps":["trace[197261163] 'process raft request' (duration: 1.631584198s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:39.813701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:38.180194Z","time spent":"1.633404065s","remote":"127.0.0.1:59160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:02:39.813708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186169365s","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-03T05:02:39.813771Z","caller":"traceutil/trace.go:171","msg":"trace[346137455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"1.186267308s","start":"2026-04-03T05:02:38.627482Z","end":"2026-04-03T05:02:39.813749Z","steps":["trace[346137455] 'agreement among raft nodes before linearized reading' (duration: 1.186099603s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:39.813876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:38.627458Z","time spent":"1.186403253s","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T05:02:39.813547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284944431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:02:39.813972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170998892s","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-03T05:02:39.813982Z","caller":"traceutil/trace.go:171","msg":"trace[1726624902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"1.285448006s","start":"2026-04-03T05:02:38.528521Z","end":"2026-04-03T05:02:39.813969Z","steps":["trace[1726624902] 'agreement among raft nodes before linearized reading' (duration: 1.284965671s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:39.814031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:38.528463Z","time spent":"1.28555947s","remote":"127.0.0.1:59076","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-03T05:02:39.813999Z","caller":"traceutil/trace.go:171","msg":"trace[403524210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"1.171063585s","start":"2026-04-03T05:02:38.642928Z","end":"2026-04-03T05:02:39.813992Z","steps":["trace[403524210] 'agreement among raft nodes before linearized reading' (duration: 1.170942091s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:39.814081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178116039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-03T05:02:39.814101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:38.64291Z","time spent":"1.171184928s","remote":"127.0.0.1:59338","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-03T05:02:39.814086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170988161s","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-03T05:02:39.814154Z","caller":"traceutil/trace.go:171","msg":"trace[1063638544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"1.178211292s","start":"2026-04-03T05:02:38.635919Z","end":"2026-04-03T05:02:39.814131Z","steps":["trace[1063638544] 'agreement among raft nodes before linearized reading' (duration: 1.178011966s)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:39.814192Z","caller":"traceutil/trace.go:171","msg":"trace[281624187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"1.171152647s","start":"2026-04-03T05:02:38.643013Z","end":"2026-04-03T05:02:39.814165Z","steps":["trace[281624187] 'agreement among raft nodes before linearized reading' (duration: 1.170668312s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:39.814203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:38.635909Z","time spent":"1.178282614s","remote":"127.0.0.1:59338","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":"warn","ts":"2026-04-03T05:02:39.814239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:38.64299Z","time spent":"1.17123984s","remote":"127.0.0.1:59338","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-03T05:02:39.923489Z","caller":"traceutil/trace.go:171","msg":"trace[1202672288] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"103.500923ms","start":"2026-04-03T05:02:39.819922Z","end":"2026-04-03T05:02:39.923422Z","steps":["trace[1202672288] 'process raft request' (duration: 76.695408ms)","trace[1202672288] 'compare' (duration: 26.613569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:02:41.779152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732648587s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:02:41.779444Z","caller":"traceutil/trace.go:171","msg":"trace[1714949631] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"1.957800303s","start":"2026-04-03T05:02:39.821581Z","end":"2026-04-03T05:02:41.779382Z","steps":["trace[1714949631] 'read index received' (duration: 75.052096ms)","trace[1714949631] 'applied index is now lower than readState.Index' (duration: 1.882747387s)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:41.779583Z","caller":"traceutil/trace.go:171","msg":"trace[6527221] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.959653391s","start":"2026-04-03T05:02:39.819922Z","end":"2026-04-03T05:02:41.779575Z","steps":["trace[6527221] 'process raft request' (duration: 226.493038ms)","trace[6527221] 'compare' (duration: 1.732106121s)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:02:41.779618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:39.819898Z","time spent":"1.959700633s","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:41.779711Z","caller":"traceutil/trace.go:171","msg":"trace[1787882772] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.959717143s","start":"2026-04-03T05:02:39.81999Z","end":"2026-04-03T05:02:41.779707Z","steps":["trace[1787882772] 'process raft request' (duration: 1.959246929s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:41.779736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:39.819982Z","time spent":"1.959739584s","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T05:02:41.779795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958187905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T05:02:41.779912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.043454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:02:41.779899Z","caller":"traceutil/trace.go:171","msg":"trace[316536286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"1.958316229s","start":"2026-04-03T05:02:39.821549Z","end":"2026-04-03T05:02:41.779865Z","steps":["trace[316536286] 'agreement among raft nodes before linearized reading' (duration: 1.958162404s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:41.779978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:39.821529Z","time spent":"1.958433203s","remote":"127.0.0.1:59062","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-03T05:02:41.780036Z","caller":"traceutil/trace.go:171","msg":"trace[598809622] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.913167895s","start":"2026-04-03T05:02:39.866863Z","end":"2026-04-03T05:02:41.780031Z","steps":["trace[598809622] 'process raft request' (duration: 1.912466804s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:41.780061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:39.866835Z","time spent":"1.913215177s","remote":"127.0.0.1:59384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:41.780108Z","caller":"traceutil/trace.go:171","msg":"trace[965559008] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.959856747s","start":"2026-04-03T05:02:39.820228Z","end":"2026-04-03T05:02:41.780085Z","steps":["trace[965559008] 'process raft request' (duration: 1.959076403s)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:41.779932Z","caller":"traceutil/trace.go:171","msg":"trace[352256847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"122.127226ms","start":"2026-04-03T05:02:41.657797Z","end":"2026-04-03T05:02:41.779925Z","steps":["trace[352256847] 'agreement among raft nodes before linearized reading' (duration: 122.103015ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:41.780215Z","caller":"traceutil/trace.go:171","msg":"trace[2129095807] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.889766058s","start":"2026-04-03T05:02:39.890435Z","end":"2026-04-03T05:02:41.780201Z","steps":["trace[2129095807] 'process raft request' (duration: 1.888921712s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:41.780238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:39.820199Z","time spent":"1.959972021s","remote":"127.0.0.1:59338","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-03T05:02:41.779844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498593169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T05:02:41.780272Z","caller":"traceutil/trace.go:171","msg":"trace[364934643] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:620; }","duration":"1.499059985s","start":"2026-04-03T05:02:40.281206Z","end":"2026-04-03T05:02:41.780266Z","steps":["trace[364934643] 'agreement among raft nodes before linearized reading' (duration: 1.49858135s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:41.780296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:40.281185Z","time spent":"1.499104865s","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-04-03T05:02:41.780333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:39.890405Z","time spent":"1.88984982s","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:02:43.539325Z","caller":"traceutil/trace.go:171","msg":"trace[360869365] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"280.889178ms","start":"2026-04-03T05:02:43.258404Z","end":"2026-04-03T05:02:43.539293Z","steps":["trace[360869365] 'process raft request' (duration: 262.84242ms)","trace[360869365] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; req_size:1036253; } (duration: 16.221342ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:43.539657Z","caller":"traceutil/trace.go:171","msg":"trace[1676653586] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"177.209723ms","start":"2026-04-03T05:02:43.362435Z","end":"2026-04-03T05:02:43.539645Z","steps":["trace[1676653586] 'process raft request' (duration: 175.924414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:47.136646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.011898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-03T05:02:47.136794Z","caller":"traceutil/trace.go:171","msg":"trace[1137105667] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"576.172863ms","start":"2026-04-03T05:02:46.560561Z","end":"2026-04-03T05:02:47.136734Z","steps":["trace[1137105667] 'range keys from in-memory index tree' (duration: 575.781201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:47.136851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:46.56055Z","time spent":"576.286286ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-04-03T05:02:47.137138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.242616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T05:02:47.137919Z","caller":"traceutil/trace.go:171","msg":"trace[1252194863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"611.460802ms","start":"2026-04-03T05:02:46.525784Z","end":"2026-04-03T05:02:47.137244Z","steps":["trace[1252194863] 'range keys from in-memory index tree' (duration: 611.159454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:47.13809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:46.525763Z","time spent":"612.217145ms","remote":"127.0.0.1:59062","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-03T05:02:47.179595Z","caller":"traceutil/trace.go:171","msg":"trace[277805271] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"217.458251ms","start":"2026-04-03T05:02:46.962107Z","end":"2026-04-03T05:02:47.179565Z","steps":["trace[277805271] 'read index received' (duration: 216.930055ms)","trace[277805271] 'applied index is now lower than readState.Index' (duration: 527.156µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:47.179632Z","caller":"traceutil/trace.go:171","msg":"trace[699148569] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"391.340544ms","start":"2026-04-03T05:02:46.788262Z","end":"2026-04-03T05:02:47.179603Z","steps":["trace[699148569] 'process raft request' (duration: 391.028565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:47.179868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.741789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-03T05:02:47.179915Z","caller":"traceutil/trace.go:171","msg":"trace[485005285] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:706; }","duration":"217.805251ms","start":"2026-04-03T05:02:46.962098Z","end":"2026-04-03T05:02:47.179904Z","steps":["trace[485005285] 'agreement among raft nodes before linearized reading' (duration: 217.595534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:02:47.180016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:02:46.788224Z","time spent":"391.637063ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T05:02:47.32268Z","caller":"traceutil/trace.go:171","msg":"trace[1014979925] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"130.475249ms","start":"2026-04-03T05:02:47.192166Z","end":"2026-04-03T05:02:47.322642Z","steps":["trace[1014979925] 'process raft request' (duration: 55.995045ms)","trace[1014979925] 'compare' (duration: 74.284999ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:47.361667Z","caller":"traceutil/trace.go:171","msg":"trace[118277614] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"168.376156ms","start":"2026-04-03T05:02:47.193269Z","end":"2026-04-03T05:02:47.361645Z","steps":["trace[118277614] 'process raft request' (duration: 168.16748ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:47.361688Z","caller":"traceutil/trace.go:171","msg":"trace[433183800] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"156.287033ms","start":"2026-04-03T05:02:47.205379Z","end":"2026-04-03T05:02:47.361666Z","steps":["trace[433183800] 'process raft request' (duration: 156.220211ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:47.465421Z","caller":"traceutil/trace.go:171","msg":"trace[577464655] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"104.054147ms","start":"2026-04-03T05:02:47.361293Z","end":"2026-04-03T05:02:47.465347Z","steps":["trace[577464655] 'read index received' (duration: 69.22037ms)","trace[577464655] 'applied index is now lower than readState.Index' (duration: 34.832797ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:47.465473Z","caller":"traceutil/trace.go:171","msg":"trace[2140162036] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"213.058469ms","start":"2026-04-03T05:02:47.252363Z","end":"2026-04-03T05:02:47.465422Z","steps":["trace[2140162036] 'process raft request' (duration: 178.547932ms)","trace[2140162036] 'compare' (duration: 34.286121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:02:47.46574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.417418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-03T05:02:47.465801Z","caller":"traceutil/trace.go:171","msg":"trace[450905797] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:710; }","duration":"104.548222ms","start":"2026-04-03T05:02:47.36124Z","end":"2026-04-03T05:02:47.465788Z","steps":["trace[450905797] 'agreement among raft nodes before linearized reading' (duration: 104.312905ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:55.529147Z","caller":"traceutil/trace.go:171","msg":"trace[1807498946] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"166.627621ms","start":"2026-04-03T05:02:55.362488Z","end":"2026-04-03T05:02:55.529116Z","steps":["trace[1807498946] 'process raft request' (duration: 109.654676ms)","trace[1807498946] 'compare' (duration: 56.729699ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:02:55.529532Z","caller":"traceutil/trace.go:171","msg":"trace[1786627774] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"166.473967ms","start":"2026-04-03T05:02:55.363029Z","end":"2026-04-03T05:02:55.529503Z","steps":["trace[1786627774] 'process raft request' (duration: 166.034125ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:55.52966Z","caller":"traceutil/trace.go:171","msg":"trace[699062197] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"165.892741ms","start":"2026-04-03T05:02:55.363736Z","end":"2026-04-03T05:02:55.529628Z","steps":["trace[699062197] 'process raft request' (duration: 165.551792ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:02:55.718961Z","caller":"traceutil/trace.go:171","msg":"trace[314177379] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"179.44738ms","start":"2026-04-03T05:02:55.539463Z","end":"2026-04-03T05:02:55.71891Z","steps":["trace[314177379] 'process raft request' (duration: 145.126858ms)","trace[314177379] 'compare' (duration: 34.142697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:03:02.462946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741170226874838,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T05:03:02.551018Z","caller":"traceutil/trace.go:171","msg":"trace[2099530265] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"831.819052ms","start":"2026-04-03T05:03:01.719165Z","end":"2026-04-03T05:03:02.550984Z","steps":["trace[2099530265] 'process raft request' (duration: 831.631657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:03:02.551187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:03:01.719137Z","time spent":"831.969466ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T05:03:02.562712Z","caller":"traceutil/trace.go:171","msg":"trace[1839555935] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:832; }","duration":"599.971718ms","start":"2026-04-03T05:03:01.962709Z","end":"2026-04-03T05:03:02.562681Z","steps":["trace[1839555935] 'read index received' (duration: 588.27098ms)","trace[1839555935] 'applied index is now lower than readState.Index' (duration: 11.699658ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:03:02.56278Z","caller":"traceutil/trace.go:171","msg":"trace[1430914441] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"286.835473ms","start":"2026-04-03T05:03:02.275913Z","end":"2026-04-03T05:03:02.562749Z","steps":["trace[1430914441] 'process raft request' (duration: 286.478994ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:03:02.562857Z","caller":"traceutil/trace.go:171","msg":"trace[1682333] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"178.534467ms","start":"2026-04-03T05:03:02.384311Z","end":"2026-04-03T05:03:02.562845Z","steps":["trace[1682333] 'process raft request' (duration: 178.314292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:03:02.56306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.180709ms","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-03T05:03:02.563096Z","caller":"traceutil/trace.go:171","msg":"trace[152925396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"594.272421ms","start":"2026-04-03T05:03:01.968814Z","end":"2026-04-03T05:03:02.563087Z","steps":["trace[152925396] 'agreement among raft nodes before linearized reading' (duration: 594.106647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:03:02.56312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:03:01.968795Z","time spent":"594.316942ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T05:03:02.563119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.695658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T05:03:02.563158Z","caller":"traceutil/trace.go:171","msg":"trace[1779757562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"535.76397ms","start":"2026-04-03T05:03:02.027383Z","end":"2026-04-03T05:03:02.563146Z","steps":["trace[1779757562] 'agreement among raft nodes before linearized reading' (duration: 535.664927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:03:02.563188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:03:02.027367Z","time spent":"535.812391ms","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T05:03:02.563155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.199015ms","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-03T05:03:02.563239Z","caller":"traceutil/trace.go:171","msg":"trace[1537432142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"479.347249ms","start":"2026-04-03T05:03:02.083875Z","end":"2026-04-03T05:03:02.563222Z","steps":["trace[1537432142] 'agreement among raft nodes before linearized reading' (duration: 479.088212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:03:02.563277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.569043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-03T05:03:02.563314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:03:02.083853Z","time spent":"479.411531ms","remote":"127.0.0.1:59338","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-03T05:03:02.56332Z","caller":"traceutil/trace.go:171","msg":"trace[915368316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"600.642105ms","start":"2026-04-03T05:03:01.96266Z","end":"2026-04-03T05:03:02.563302Z","steps":["trace[915368316] 'agreement among raft nodes before linearized reading' (duration: 600.535102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:03:02.563352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:03:01.962638Z","time spent":"600.706297ms","remote":"127.0.0.1:59338","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":"warn","ts":"2026-04-03T05:03:02.56394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.17252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:03:02.563994Z","caller":"traceutil/trace.go:171","msg":"trace[1789671094] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:804; }","duration":"568.684732ms","start":"2026-04-03T05:03:01.995297Z","end":"2026-04-03T05:03:02.563981Z","steps":["trace[1789671094] 'agreement among raft nodes before linearized reading' (duration: 568.143868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:03:02.564028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T05:03:01.995265Z","time spent":"568.752554ms","remote":"127.0.0.1:59452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-03T05:04:39.299399Z","caller":"traceutil/trace.go:171","msg":"trace[1029032635] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"180.959636ms","start":"2026-04-03T05:04:39.118415Z","end":"2026-04-03T05:04:39.299375Z","steps":["trace[1029032635] 'read index received' (duration: 180.742842ms)","trace[1029032635] 'applied index is now lower than readState.Index' (duration: 215.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:04:39.29946Z","caller":"traceutil/trace.go:171","msg":"trace[2094460388] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"189.024925ms","start":"2026-04-03T05:04:39.110411Z","end":"2026-04-03T05:04:39.299436Z","steps":["trace[2094460388] 'process raft request' (duration: 188.821182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:04:39.299708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.280383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-03T05:04:39.299774Z","caller":"traceutil/trace.go:171","msg":"trace[488574794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1252; }","duration":"144.369905ms","start":"2026-04-03T05:04:39.15537Z","end":"2026-04-03T05:04:39.29974Z","steps":["trace[488574794] 'agreement among raft nodes before linearized reading' (duration: 144.140562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:04:39.300717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.259645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12751"} {"level":"info","ts":"2026-04-03T05:04:39.300822Z","caller":"traceutil/trace.go:171","msg":"trace[1277583096] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1252; }","duration":"182.422347ms","start":"2026-04-03T05:04:39.118376Z","end":"2026-04-03T05:04:39.300799Z","steps":["trace[1277583096] 'agreement among raft nodes before linearized reading' (duration: 181.822338ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:04:39.432146Z","caller":"traceutil/trace.go:171","msg":"trace[740234048] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"127.156161ms","start":"2026-04-03T05:04:39.304961Z","end":"2026-04-03T05:04:39.432117Z","steps":["trace[740234048] 'process raft request' (duration: 122.601433ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:04:39.432344Z","caller":"traceutil/trace.go:171","msg":"trace[1877963892] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"127.158151ms","start":"2026-04-03T05:04:39.305175Z","end":"2026-04-03T05:04:39.432334Z","steps":["trace[1877963892] 'process raft request' (duration: 126.891747ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:04:39.43273Z","caller":"traceutil/trace.go:171","msg":"trace[1709170099] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"126.085824ms","start":"2026-04-03T05:04:39.306617Z","end":"2026-04-03T05:04:39.432703Z","steps":["trace[1709170099] 'process raft request' (duration: 125.663158ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:01.606438Z","caller":"traceutil/trace.go:171","msg":"trace[332849448] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"116.198386ms","start":"2026-04-03T05:05:01.49022Z","end":"2026-04-03T05:05:01.606419Z","steps":["trace[332849448] 'process raft request' (duration: 115.978463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:01.841363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.019923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T05:05:01.841697Z","caller":"traceutil/trace.go:171","msg":"trace[868161363] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1420; }","duration":"211.332641ms","start":"2026-04-03T05:05:01.630313Z","end":"2026-04-03T05:05:01.841646Z","steps":["trace[868161363] 'read index received' (duration: 61.851461ms)","trace[868161363] 'applied index is now lower than readState.Index' (duration: 149.4788ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T05:05:01.841702Z","caller":"traceutil/trace.go:171","msg":"trace[971467101] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"220.505508ms","start":"2026-04-03T05:05:01.621159Z","end":"2026-04-03T05:05:01.841665Z","steps":["trace[971467101] 'process raft request' (duration: 71.096109ms)","trace[971467101] 'compare' (duration: 148.597948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T05:05:01.841884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.551984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T05:05:01.841965Z","caller":"traceutil/trace.go:171","msg":"trace[291045451] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1363; }","duration":"211.704676ms","start":"2026-04-03T05:05:01.630248Z","end":"2026-04-03T05:05:01.841953Z","steps":["trace[291045451] 'agreement among raft nodes before linearized reading' (duration: 211.526023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T05:05:01.842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.60551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-03T05:05:01.842013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.6024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-03T05:05:01.842045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.598616ms","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-03T05:05:01.842089Z","caller":"traceutil/trace.go:171","msg":"trace[1922950698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1363; }","duration":"169.740122ms","start":"2026-04-03T05:05:01.672335Z","end":"2026-04-03T05:05:01.842076Z","steps":["trace[1922950698] 'agreement among raft nodes before linearized reading' (duration: 169.562759ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:01.842111Z","caller":"traceutil/trace.go:171","msg":"trace[1969054130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1363; }","duration":"168.726778ms","start":"2026-04-03T05:05:01.673366Z","end":"2026-04-03T05:05:01.842093Z","steps":["trace[1969054130] 'agreement among raft nodes before linearized reading' (duration: 168.517925ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:01.842034Z","caller":"traceutil/trace.go:171","msg":"trace[1348430058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1363; }","duration":"210.663731ms","start":"2026-04-03T05:05:01.63136Z","end":"2026-04-03T05:05:01.842024Z","steps":["trace[1348430058] 'agreement among raft nodes before linearized reading' (duration: 210.480218ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:02.016639Z","caller":"traceutil/trace.go:171","msg":"trace[1982373140] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"169.957174ms","start":"2026-04-03T05:05:01.846661Z","end":"2026-04-03T05:05:02.016618Z","steps":["trace[1982373140] 'process raft request' (duration: 169.914364ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T05:05:02.016712Z","caller":"traceutil/trace.go:171","msg":"trace[625269557] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"170.047295ms","start":"2026-04-03T05:05:01.846633Z","end":"2026-04-03T05:05:02.01668Z","steps":["trace[625269557] 'process raft request' (duration: 169.624219ms)"],"step_count":1}