{"level":"warn","ts":"2026-10-04T05:41:35.104739Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:41:35.104814Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.95:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.95:2380","--initial-cluster=instance=https://162.253.55.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.95:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-04T05:41:35.104864Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:41:35.104874Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.95:2380"]} {"level":"info","ts":"2026-10-04T05:41:35.104889Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T05:41:35.105381Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"]} {"level":"info","ts":"2026-10-04T05:41:35.105455Z","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://162.253.55.95:2380"],"listen-peer-urls":["https://162.253.55.95:2380"],"advertise-client-urls":["https://162.253.55.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.95:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-04T05:41:35.110275Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.662396ms"} {"level":"info","ts":"2026-10-04T05:41:35.122046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"282167b6747c9cda","cluster-id":"dc65cfc6e389e361"} {"level":"info","ts":"2026-10-04T05:41:35.12211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T05:41:35.122125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became follower at term 0"} {"level":"info","ts":"2026-10-04T05:41:35.122132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 282167b6747c9cda [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T05:41:35.122142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became follower at term 1"} {"level":"info","ts":"2026-10-04T05:41:35.122172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=(2891706469084601562)"} {"level":"warn","ts":"2026-10-04T05:41:35.135777Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T05:41:35.137458Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T05:41:35.138826Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-04T05:41:35.140974Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"282167b6747c9cda","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T05:41:35.144569Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"282167b6747c9cda","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T05:41:35.144704Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T05:41:35.144747Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T05:41:35.144756Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T05:41:35.149929Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T05:41:35.150101Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"282167b6747c9cda","initial-advertise-peer-urls":["https://162.253.55.95:2380"],"listen-peer-urls":["https://162.253.55.95:2380"],"advertise-client-urls":["https://162.253.55.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T05:41:35.150133Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T05:41:35.150232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.95:2380"} {"level":"info","ts":"2026-10-04T05:41:35.15024Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.95:2380"} {"level":"info","ts":"2026-10-04T05:41:35.150709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=(2891706469084601562)"} {"level":"info","ts":"2026-10-04T05:41:35.150863Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dc65cfc6e389e361","local-member-id":"282167b6747c9cda","added-peer-id":"282167b6747c9cda","added-peer-peer-urls":["https://162.253.55.95:2380"]} {"level":"info","ts":"2026-10-04T05:41:35.422447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T05:41:35.422566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T05:41:35.422605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda received MsgPreVoteResp from 282167b6747c9cda at term 1"} {"level":"info","ts":"2026-10-04T05:41:35.422637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became candidate at term 2"} {"level":"info","ts":"2026-10-04T05:41:35.422662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda received MsgVoteResp from 282167b6747c9cda at term 2"} {"level":"info","ts":"2026-10-04T05:41:35.42269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became leader at term 2"} {"level":"info","ts":"2026-10-04T05:41:35.422719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 282167b6747c9cda elected leader 282167b6747c9cda at term 2"} {"level":"info","ts":"2026-10-04T05:41:35.423717Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"282167b6747c9cda","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.95:2379]}","request-path":"/0/members/282167b6747c9cda/attributes","cluster-id":"dc65cfc6e389e361","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T05:41:35.423895Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:41:35.42415Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T05:41:35.423959Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:41:35.424002Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:41:35.424177Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T05:41:35.425239Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dc65cfc6e389e361","local-member-id":"282167b6747c9cda","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:41:35.425297Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:41:35.425365Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:41:35.427486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.95:2379"} {"level":"info","ts":"2026-10-04T05:41:35.427599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T05:41:45.012701Z","caller":"traceutil/trace.go:171","msg":"trace[129146707] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"162.406975ms","start":"2026-10-04T05:41:44.850247Z","end":"2026-10-04T05:41:45.012654Z","steps":["trace[129146707] 'process raft request' (duration: 143.51918ms)","trace[129146707] 'compare' (duration: 18.803253ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:41:45.430558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.593418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-04T05:41:45.430648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.513174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-10-04T05:41:45.430713Z","caller":"traceutil/trace.go:171","msg":"trace[1336636604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:346; }","duration":"121.75508ms","start":"2026-10-04T05:41:45.308892Z","end":"2026-10-04T05:41:45.430647Z","steps":["trace[1336636604] 'range keys from in-memory index tree' (duration: 121.465996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:41:45.430812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.330457ms","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-10-04T05:41:45.430842Z","caller":"traceutil/trace.go:171","msg":"trace[1387824469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:346; }","duration":"152.400829ms","start":"2026-10-04T05:41:45.278433Z","end":"2026-10-04T05:41:45.430834Z","steps":["trace[1387824469] 'range keys from in-memory index tree' (duration: 151.906691ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:41:45.430722Z","caller":"traceutil/trace.go:171","msg":"trace[234542988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:346; }","duration":"151.644276ms","start":"2026-10-04T05:41:45.279058Z","end":"2026-10-04T05:41:45.430702Z","steps":["trace[234542988] 'range keys from in-memory index tree' (duration: 151.284021ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:41:47.64069Z","caller":"traceutil/trace.go:171","msg":"trace[464725955] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"181.153883ms","start":"2026-10-04T05:41:47.459519Z","end":"2026-10-04T05:41:47.640673Z","steps":["trace[464725955] 'process raft request' (duration: 172.56575ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:41:47.640863Z","caller":"traceutil/trace.go:171","msg":"trace[1586405089] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"131.17722ms","start":"2026-10-04T05:41:47.509638Z","end":"2026-10-04T05:41:47.640815Z","steps":["trace[1586405089] 'process raft request' (duration: 131.000667ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:41:53.61688Z","caller":"traceutil/trace.go:171","msg":"trace[802579803] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"134.719716ms","start":"2026-10-04T05:41:53.482137Z","end":"2026-10-04T05:41:53.616857Z","steps":["trace[802579803] 'process raft request' (duration: 134.598405ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:41:53.684463Z","caller":"traceutil/trace.go:171","msg":"trace[1620670162] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"201.87894ms","start":"2026-10-04T05:41:53.482566Z","end":"2026-10-04T05:41:53.684445Z","steps":["trace[1620670162] 'process raft request' (duration: 201.686998ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:41:58.276946Z","caller":"traceutil/trace.go:171","msg":"trace[1777293476] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"106.913886ms","start":"2026-10-04T05:41:58.169997Z","end":"2026-10-04T05:41:58.276911Z","steps":["trace[1777293476] 'process raft request' (duration: 106.711673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:41:59.409562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:41:58.896924Z","time spent":"512.624981ms","remote":"127.0.0.1:46652","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-04T05:41:59.752541Z","caller":"traceutil/trace.go:171","msg":"trace[2122735530] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"341.315364ms","start":"2026-10-04T05:41:59.411196Z","end":"2026-10-04T05:41:59.752512Z","steps":["trace[2122735530] 'read index received' (duration: 295.66911ms)","trace[2122735530] 'applied index is now lower than readState.Index' (duration: 45.645084ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:41:59.752589Z","caller":"traceutil/trace.go:171","msg":"trace[105878285] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"341.609179ms","start":"2026-10-04T05:41:59.410937Z","end":"2026-10-04T05:41:59.752546Z","steps":["trace[105878285] 'process raft request' (duration: 295.949214ms)","trace[105878285] 'compare' (duration: 45.452902ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:41:59.752828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.610298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:41:59.752869Z","caller":"traceutil/trace.go:171","msg":"trace[1448699187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:393; }","duration":"341.7447ms","start":"2026-10-04T05:41:59.411111Z","end":"2026-10-04T05:41:59.752856Z","steps":["trace[1448699187] 'agreement among raft nodes before linearized reading' (duration: 341.557747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:41:59.752911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:41:59.411081Z","time spent":"341.822302ms","remote":"127.0.0.1:46628","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-10-04T05:41:59.753475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:41:59.410901Z","time spent":"341.76277ms","remote":"127.0.0.1:46652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:41:59.753879Z","caller":"traceutil/trace.go:171","msg":"trace[2109243412] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"332.29652ms","start":"2026-10-04T05:41:59.421566Z","end":"2026-10-04T05:41:59.753862Z","steps":["trace[2109243412] 'process raft request' (duration: 332.022486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:41:59.753935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.132138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-04T05:41:59.754041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:41:59.421538Z","time spent":"332.456132ms","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4360,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:41:59.754045Z","caller":"traceutil/trace.go:171","msg":"trace[1363968705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"108.31742ms","start":"2026-10-04T05:41:59.645699Z","end":"2026-10-04T05:41:59.754016Z","steps":["trace[1363968705] 'agreement among raft nodes before linearized reading' (duration: 108.085067ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:01.525457Z","caller":"traceutil/trace.go:171","msg":"trace[274146073] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"158.182571ms","start":"2026-10-04T05:42:01.367246Z","end":"2026-10-04T05:42:01.525429Z","steps":["trace[274146073] 'process raft request' (duration: 158.014339ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:01.752038Z","caller":"traceutil/trace.go:171","msg":"trace[1216150352] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"173.200831ms","start":"2026-10-04T05:42:01.578814Z","end":"2026-10-04T05:42:01.752015Z","steps":["trace[1216150352] 'process raft request' (duration: 173.13325ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:01.752065Z","caller":"traceutil/trace.go:171","msg":"trace[1889538136] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"173.189681ms","start":"2026-10-04T05:42:01.578812Z","end":"2026-10-04T05:42:01.752002Z","steps":["trace[1889538136] 'process raft request' (duration: 138.230238ms)","trace[1889538136] 'compare' (duration: 34.73582ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:01.767287Z","caller":"traceutil/trace.go:171","msg":"trace[1081268545] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"188.364944ms","start":"2026-10-04T05:42:01.5789Z","end":"2026-10-04T05:42:01.767265Z","steps":["trace[1081268545] 'process raft request' (duration: 188.223963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:02.06176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.202848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:42:02.061999Z","caller":"traceutil/trace.go:171","msg":"trace[1355705461] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"288.896796ms","start":"2026-10-04T05:42:01.773064Z","end":"2026-10-04T05:42:02.061961Z","steps":["trace[1355705461] 'process raft request' (duration: 160.227331ms)","trace[1355705461] 'compare' (duration: 127.913254ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:02.062094Z","caller":"traceutil/trace.go:171","msg":"trace[654861919] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"288.998736ms","start":"2026-10-04T05:42:01.773074Z","end":"2026-10-04T05:42:02.062073Z","steps":["trace[654861919] 'process raft request' (duration: 288.803994ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:02.062239Z","caller":"traceutil/trace.go:171","msg":"trace[1980802690] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"283.65818ms","start":"2026-10-04T05:42:01.778566Z","end":"2026-10-04T05:42:02.062225Z","steps":["trace[1980802690] 'process raft request' (duration: 283.468796ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:02.062356Z","caller":"traceutil/trace.go:171","msg":"trace[1710002734] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"208.463736ms","start":"2026-10-04T05:42:01.853877Z","end":"2026-10-04T05:42:02.06234Z","steps":["trace[1710002734] 'process raft request' (duration: 208.303334ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:02.062427Z","caller":"traceutil/trace.go:171","msg":"trace[759747104] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:421; }","duration":"213.55115ms","start":"2026-10-04T05:42:01.848865Z","end":"2026-10-04T05:42:02.062416Z","steps":["trace[759747104] 'read index received' (duration: 84.438938ms)","trace[759747104] 'applied index is now lower than readState.Index' (duration: 129.111242ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:02.062487Z","caller":"traceutil/trace.go:171","msg":"trace[977404621] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"209.384509ms","start":"2026-10-04T05:42:01.853094Z","end":"2026-10-04T05:42:02.062479Z","steps":["trace[977404621] 'process raft request' (duration: 209.006943ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:02.062389Z","caller":"traceutil/trace.go:171","msg":"trace[405277434] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"285.656958ms","start":"2026-10-04T05:42:01.77668Z","end":"2026-10-04T05:42:02.062337Z","steps":["trace[405277434] 'process raft request' (duration: 285.269032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:02.062546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.114909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-04T05:42:02.06256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.511917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:42:02.062592Z","caller":"traceutil/trace.go:171","msg":"trace[1661056562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"298.582808ms","start":"2026-10-04T05:42:01.764Z","end":"2026-10-04T05:42:02.062582Z","steps":["trace[1661056562] 'agreement among raft nodes before linearized reading' (duration: 298.492656ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:02.062591Z","caller":"traceutil/trace.go:171","msg":"trace[1680140279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"293.207979ms","start":"2026-10-04T05:42:01.76937Z","end":"2026-10-04T05:42:02.062578Z","steps":["trace[1680140279] 'agreement among raft nodes before linearized reading' (duration: 293.098508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:02.062673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.660134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-zbqlr\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-10-04T05:42:02.062689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.691865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-10-04T05:42:02.062706Z","caller":"traceutil/trace.go:171","msg":"trace[902555042] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-zbqlr; range_end:; response_count:1; response_revision:411; }","duration":"209.735614ms","start":"2026-10-04T05:42:01.85296Z","end":"2026-10-04T05:42:02.062695Z","steps":["trace[902555042] 'agreement among raft nodes before linearized reading' (duration: 209.662603ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:02.062746Z","caller":"traceutil/trace.go:171","msg":"trace[1100877624] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:411; }","duration":"205.767037ms","start":"2026-10-04T05:42:01.856962Z","end":"2026-10-04T05:42:02.062729Z","steps":["trace[1100877624] 'agreement among raft nodes before linearized reading' (duration: 205.659105ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:04.196153Z","caller":"traceutil/trace.go:171","msg":"trace[1353329844] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"116.828888ms","start":"2026-10-04T05:42:04.079278Z","end":"2026-10-04T05:42:04.196107Z","steps":["trace[1353329844] 'process raft request' (duration: 116.584593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:04.575883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.898532ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-04T05:42:04.576061Z","caller":"traceutil/trace.go:171","msg":"trace[643876203] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"380.04649ms","start":"2026-10-04T05:42:04.195992Z","end":"2026-10-04T05:42:04.576039Z","steps":["trace[643876203] 'read index received' (duration: 72.026776ms)","trace[643876203] 'applied index is now lower than readState.Index' (duration: 308.017274ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:04.57614Z","caller":"traceutil/trace.go:171","msg":"trace[628415130] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"375.870099ms","start":"2026-10-04T05:42:04.200243Z","end":"2026-10-04T05:42:04.576113Z","steps":["trace[628415130] 'process raft request' (duration: 375.835538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:04.576224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:04.200229Z","time spent":"375.936559ms","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:42:04.576269Z","caller":"traceutil/trace.go:171","msg":"trace[812887306] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"376.353227ms","start":"2026-10-04T05:42:04.199906Z","end":"2026-10-04T05:42:04.57626Z","steps":["trace[812887306] 'process raft request' (duration: 376.062673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:04.576227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.22436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-04T05:42:04.576316Z","caller":"traceutil/trace.go:171","msg":"trace[842125623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:436; }","duration":"249.342101ms","start":"2026-10-04T05:42:04.326965Z","end":"2026-10-04T05:42:04.576307Z","steps":["trace[842125623] 'agreement among raft nodes before linearized reading' (duration: 249.202829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:04.57633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:04.199882Z","time spent":"376.414527ms","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:42:16.489045Z","caller":"traceutil/trace.go:171","msg":"trace[1267161251] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"452.917596ms","start":"2026-10-04T05:42:16.036072Z","end":"2026-10-04T05:42:16.48899Z","steps":["trace[1267161251] 'process raft request' (duration: 452.383719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.489439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.036012Z","time spent":"453.12598ms","remote":"127.0.0.1:46664","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-10-04T05:42:16.500271Z","caller":"traceutil/trace.go:171","msg":"trace[1447527513] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"455.989179ms","start":"2026-10-04T05:42:16.044262Z","end":"2026-10-04T05:42:16.500251Z","steps":["trace[1447527513] 'process raft request' (duration: 455.715185ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:16.500282Z","caller":"traceutil/trace.go:171","msg":"trace[1495048022] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"452.332638ms","start":"2026-10-04T05:42:16.047895Z","end":"2026-10-04T05:42:16.500228Z","steps":["trace[1495048022] 'process raft request' (duration: 452.192656ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:16.500414Z","caller":"traceutil/trace.go:171","msg":"trace[1923359157] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"430.774697ms","start":"2026-10-04T05:42:16.069611Z","end":"2026-10-04T05:42:16.500385Z","steps":["trace[1923359157] 'process raft request' (duration: 430.518872ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:16.500441Z","caller":"traceutil/trace.go:171","msg":"trace[1264301711] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"424.136203ms","start":"2026-10-04T05:42:16.076295Z","end":"2026-10-04T05:42:16.500432Z","steps":["trace[1264301711] 'process raft request' (duration: 423.875549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.500492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.076273Z","time spent":"424.197725ms","remote":"127.0.0.1:46664","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-10-04T05:42:16.500526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.069581Z","time spent":"430.893648ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:42:16.500638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.04788Z","time spent":"452.606712ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:42:16.500699Z","caller":"traceutil/trace.go:171","msg":"trace[1566852652] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"424.330306ms","start":"2026-10-04T05:42:16.076311Z","end":"2026-10-04T05:42:16.500641Z","steps":["trace[1566852652] 'process raft request' (duration: 423.90186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.500391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.044224Z","time spent":"456.113701ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:42:16.500886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.076297Z","time spent":"424.503218ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:42:16.500896Z","caller":"traceutil/trace.go:171","msg":"trace[984581591] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"418.564685ms","start":"2026-10-04T05:42:16.082307Z","end":"2026-10-04T05:42:16.500872Z","steps":["trace[984581591] 'process raft request' (duration: 418.037718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.500966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.082296Z","time spent":"418.634946ms","remote":"127.0.0.1:46664","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-10-04T05:42:16.501076Z","caller":"traceutil/trace.go:171","msg":"trace[1579340587] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"418.712998ms","start":"2026-10-04T05:42:16.082355Z","end":"2026-10-04T05:42:16.501068Z","steps":["trace[1579340587] 'process raft request' (duration: 418.337233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.50112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.08235Z","time spent":"418.750318ms","remote":"127.0.0.1:46664","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-10-04T05:42:16.501275Z","caller":"traceutil/trace.go:171","msg":"trace[598318016] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"402.969887ms","start":"2026-10-04T05:42:16.098284Z","end":"2026-10-04T05:42:16.501254Z","steps":["trace[598318016] 'process raft request' (duration: 402.616793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.501397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.098261Z","time spent":"403.065519ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:42:16.501401Z","caller":"traceutil/trace.go:171","msg":"trace[981135260] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"399.678491ms","start":"2026-10-04T05:42:16.101709Z","end":"2026-10-04T05:42:16.501388Z","steps":["trace[981135260] 'process raft request' (duration: 399.366677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.501463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.101699Z","time spent":"399.738172ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:42:16.501576Z","caller":"traceutil/trace.go:171","msg":"trace[117598453] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:515; }","duration":"208.831791ms","start":"2026-10-04T05:42:16.292726Z","end":"2026-10-04T05:42:16.501558Z","steps":["trace[117598453] 'read index received' (duration: 195.772529ms)","trace[117598453] 'applied index is now lower than readState.Index' (duration: 13.058142ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:16.501642Z","caller":"traceutil/trace.go:171","msg":"trace[895350034] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"372.417431ms","start":"2026-10-04T05:42:16.129156Z","end":"2026-10-04T05:42:16.501573Z","steps":["trace[895350034] 'process raft request' (duration: 372.112677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.501701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:16.129135Z","time spent":"372.541053ms","remote":"127.0.0.1:46664","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-10-04T05:42:16.501706Z","caller":"traceutil/trace.go:171","msg":"trace[592669210] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"255.752548ms","start":"2026-10-04T05:42:16.245941Z","end":"2026-10-04T05:42:16.501693Z","steps":["trace[592669210] 'process raft request' (duration: 255.474543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:16.502823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.084559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-04T05:42:16.502992Z","caller":"traceutil/trace.go:171","msg":"trace[2084124308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"210.203891ms","start":"2026-10-04T05:42:16.292658Z","end":"2026-10-04T05:42:16.502862Z","steps":["trace[2084124308] 'agreement among raft nodes before linearized reading' (duration: 208.974933ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:17.684098Z","caller":"traceutil/trace.go:171","msg":"trace[483918136] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"619.600084ms","start":"2026-10-04T05:42:17.064462Z","end":"2026-10-04T05:42:17.684062Z","steps":["trace[483918136] 'process raft request' (duration: 611.016724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:17.684261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:17.064432Z","time spent":"619.743526ms","remote":"127.0.0.1:46664","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-10-04T05:42:17.68484Z","caller":"traceutil/trace.go:171","msg":"trace[1020858106] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"608.533189ms","start":"2026-10-04T05:42:17.076269Z","end":"2026-10-04T05:42:17.684802Z","steps":["trace[1020858106] 'process raft request' (duration: 608.204434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:17.685037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:17.076246Z","time spent":"608.664361ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:42:17.685474Z","caller":"traceutil/trace.go:171","msg":"trace[145717919] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:578; }","duration":"354.774999ms","start":"2026-10-04T05:42:17.330672Z","end":"2026-10-04T05:42:17.685447Z","steps":["trace[145717919] 'read index received' (duration: 344.84754ms)","trace[145717919] 'applied index is now lower than readState.Index' (duration: 9.925949ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:17.68561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.932241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:42:17.68564Z","caller":"traceutil/trace.go:171","msg":"trace[1785355068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"355.042012ms","start":"2026-10-04T05:42:17.330588Z","end":"2026-10-04T05:42:17.68563Z","steps":["trace[1785355068] 'agreement among raft nodes before linearized reading' (duration: 354.944781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:17.685668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:17.330555Z","time spent":"355.107003ms","remote":"127.0.0.1:46640","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-10-04T05:42:17.685858Z","caller":"traceutil/trace.go:171","msg":"trace[505877020] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"465.560864ms","start":"2026-10-04T05:42:17.220285Z","end":"2026-10-04T05:42:17.685846Z","steps":["trace[505877020] 'process raft request' (duration: 464.715313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:17.685926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:17.220254Z","time spent":"465.625365ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:42:20.802966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.485907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:42:20.803232Z","caller":"traceutil/trace.go:171","msg":"trace[1602555474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"112.838302ms","start":"2026-10-04T05:42:20.690362Z","end":"2026-10-04T05:42:20.8032Z","steps":["trace[1602555474] 'range keys from in-memory index tree' (duration: 112.278523ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:20.840296Z","caller":"traceutil/trace.go:171","msg":"trace[643965391] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"108.917297ms","start":"2026-10-04T05:42:20.731351Z","end":"2026-10-04T05:42:20.840269Z","steps":["trace[643965391] 'process raft request' (duration: 108.045236ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:24.514645Z","caller":"traceutil/trace.go:171","msg":"trace[1642930025] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"556.952304ms","start":"2026-10-04T05:42:23.953829Z","end":"2026-10-04T05:42:24.510781Z","steps":["trace[1642930025] 'process raft request' (duration: 555.450494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:24.514893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:23.953797Z","time spent":"560.99856ms","remote":"127.0.0.1:53862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:42:24.69824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.495835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:42:24.698583Z","caller":"traceutil/trace.go:171","msg":"trace[118018399] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"739.35793ms","start":"2026-10-04T05:42:23.959201Z","end":"2026-10-04T05:42:24.698559Z","steps":["trace[118018399] 'process raft request' (duration: 638.454309ms)","trace[118018399] 'compare' (duration: 100.360064ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:24.698776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:23.959166Z","time spent":"739.503832ms","remote":"127.0.0.1:53944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:42:26.075397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376967014s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-04T05:42:26.075662Z","caller":"traceutil/trace.go:171","msg":"trace[1900640833] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:631; }","duration":"1.744835549s","start":"2026-10-04T05:42:24.330782Z","end":"2026-10-04T05:42:26.075617Z","steps":["trace[1900640833] 'read index received' (duration: 176.843471ms)","trace[1900640833] 'applied index is now lower than readState.Index' (duration: 1.567991158s)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:26.075819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.745034533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:42:26.075852Z","caller":"traceutil/trace.go:171","msg":"trace[214797502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.745105314s","start":"2026-10-04T05:42:24.330737Z","end":"2026-10-04T05:42:26.075842Z","steps":["trace[214797502] 'agreement among raft nodes before linearized reading' (duration: 1.745046863s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:26.075884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:24.330695Z","time spent":"1.745181134s","remote":"127.0.0.1:46640","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-10-04T05:42:26.076499Z","caller":"traceutil/trace.go:171","msg":"trace[1749845084] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.489924201s","start":"2026-10-04T05:42:24.586535Z","end":"2026-10-04T05:42:26.076459Z","steps":["trace[1749845084] 'process raft request' (duration: 1.488955898s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:26.076771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:24.586517Z","time spent":"1.490136473s","remote":"127.0.0.1:53988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:42:26.120758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535479871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-10-04T05:42:26.120733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256756506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:42:26.120838Z","caller":"traceutil/trace.go:171","msg":"trace[232289617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"1.256900529s","start":"2026-10-04T05:42:24.863916Z","end":"2026-10-04T05:42:26.120817Z","steps":["trace[232289617] 'agreement among raft nodes before linearized reading' (duration: 1.212556645s)","trace[232289617] 'range keys from in-memory index tree' (duration: 44.081491ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:26.12084Z","caller":"traceutil/trace.go:171","msg":"trace[1114675099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"1.535583063s","start":"2026-10-04T05:42:24.585227Z","end":"2026-10-04T05:42:26.12081Z","steps":["trace[1114675099] 'agreement among raft nodes before linearized reading' (duration: 1.491184388s)","trace[1114675099] 'range keys from in-memory index tree' (duration: 44.280333ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:26.120907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:24.585205Z","time spent":"1.535689714s","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T05:42:26.120897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:24.863882Z","time spent":"1.25699931s","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T05:42:26.121022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258076255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-04T05:42:26.12325Z","caller":"traceutil/trace.go:171","msg":"trace[1622502931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"1.260358035s","start":"2026-10-04T05:42:24.862857Z","end":"2026-10-04T05:42:26.123215Z","steps":["trace[1622502931] 'agreement among raft nodes before linearized reading' (duration: 1.213587039s)","trace[1622502931] 'range keys from in-memory index tree' (duration: 44.322684ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:26.123334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:24.862837Z","time spent":"1.260478657s","remote":"127.0.0.1:53996","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-10-04T05:42:26.121096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261141657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-04T05:42:26.123792Z","caller":"traceutil/trace.go:171","msg":"trace[339773972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"1.263865894s","start":"2026-10-04T05:42:24.859903Z","end":"2026-10-04T05:42:26.123769Z","steps":["trace[339773972] 'agreement among raft nodes before linearized reading' (duration: 1.21657687s)","trace[339773972] 'range keys from in-memory index tree' (duration: 44.536617ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:42:26.124141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:24.859826Z","time spent":"1.26429855s","remote":"127.0.0.1:53996","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":"info","ts":"2026-10-04T05:42:28.245514Z","caller":"traceutil/trace.go:171","msg":"trace[1326853885] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"110.150641ms","start":"2026-10-04T05:42:28.135343Z","end":"2026-10-04T05:42:28.245493Z","steps":["trace[1326853885] 'read index received' (duration: 106.317819ms)","trace[1326853885] 'applied index is now lower than readState.Index' (duration: 3.831882ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:28.245607Z","caller":"traceutil/trace.go:171","msg":"trace[1140962341] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"119.447478ms","start":"2026-10-04T05:42:28.126151Z","end":"2026-10-04T05:42:28.245598Z","steps":["trace[1140962341] 'process raft request' (duration: 115.448954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:28.245822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.474015ms","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-10-04T05:42:28.245828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.912032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-04T05:42:28.24583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.728857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:42:28.245864Z","caller":"traceutil/trace.go:171","msg":"trace[343126402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"110.560028ms","start":"2026-10-04T05:42:28.135292Z","end":"2026-10-04T05:42:28.245852Z","steps":["trace[343126402] 'agreement among raft nodes before linearized reading' (duration: 110.367516ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:28.245886Z","caller":"traceutil/trace.go:171","msg":"trace[2137611371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"105.007822ms","start":"2026-10-04T05:42:28.140862Z","end":"2026-10-04T05:42:28.245869Z","steps":["trace[2137611371] 'agreement among raft nodes before linearized reading' (duration: 104.79271ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:28.245907Z","caller":"traceutil/trace.go:171","msg":"trace[1884092566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"109.819667ms","start":"2026-10-04T05:42:28.136059Z","end":"2026-10-04T05:42:28.245879Z","steps":["trace[1884092566] 'agreement among raft nodes before linearized reading' (duration: 109.570714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:28.246115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.791423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-04T05:42:28.246213Z","caller":"traceutil/trace.go:171","msg":"trace[1170563552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"105.971815ms","start":"2026-10-04T05:42:28.140224Z","end":"2026-10-04T05:42:28.246196Z","steps":["trace[1170563552] 'agreement among raft nodes before linearized reading' (duration: 105.489728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:42.829471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11302523259541723220,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-04T05:42:43.115842Z","caller":"traceutil/trace.go:171","msg":"trace[437195029] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"786.716874ms","start":"2026-10-04T05:42:42.329076Z","end":"2026-10-04T05:42:43.115793Z","steps":["trace[437195029] 'read index received' (duration: 786.584032ms)","trace[437195029] 'applied index is now lower than readState.Index' (duration: 131.692µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:42:43.115904Z","caller":"traceutil/trace.go:171","msg":"trace[1681965660] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"788.436307ms","start":"2026-10-04T05:42:42.327438Z","end":"2026-10-04T05:42:43.115874Z","steps":["trace[1681965660] 'process raft request' (duration: 788.199344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:43.116064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.966457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-04T05:42:43.116087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:42.327415Z","time spent":"788.566078ms","remote":"127.0.0.1:53996","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-10-04T05:42:43.116108Z","caller":"traceutil/trace.go:171","msg":"trace[514775103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"787.041508ms","start":"2026-10-04T05:42:42.329054Z","end":"2026-10-04T05:42:43.116095Z","steps":["trace[514775103] 'agreement among raft nodes before linearized reading' (duration: 786.885376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:43.116152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:42.329043Z","time spent":"787.100609ms","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T05:42:43.132964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.007445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T05:42:43.13302Z","caller":"traceutil/trace.go:171","msg":"trace[533586283] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:777; }","duration":"489.071306ms","start":"2026-10-04T05:42:42.643937Z","end":"2026-10-04T05:42:43.133008Z","steps":["trace[533586283] 'agreement among raft nodes before linearized reading' (duration: 488.857623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:43.133052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:42.643903Z","time spent":"489.140227ms","remote":"127.0.0.1:54080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-10-04T05:42:43.132939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.538941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:42:43.133236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.481903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-10-04T05:42:43.133298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.544655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-04T05:42:43.133399Z","caller":"traceutil/trace.go:171","msg":"trace[793095349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"797.657877ms","start":"2026-10-04T05:42:42.335722Z","end":"2026-10-04T05:42:43.13338Z","steps":["trace[793095349] 'agreement among raft nodes before linearized reading' (duration: 797.425714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:43.133435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:42.335713Z","time spent":"797.712477ms","remote":"127.0.0.1:53996","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-10-04T05:42:43.133314Z","caller":"traceutil/trace.go:171","msg":"trace[842292105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"790.585043ms","start":"2026-10-04T05:42:42.342701Z","end":"2026-10-04T05:42:43.133286Z","steps":["trace[842292105] 'agreement among raft nodes before linearized reading' (duration: 790.29659ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:42:43.133179Z","caller":"traceutil/trace.go:171","msg":"trace[707856561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"802.808335ms","start":"2026-10-04T05:42:42.330349Z","end":"2026-10-04T05:42:43.133157Z","steps":["trace[707856561] 'agreement among raft nodes before linearized reading' (duration: 802.4358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:42:43.133584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:42.342681Z","time spent":"790.835908ms","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T05:42:43.133683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:42:42.33034Z","time spent":"803.228741ms","remote":"127.0.0.1:46628","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-10-04T05:42:54.9885Z","caller":"traceutil/trace.go:171","msg":"trace[426001430] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"107.346358ms","start":"2026-10-04T05:42:54.881135Z","end":"2026-10-04T05:42:54.988481Z","steps":["trace[426001430] 'process raft request' (duration: 107.207046ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:43:07.497524Z","caller":"traceutil/trace.go:171","msg":"trace[146845426] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"166.881068ms","start":"2026-10-04T05:43:07.330618Z","end":"2026-10-04T05:43:07.497499Z","steps":["trace[146845426] 'read index received' (duration: 166.043777ms)","trace[146845426] 'applied index is now lower than readState.Index' (duration: 836.211µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:43:07.497659Z","caller":"traceutil/trace.go:171","msg":"trace[734832601] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"167.261384ms","start":"2026-10-04T05:43:07.330379Z","end":"2026-10-04T05:43:07.49764Z","steps":["trace[734832601] 'process raft request' (duration: 166.350282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:43:07.497752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.116501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:43:07.497808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.454211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-04T05:43:07.497811Z","caller":"traceutil/trace.go:171","msg":"trace[1070927165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"167.262183ms","start":"2026-10-04T05:43:07.33053Z","end":"2026-10-04T05:43:07.497792Z","steps":["trace[1070927165] 'agreement among raft nodes before linearized reading' (duration: 167.131371ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:43:07.49785Z","caller":"traceutil/trace.go:171","msg":"trace[930096397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"165.521651ms","start":"2026-10-04T05:43:07.332315Z","end":"2026-10-04T05:43:07.497837Z","steps":["trace[930096397] 'agreement among raft nodes before linearized reading' (duration: 165.366809ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:43:21.468788Z","caller":"traceutil/trace.go:171","msg":"trace[1173144509] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"104.279397ms","start":"2026-10-04T05:43:21.364481Z","end":"2026-10-04T05:43:21.46876Z","steps":["trace[1173144509] 'process raft request' (duration: 103.802041ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:43:33.862015Z","caller":"traceutil/trace.go:171","msg":"trace[605718846] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"198.772543ms","start":"2026-10-04T05:43:33.663216Z","end":"2026-10-04T05:43:33.861989Z","steps":["trace[605718846] 'read index received' (duration: 163.452492ms)","trace[605718846] 'applied index is now lower than readState.Index' (duration: 35.31898ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:43:33.862279Z","caller":"traceutil/trace.go:171","msg":"trace[1408738189] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"209.706615ms","start":"2026-10-04T05:43:33.652537Z","end":"2026-10-04T05:43:33.862244Z","steps":["trace[1408738189] 'process raft request' (duration: 174.120452ms)","trace[1408738189] 'compare' (duration: 35.222399ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:43:33.86231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.066846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-04T05:43:33.862392Z","caller":"traceutil/trace.go:171","msg":"trace[1511520943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1075; }","duration":"199.186417ms","start":"2026-10-04T05:43:33.66319Z","end":"2026-10-04T05:43:33.862377Z","steps":["trace[1511520943] 'agreement among raft nodes before linearized reading' (duration: 198.919004ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:43:35.993908Z","caller":"traceutil/trace.go:171","msg":"trace[1431740518] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"107.083953ms","start":"2026-10-04T05:43:35.886804Z","end":"2026-10-04T05:43:35.993888Z","steps":["trace[1431740518] 'read index received' (duration: 106.93548ms)","trace[1431740518] 'applied index is now lower than readState.Index' (duration: 147.593µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:43:35.994136Z","caller":"traceutil/trace.go:171","msg":"trace[1775804899] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"122.958725ms","start":"2026-10-04T05:43:35.871165Z","end":"2026-10-04T05:43:35.994124Z","steps":["trace[1775804899] 'process raft request' (duration: 122.625142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:43:35.994413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.628018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-04T05:43:35.994452Z","caller":"traceutil/trace.go:171","msg":"trace[1660372004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"107.681789ms","start":"2026-10-04T05:43:35.886761Z","end":"2026-10-04T05:43:35.994442Z","steps":["trace[1660372004] 'agreement among raft nodes before linearized reading' (duration: 107.522617ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:02.011867Z","caller":"traceutil/trace.go:171","msg":"trace[2121320252] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"111.866743ms","start":"2026-10-04T05:44:01.899947Z","end":"2026-10-04T05:44:02.011813Z","steps":["trace[2121320252] 'process raft request' (duration: 47.676958ms)","trace[2121320252] 'compare' (duration: 63.914922ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:46.768406Z","caller":"traceutil/trace.go:171","msg":"trace[791782818] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"476.506749ms","start":"2026-10-04T05:44:46.291876Z","end":"2026-10-04T05:44:46.768383Z","steps":["trace[791782818] 'process raft request' (duration: 461.002428ms)","trace[791782818] 'compare' (duration: 15.087569ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:46.768582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:46.29185Z","time spent":"476.642512ms","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:44:46.768827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.532123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-04T05:44:46.7689Z","caller":"traceutil/trace.go:171","msg":"trace[1654509712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"323.633186ms","start":"2026-10-04T05:44:46.445249Z","end":"2026-10-04T05:44:46.768882Z","steps":["trace[1654509712] 'agreement among raft nodes before linearized reading' (duration: 323.458941ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:46.768917Z","caller":"traceutil/trace.go:171","msg":"trace[370524768] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"323.021559ms","start":"2026-10-04T05:44:46.445293Z","end":"2026-10-04T05:44:46.768314Z","steps":["trace[370524768] 'read index received' (duration: 307.502288ms)","trace[370524768] 'applied index is now lower than readState.Index' (duration: 15.518091ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:46.768946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:46.445229Z","time spent":"323.704789ms","remote":"127.0.0.1:53996","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-10-04T05:44:46.769378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.812601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-04T05:44:46.769439Z","caller":"traceutil/trace.go:171","msg":"trace[576020089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"116.911845ms","start":"2026-10-04T05:44:46.652511Z","end":"2026-10-04T05:44:46.769423Z","steps":["trace[576020089] 'agreement among raft nodes before linearized reading' (duration: 116.7634ms)"],"step_count":1}