{"level":"warn","ts":"2026-06-16T19:58:18.612694Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T19:58:18.612902Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-06-16T19:58:18.613096Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T19:58:18.613131Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-16T19:58:18.613207Z","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-06-16T19:58:18.614237Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-06-16T19:58:18.614448Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-06-16T19:58:18.617788Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.976684ms"} {"level":"info","ts":"2026-06-16T19:58:18.623684Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-06-16T19:58:18.623838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T19:58:18.623936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-06-16T19:58:18.623979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T19:58:18.62403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-06-16T19:58:18.62414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-06-16T19:58:18.630502Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T19:58:18.632583Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T19:58:18.634338Z","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-06-16T19:58:18.636721Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T19:58:18.637187Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T19:58:18.637365Z","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-06-16T19:58:18.637422Z","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-06-16T19:58:18.637433Z","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-06-16T19:58:18.639459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-06-16T19:58:18.641779Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-16T19:58:18.641876Z","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-06-16T19:58:18.641978Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-16T19:58:18.642016Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-16T19:58:18.642104Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T19:58:18.642136Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T19:58:19.025338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T19:58:19.025404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T19:58:19.02545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-06-16T19:58:19.025474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-06-16T19:58:19.02549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-16T19:58:19.025747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-06-16T19:58:19.025792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-16T19:58:19.037151Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T19:58:19.0375Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T19:58:19.039513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T19:58:19.040374Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T19:58:19.040524Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T19:58:19.040609Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T19:58:19.040817Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T19:58:19.040857Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T19:58:19.041445Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-06-16T19:58:19.041528Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T19:58:19.043061Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-16T19:58:22.016955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.003146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-16T19:58:22.017201Z","caller":"traceutil/trace.go:171","msg":"trace[929081333] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"514.37897ms","start":"2026-06-16T19:58:21.502798Z","end":"2026-06-16T19:58:22.017177Z","steps":["trace[929081333] 'read index received' (duration: 95.431401ms)","trace[929081333] 'applied index is now lower than readState.Index' (duration: 418.946229ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T19:58:22.017248Z","caller":"traceutil/trace.go:171","msg":"trace[1575335942] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"575.364285ms","start":"2026-06-16T19:58:21.441836Z","end":"2026-06-16T19:58:22.0172Z","steps":["trace[1575335942] 'process raft request' (duration: 156.448147ms)","trace[1575335942] 'compare' (duration: 417.809912ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T19:58:22.017449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:58:21.441824Z","time spent":"575.524489ms","remote":"127.0.0.1:49728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T19:58:22.017702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.776299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-16T19:58:22.017825Z","caller":"traceutil/trace.go:171","msg":"trace[1190203781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"515.069056ms","start":"2026-06-16T19:58:21.502734Z","end":"2026-06-16T19:58:22.017803Z","steps":["trace[1190203781] 'agreement among raft nodes before linearized reading' (duration: 514.552023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:58:22.017973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:58:21.502661Z","time spent":"515.296412ms","remote":"127.0.0.1:49408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T19:58:22.362424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.378798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-16T19:58:22.362532Z","caller":"traceutil/trace.go:171","msg":"trace[1002381290] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"339.703144ms","start":"2026-06-16T19:58:22.022811Z","end":"2026-06-16T19:58:22.362514Z","steps":["trace[1002381290] 'process raft request' (duration: 176.153072ms)","trace[1002381290] 'compare' (duration: 163.216754ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T19:58:22.362597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:58:22.022792Z","time spent":"339.779935ms","remote":"127.0.0.1:49728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T19:58:22.365874Z","caller":"traceutil/trace.go:171","msg":"trace[1790861889] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"119.623844ms","start":"2026-06-16T19:58:22.246224Z","end":"2026-06-16T19:58:22.365848Z","steps":["trace[1790861889] 'process raft request' (duration: 119.511511ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.095331Z","caller":"traceutil/trace.go:171","msg":"trace[1959571182] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"179.473083ms","start":"2026-06-16T19:58:54.915833Z","end":"2026-06-16T19:58:55.095307Z","steps":["trace[1959571182] 'process raft request' (duration: 179.36168ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.10511Z","caller":"traceutil/trace.go:171","msg":"trace[2090055060] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"189.057318ms","start":"2026-06-16T19:58:54.91602Z","end":"2026-06-16T19:58:55.105077Z","steps":["trace[2090055060] 'process raft request' (duration: 188.957186ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.105214Z","caller":"traceutil/trace.go:171","msg":"trace[182943482] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"189.003606ms","start":"2026-06-16T19:58:54.916157Z","end":"2026-06-16T19:58:55.10516Z","steps":["trace[182943482] 'process raft request' (duration: 188.875663ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.105309Z","caller":"traceutil/trace.go:171","msg":"trace[302658281] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"177.839763ms","start":"2026-06-16T19:58:54.927395Z","end":"2026-06-16T19:58:55.105234Z","steps":["trace[302658281] 'process raft request' (duration: 177.684409ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.105277Z","caller":"traceutil/trace.go:171","msg":"trace[722116140] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"177.858173ms","start":"2026-06-16T19:58:54.927346Z","end":"2026-06-16T19:58:55.105204Z","steps":["trace[722116140] 'process raft request' (duration: 177.70808ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.105425Z","caller":"traceutil/trace.go:171","msg":"trace[1819356936] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"168.192047ms","start":"2026-06-16T19:58:54.937227Z","end":"2026-06-16T19:58:55.105419Z","steps":["trace[1819356936] 'process raft request' (duration: 167.940831ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.105364Z","caller":"traceutil/trace.go:171","msg":"trace[1592308661] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"177.762521ms","start":"2026-06-16T19:58:54.927531Z","end":"2026-06-16T19:58:55.105293Z","steps":["trace[1592308661] 'process raft request' (duration: 177.586826ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.10695Z","caller":"traceutil/trace.go:171","msg":"trace[13176124] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"165.903381ms","start":"2026-06-16T19:58:54.939647Z","end":"2026-06-16T19:58:55.10555Z","steps":["trace[13176124] 'process raft request' (duration: 165.701586ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.107957Z","caller":"traceutil/trace.go:171","msg":"trace[483715209] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"163.717996ms","start":"2026-06-16T19:58:54.944088Z","end":"2026-06-16T19:58:55.107806Z","steps":["trace[483715209] 'process raft request' (duration: 161.41382ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.109608Z","caller":"traceutil/trace.go:171","msg":"trace[1822295044] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"125.52877ms","start":"2026-06-16T19:58:54.983587Z","end":"2026-06-16T19:58:55.109116Z","steps":["trace[1822295044] 'process raft request' (duration: 124.198377ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.110322Z","caller":"traceutil/trace.go:171","msg":"trace[1618954875] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"151.759363ms","start":"2026-06-16T19:58:54.958429Z","end":"2026-06-16T19:58:55.110188Z","steps":["trace[1618954875] 'process raft request' (duration: 148.098713ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:58:55.611417Z","caller":"traceutil/trace.go:171","msg":"trace[589475505] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"133.400224ms","start":"2026-06-16T19:58:55.477987Z","end":"2026-06-16T19:58:55.611387Z","steps":["trace[589475505] 'process raft request' (duration: 88.00883ms)","trace[589475505] 'compare' (duration: 45.138967ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T19:58:55.612215Z","caller":"traceutil/trace.go:171","msg":"trace[23650412] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"131.370613ms","start":"2026-06-16T19:58:55.480838Z","end":"2026-06-16T19:58:55.612209Z","steps":["trace[23650412] 'process raft request' (duration: 130.509953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:01.845387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021138994464041,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T19:59:02.196394Z","caller":"traceutil/trace.go:171","msg":"trace[472386934] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"898.998733ms","start":"2026-06-16T19:59:01.297369Z","end":"2026-06-16T19:59:02.196368Z","steps":["trace[472386934] 'process raft request' (duration: 898.84886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:02.19658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:01.297338Z","time spent":"899.128936ms","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T19:59:02.35758Z","caller":"traceutil/trace.go:171","msg":"trace[1266838297] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"1.012489211s","start":"2026-06-16T19:59:01.345064Z","end":"2026-06-16T19:59:02.357553Z","steps":["trace[1266838297] 'read index received' (duration: 851.15898ms)","trace[1266838297] 'applied index is now lower than readState.Index' (duration: 161.329341ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T19:59:02.35776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.116915ms","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-06-16T19:59:02.357803Z","caller":"traceutil/trace.go:171","msg":"trace[1133453707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"697.251349ms","start":"2026-06-16T19:59:01.660539Z","end":"2026-06-16T19:59:02.35779Z","steps":["trace[1133453707] 'agreement among raft nodes before linearized reading' (duration: 697.158826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:02.357838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:01.66049Z","time spent":"697.33844ms","remote":"127.0.0.1:49648","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":"warn","ts":"2026-06-16T19:59:02.357847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012768418s","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-06-16T19:59:02.357918Z","caller":"traceutil/trace.go:171","msg":"trace[1047599469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"1.01285289s","start":"2026-06-16T19:59:01.345045Z","end":"2026-06-16T19:59:02.357898Z","steps":["trace[1047599469] 'agreement among raft nodes before linearized reading' (duration: 1.012593664s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:02.35796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:01.344965Z","time spent":"1.012986763s","remote":"127.0.0.1:49648","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-06-16T19:59:02.357947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.006007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T19:59:02.358032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.61986ms","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-06-16T19:59:02.35803Z","caller":"traceutil/trace.go:171","msg":"trace[890541132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"545.146341ms","start":"2026-06-16T19:59:01.812859Z","end":"2026-06-16T19:59:02.358006Z","steps":["trace[890541132] 'agreement among raft nodes before linearized reading' (duration: 545.010037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:02.358044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.860797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T19:59:02.358064Z","caller":"traceutil/trace.go:171","msg":"trace[324252571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"635.703363ms","start":"2026-06-16T19:59:01.722351Z","end":"2026-06-16T19:59:02.358054Z","steps":["trace[324252571] 'agreement among raft nodes before linearized reading' (duration: 635.59664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:02.358071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:01.812848Z","time spent":"545.216512ms","remote":"127.0.0.1:49392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T19:59:02.357966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.395888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-16T19:59:02.358097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:01.722324Z","time spent":"635.766564ms","remote":"127.0.0.1:49648","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":"info","ts":"2026-06-16T19:59:02.358093Z","caller":"traceutil/trace.go:171","msg":"trace[1953580491] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:590; }","duration":"150.934249ms","start":"2026-06-16T19:59:02.207139Z","end":"2026-06-16T19:59:02.358074Z","steps":["trace[1953580491] 'agreement among raft nodes before linearized reading' (duration: 150.879918ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:59:02.358154Z","caller":"traceutil/trace.go:171","msg":"trace[970705196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"313.598304ms","start":"2026-06-16T19:59:02.044526Z","end":"2026-06-16T19:59:02.358125Z","steps":["trace[970705196] 'agreement among raft nodes before linearized reading' (duration: 313.257605ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:59:02.358226Z","caller":"traceutil/trace.go:171","msg":"trace[1398470171] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"907.551751ms","start":"2026-06-16T19:59:01.450645Z","end":"2026-06-16T19:59:02.358197Z","steps":["trace[1398470171] 'process raft request' (duration: 868.739159ms)","trace[1398470171] 'compare' (duration: 38.048904ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T19:59:02.358248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:02.044514Z","time spent":"313.705196ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T19:59:02.358498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:01.450628Z","time spent":"907.635223ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T19:59:03.292108Z","caller":"traceutil/trace.go:171","msg":"trace[1822238918] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"524.546721ms","start":"2026-06-16T19:59:02.767526Z","end":"2026-06-16T19:59:03.292073Z","steps":["trace[1822238918] 'process raft request' (duration: 517.651173ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:59:03.292095Z","caller":"traceutil/trace.go:171","msg":"trace[1235568177] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"476.890593ms","start":"2026-06-16T19:59:02.815085Z","end":"2026-06-16T19:59:03.291975Z","steps":["trace[1235568177] 'read index received' (duration: 469.81773ms)","trace[1235568177] 'applied index is now lower than readState.Index' (duration: 7.071153ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T19:59:03.292261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.17656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T19:59:03.292309Z","caller":"traceutil/trace.go:171","msg":"trace[1180573163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"477.293343ms","start":"2026-06-16T19:59:02.815002Z","end":"2026-06-16T19:59:03.292296Z","steps":["trace[1180573163] 'agreement among raft nodes before linearized reading' (duration: 477.197951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:03.29234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:02.814967Z","time spent":"477.367765ms","remote":"127.0.0.1:49392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T19:59:03.292304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:02.767495Z","time spent":"524.688755ms","remote":"127.0.0.1:49480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T19:59:03.292386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.874637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-16T19:59:03.292425Z","caller":"traceutil/trace.go:171","msg":"trace[1383604656] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:595; }","duration":"187.9982ms","start":"2026-06-16T19:59:03.104416Z","end":"2026-06-16T19:59:03.292414Z","steps":["trace[1383604656] 'agreement among raft nodes before linearized reading' (duration: 187.880827ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:59:18.564894Z","caller":"traceutil/trace.go:171","msg":"trace[373496869] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"119.919555ms","start":"2026-06-16T19:59:18.444943Z","end":"2026-06-16T19:59:18.564862Z","steps":["trace[373496869] 'read index received' (duration: 119.559876ms)","trace[373496869] 'applied index is now lower than readState.Index' (duration: 358.519µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T19:59:18.565017Z","caller":"traceutil/trace.go:171","msg":"trace[338653898] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"526.179821ms","start":"2026-06-16T19:59:18.0388Z","end":"2026-06-16T19:59:18.56498Z","steps":["trace[338653898] 'process raft request' (duration: 525.864513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:18.565211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T19:59:18.038785Z","time spent":"526.335764ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T19:59:18.565227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.257803ms","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-06-16T19:59:18.565274Z","caller":"traceutil/trace.go:171","msg":"trace[1293613994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"120.386536ms","start":"2026-06-16T19:59:18.444875Z","end":"2026-06-16T19:59:18.565262Z","steps":["trace[1293613994] 'agreement among raft nodes before linearized reading' (duration: 120.173521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:18.565558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.58824ms","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-06-16T19:59:18.565665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.388375ms","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-06-16T19:59:18.565789Z","caller":"traceutil/trace.go:171","msg":"trace[1037938673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"108.850186ms","start":"2026-06-16T19:59:18.456913Z","end":"2026-06-16T19:59:18.565763Z","steps":["trace[1037938673] 'agreement among raft nodes before linearized reading' (duration: 108.462966ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T19:59:18.565823Z","caller":"traceutil/trace.go:171","msg":"trace[1908210118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"108.587819ms","start":"2026-06-16T19:59:18.457215Z","end":"2026-06-16T19:59:18.565803Z","steps":["trace[1908210118] 'agreement among raft nodes before linearized reading' (duration: 108.135509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T19:59:18.566052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.757978ms","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-06-16T19:59:18.566121Z","caller":"traceutil/trace.go:171","msg":"trace[206787059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"109.8689ms","start":"2026-06-16T19:59:18.456237Z","end":"2026-06-16T19:59:18.566106Z","steps":["trace[206787059] 'agreement among raft nodes before linearized reading' (duration: 109.006919ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:23.764955Z","caller":"traceutil/trace.go:171","msg":"trace[1917694726] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"273.537047ms","start":"2026-06-16T20:00:23.491392Z","end":"2026-06-16T20:00:23.764929Z","steps":["trace[1917694726] 'read index received' (duration: 273.394333ms)","trace[1917694726] 'applied index is now lower than readState.Index' (duration: 141.664µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T20:00:23.765084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.66797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T20:00:23.765124Z","caller":"traceutil/trace.go:171","msg":"trace[1577151474] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1074; }","duration":"273.772622ms","start":"2026-06-16T20:00:23.49134Z","end":"2026-06-16T20:00:23.765113Z","steps":["trace[1577151474] 'agreement among raft nodes before linearized reading' (duration: 273.6903ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:23.765251Z","caller":"traceutil/trace.go:171","msg":"trace[454171804] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"424.099759ms","start":"2026-06-16T20:00:23.341122Z","end":"2026-06-16T20:00:23.765222Z","steps":["trace[454171804] 'process raft request' (duration: 423.667139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:00:23.765398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:00:23.341104Z","time spent":"424.214232ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T20:00:39.162894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.741853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-16T20:00:39.162953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.246245ms","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-06-16T20:00:39.162961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.816649ms","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-06-16T20:00:39.162986Z","caller":"traceutil/trace.go:171","msg":"trace[675464079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1122; }","duration":"121.902697ms","start":"2026-06-16T20:00:39.041063Z","end":"2026-06-16T20:00:39.162966Z","steps":["trace[675464079] 'range keys from in-memory index tree' (duration: 121.527107ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:39.163027Z","caller":"traceutil/trace.go:171","msg":"trace[2094830270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1122; }","duration":"165.377498ms","start":"2026-06-16T20:00:38.997632Z","end":"2026-06-16T20:00:39.16301Z","steps":["trace[2094830270] 'range keys from in-memory index tree' (duration: 164.981138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:00:39.163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.829934ms","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-06-16T20:00:39.163058Z","caller":"traceutil/trace.go:171","msg":"trace[1000910930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1122; }","duration":"124.941092ms","start":"2026-06-16T20:00:39.038091Z","end":"2026-06-16T20:00:39.163032Z","steps":["trace[1000910930] 'range keys from in-memory index tree' (duration: 124.668994ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:39.163112Z","caller":"traceutil/trace.go:171","msg":"trace[1718165989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1122; }","duration":"126.000237ms","start":"2026-06-16T20:00:39.037087Z","end":"2026-06-16T20:00:39.163088Z","steps":["trace[1718165989] 'range keys from in-memory index tree' (duration: 125.615657ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:40.289048Z","caller":"traceutil/trace.go:171","msg":"trace[666448414] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"447.495247ms","start":"2026-06-16T20:00:39.841528Z","end":"2026-06-16T20:00:40.289023Z","steps":["trace[666448414] 'process raft request' (duration: 447.315422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:00:40.2892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:00:39.841498Z","time spent":"447.62034ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T20:00:41.495074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.683384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T20:00:41.495157Z","caller":"traceutil/trace.go:171","msg":"trace[1210448382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1128; }","duration":"248.822577ms","start":"2026-06-16T20:00:41.246316Z","end":"2026-06-16T20:00:41.495138Z","steps":["trace[1210448382] 'range keys from in-memory index tree' (duration: 248.423278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:00:41.495205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.812497ms","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-06-16T20:00:41.495298Z","caller":"traceutil/trace.go:171","msg":"trace[632651765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1128; }","duration":"248.974241ms","start":"2026-06-16T20:00:41.246299Z","end":"2026-06-16T20:00:41.495273Z","steps":["trace[632651765] 'range keys from in-memory index tree' (duration: 248.5144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:00:41.49538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.266395ms","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-06-16T20:00:41.495393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.740606ms","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-06-16T20:00:41.495465Z","caller":"traceutil/trace.go:171","msg":"trace[1263228167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1128; }","duration":"287.372617ms","start":"2026-06-16T20:00:41.208046Z","end":"2026-06-16T20:00:41.495419Z","steps":["trace[1263228167] 'range keys from in-memory index tree' (duration: 287.044569ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:41.49552Z","caller":"traceutil/trace.go:171","msg":"trace[1173695277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1128; }","duration":"248.874909ms","start":"2026-06-16T20:00:41.246621Z","end":"2026-06-16T20:00:41.495496Z","steps":["trace[1173695277] 'range keys from in-memory index tree' (duration: 248.473549ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:41.764149Z","caller":"traceutil/trace.go:171","msg":"trace[1846033027] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"263.15409ms","start":"2026-06-16T20:00:41.500966Z","end":"2026-06-16T20:00:41.76412Z","steps":["trace[1846033027] 'process raft request' (duration: 262.938255ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:41.764393Z","caller":"traceutil/trace.go:171","msg":"trace[1649258271] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1176; }","duration":"103.075952ms","start":"2026-06-16T20:00:41.661305Z","end":"2026-06-16T20:00:41.764381Z","steps":["trace[1649258271] 'read index received' (duration: 102.525099ms)","trace[1649258271] 'applied index is now lower than readState.Index' (duration: 549.943µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T20:00:41.764385Z","caller":"traceutil/trace.go:171","msg":"trace[282318990] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"263.199892ms","start":"2026-06-16T20:00:41.501071Z","end":"2026-06-16T20:00:41.764271Z","steps":["trace[282318990] 'process raft request' (duration: 262.957636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:00:41.764536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.187095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T20:00:41.764626Z","caller":"traceutil/trace.go:171","msg":"trace[137165177] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1132; }","duration":"103.39103ms","start":"2026-06-16T20:00:41.661223Z","end":"2026-06-16T20:00:41.764614Z","steps":["trace[137165177] 'agreement among raft nodes before linearized reading' (duration: 103.209106ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:41.764608Z","caller":"traceutil/trace.go:171","msg":"trace[2062979630] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"263.348235ms","start":"2026-06-16T20:00:41.501189Z","end":"2026-06-16T20:00:41.764537Z","steps":["trace[2062979630] 'process raft request' (duration: 262.880304ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:43.179455Z","caller":"traceutil/trace.go:171","msg":"trace[1892888108] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"158.428327ms","start":"2026-06-16T20:00:43.020977Z","end":"2026-06-16T20:00:43.179405Z","steps":["trace[1892888108] 'process raft request' (duration: 158.159811ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:55.59582Z","caller":"traceutil/trace.go:171","msg":"trace[1702982670] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"138.539998ms","start":"2026-06-16T20:00:55.457253Z","end":"2026-06-16T20:00:55.595792Z","steps":["trace[1702982670] 'process raft request' (duration: 138.378493ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:00:59.600968Z","caller":"traceutil/trace.go:171","msg":"trace[507469344] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"363.097745ms","start":"2026-06-16T20:00:59.237828Z","end":"2026-06-16T20:00:59.600926Z","steps":["trace[507469344] 'read index received' (duration: 314.731212ms)","trace[507469344] 'applied index is now lower than readState.Index' (duration: 48.364933ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T20:00:59.601185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.34036ms","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-06-16T20:00:59.601223Z","caller":"traceutil/trace.go:171","msg":"trace[1029815530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"363.434313ms","start":"2026-06-16T20:00:59.237779Z","end":"2026-06-16T20:00:59.601213Z","steps":["trace[1029815530] 'agreement among raft nodes before linearized reading' (duration: 363.259028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:00:59.601263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:00:59.237761Z","time spent":"363.494275ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T20:01:00.024795Z","caller":"traceutil/trace.go:171","msg":"trace[496030925] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"357.004955ms","start":"2026-06-16T20:00:59.667774Z","end":"2026-06-16T20:01:00.024779Z","steps":["trace[496030925] 'read index received' (duration: 356.893831ms)","trace[496030925] 'applied index is now lower than readState.Index' (duration: 110.374µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T20:01:00.024891Z","caller":"traceutil/trace.go:171","msg":"trace[1682657774] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"420.228705ms","start":"2026-06-16T20:00:59.604653Z","end":"2026-06-16T20:01:00.024882Z","steps":["trace[1682657774] 'process raft request' (duration: 420.005688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:00.024971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:00:59.604627Z","time spent":"420.286146ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T20:01:00.025076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.160093ms","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-06-16T20:01:00.025153Z","caller":"traceutil/trace.go:171","msg":"trace[1013388103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"184.287635ms","start":"2026-06-16T20:00:59.840848Z","end":"2026-06-16T20:01:00.025136Z","steps":["trace[1013388103] 'agreement among raft nodes before linearized reading' (duration: 184.08076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:00.025224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.712912ms","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-06-16T20:01:00.02531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.533109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-16T20:01:00.025345Z","caller":"traceutil/trace.go:171","msg":"trace[1169402442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"357.60498ms","start":"2026-06-16T20:00:59.667731Z","end":"2026-06-16T20:01:00.025336Z","steps":["trace[1169402442] 'agreement among raft nodes before linearized reading' (duration: 357.499127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:00.02537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:00:59.667712Z","time spent":"357.653661ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T20:01:00.025319Z","caller":"traceutil/trace.go:171","msg":"trace[1714475578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"179.909488ms","start":"2026-06-16T20:00:59.845392Z","end":"2026-06-16T20:01:00.025302Z","steps":["trace[1714475578] 'agreement among raft nodes before linearized reading' (duration: 179.57284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:00.025418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.84612ms","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-06-16T20:01:00.025565Z","caller":"traceutil/trace.go:171","msg":"trace[1766581793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"183.034485ms","start":"2026-06-16T20:00:59.842523Z","end":"2026-06-16T20:01:00.025557Z","steps":["trace[1766581793] 'agreement among raft nodes before linearized reading' (duration: 182.82949ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:01:15.65546Z","caller":"traceutil/trace.go:171","msg":"trace[2082283364] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"165.031236ms","start":"2026-06-16T20:01:15.490374Z","end":"2026-06-16T20:01:15.655405Z","steps":["trace[2082283364] 'process raft request' (duration: 164.838081ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:01:15.655696Z","caller":"traceutil/trace.go:171","msg":"trace[1831443004] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"159.302768ms","start":"2026-06-16T20:01:15.496354Z","end":"2026-06-16T20:01:15.655656Z","steps":["trace[1831443004] 'process raft request' (duration: 158.995631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:18.704601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.067574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T20:01:18.704855Z","caller":"traceutil/trace.go:171","msg":"trace[904938104] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"536.492858ms","start":"2026-06-16T20:01:18.168299Z","end":"2026-06-16T20:01:18.704791Z","steps":["trace[904938104] 'process raft request' (duration: 266.127987ms)","trace[904938104] 'compare' (duration: 269.550991ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T20:01:18.704979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.168254Z","time spent":"536.677542ms","remote":"127.0.0.1:49460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T20:01:18.705485Z","caller":"traceutil/trace.go:171","msg":"trace[192623957] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"535.957795ms","start":"2026-06-16T20:01:18.169514Z","end":"2026-06-16T20:01:18.705472Z","steps":["trace[192623957] 'read index received' (duration: 265.00031ms)","trace[192623957] 'applied index is now lower than readState.Index' (duration: 270.953435ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T20:01:19.085392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.854921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-16T20:01:19.085414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.637194ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-16T20:01:19.085577Z","caller":"traceutil/trace.go:171","msg":"trace[22076516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"916.057576ms","start":"2026-06-16T20:01:18.16943Z","end":"2026-06-16T20:01:19.085487Z","steps":["trace[22076516] 'agreement among raft nodes before linearized reading' (duration: 536.123509ms)","trace[22076516] 'range keys from in-memory index tree' (duration: 379.645419ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T20:01:19.085736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.169412Z","time spent":"916.261651ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T20:01:19.085991Z","caller":"traceutil/trace.go:171","msg":"trace[1428241355] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1377; }","duration":"380.45198ms","start":"2026-06-16T20:01:18.705519Z","end":"2026-06-16T20:01:19.085971Z","steps":["trace[1428241355] 'read index received' (duration: 27.632378ms)","trace[1428241355] 'applied index is now lower than readState.Index' (duration: 352.818362ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T20:01:19.086069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.010986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T20:01:19.086076Z","caller":"traceutil/trace.go:171","msg":"trace[401864571] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"626.849481ms","start":"2026-06-16T20:01:18.459201Z","end":"2026-06-16T20:01:19.08605Z","steps":["trace[401864571] 'process raft request' (duration: 626.535933ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:01:19.086135Z","caller":"traceutil/trace.go:171","msg":"trace[880871266] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1323; }","duration":"909.088258ms","start":"2026-06-16T20:01:18.177029Z","end":"2026-06-16T20:01:19.086117Z","steps":["trace[880871266] 'agreement among raft nodes before linearized reading' (duration: 908.994276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:19.086178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.177022Z","time spent":"909.145269ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-16T20:01:19.086227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.898431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-16T20:01:19.086238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.459178Z","time spent":"626.987044ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T20:01:19.086286Z","caller":"traceutil/trace.go:171","msg":"trace[1185097200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1323; }","duration":"910.989793ms","start":"2026-06-16T20:01:18.175281Z","end":"2026-06-16T20:01:19.086271Z","steps":["trace[1185097200] 'agreement among raft nodes before linearized reading' (duration: 910.843709ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:01:19.086279Z","caller":"traceutil/trace.go:171","msg":"trace[656892310] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"588.862923ms","start":"2026-06-16T20:01:18.497401Z","end":"2026-06-16T20:01:19.086264Z","steps":["trace[656892310] 'process raft request' (duration: 588.503194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:19.086397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.035221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T20:01:19.086441Z","caller":"traceutil/trace.go:171","msg":"trace[252541486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1323; }","duration":"272.117583ms","start":"2026-06-16T20:01:18.81431Z","end":"2026-06-16T20:01:19.086428Z","steps":["trace[252541486] 'agreement among raft nodes before linearized reading' (duration: 272.049351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:19.086503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.331391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-16T20:01:19.086526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.497374Z","time spent":"588.998306ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T20:01:19.086335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.175262Z","time spent":"911.062015ms","remote":"127.0.0.1:49648","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":"info","ts":"2026-06-16T20:01:19.086556Z","caller":"traceutil/trace.go:171","msg":"trace[1333422451] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1323; }","duration":"911.409603ms","start":"2026-06-16T20:01:18.175134Z","end":"2026-06-16T20:01:19.086544Z","steps":["trace[1333422451] 'agreement among raft nodes before linearized reading' (duration: 911.241939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:19.086713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.359672ms","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-06-16T20:01:19.086764Z","caller":"traceutil/trace.go:171","msg":"trace[1367540955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1323; }","duration":"911.422043ms","start":"2026-06-16T20:01:18.17533Z","end":"2026-06-16T20:01:19.086752Z","steps":["trace[1367540955] 'agreement among raft nodes before linearized reading' (duration: 911.30001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:19.086728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.175121Z","time spent":"911.594818ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T20:01:19.086815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.175325Z","time spent":"911.478555ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T20:01:19.08675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.340511ms","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-06-16T20:01:19.08697Z","caller":"traceutil/trace.go:171","msg":"trace[1869482942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1323; }","duration":"911.651219ms","start":"2026-06-16T20:01:18.175295Z","end":"2026-06-16T20:01:19.086946Z","steps":["trace[1869482942] 'agreement among raft nodes before linearized reading' (duration: 910.757018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:19.088174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:01:18.17528Z","time spent":"912.861258ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T20:01:28.087143Z","caller":"traceutil/trace.go:171","msg":"trace[1023378691] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"276.004587ms","start":"2026-06-16T20:01:27.811101Z","end":"2026-06-16T20:01:28.087105Z","steps":["trace[1023378691] 'read index received' (duration: 275.628908ms)","trace[1023378691] 'applied index is now lower than readState.Index' (duration: 374.379µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T20:01:28.087199Z","caller":"traceutil/trace.go:171","msg":"trace[544182369] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"281.209313ms","start":"2026-06-16T20:01:27.805952Z","end":"2026-06-16T20:01:28.087161Z","steps":["trace[544182369] 'process raft request' (duration: 280.914156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:28.087317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.180371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T20:01:28.087359Z","caller":"traceutil/trace.go:171","msg":"trace[1234841514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1394; }","duration":"276.271163ms","start":"2026-06-16T20:01:27.811076Z","end":"2026-06-16T20:01:28.087347Z","steps":["trace[1234841514] 'agreement among raft nodes before linearized reading' (duration: 276.167861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:01:28.743422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.117126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-16T20:01:28.743532Z","caller":"traceutil/trace.go:171","msg":"trace[1980375784] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1394; }","duration":"179.28403ms","start":"2026-06-16T20:01:28.56423Z","end":"2026-06-16T20:01:28.743514Z","steps":["trace[1980375784] 'range keys from in-memory index tree' (duration: 178.719366ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:01:28.748295Z","caller":"traceutil/trace.go:171","msg":"trace[1160220814] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"121.291081ms","start":"2026-06-16T20:01:28.626718Z","end":"2026-06-16T20:01:28.748009Z","steps":["trace[1160220814] 'process raft request' (duration: 121.120067ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:02:05.064941Z","caller":"traceutil/trace.go:171","msg":"trace[1224261803] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"142.150764ms","start":"2026-06-16T20:02:04.922758Z","end":"2026-06-16T20:02:05.064908Z","steps":["trace[1224261803] 'process raft request' (duration: 142.00254ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:02:11.71844Z","caller":"traceutil/trace.go:171","msg":"trace[1424296246] linearizableReadLoop","detail":"{readStateIndex:1661; appliedIndex:1658; }","duration":"316.961252ms","start":"2026-06-16T20:02:11.401449Z","end":"2026-06-16T20:02:11.71841Z","steps":["trace[1424296246] 'read index received' (duration: 39.762453ms)","trace[1424296246] 'applied index is now lower than readState.Index' (duration: 277.197219ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T20:02:11.718502Z","caller":"traceutil/trace.go:171","msg":"trace[963671687] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"364.394687ms","start":"2026-06-16T20:02:11.354075Z","end":"2026-06-16T20:02:11.71847Z","steps":["trace[963671687] 'process raft request' (duration: 363.440193ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T20:02:11.718597Z","caller":"traceutil/trace.go:171","msg":"trace[294725544] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"364.459898ms","start":"2026-06-16T20:02:11.354097Z","end":"2026-06-16T20:02:11.718557Z","steps":["trace[294725544] 'process raft request' (duration: 364.243223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:02:11.718669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:02:11.354039Z","time spent":"364.529269ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T20:02:11.718689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.225518ms","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-06-16T20:02:11.71873Z","caller":"traceutil/trace.go:171","msg":"trace[1827573543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1594; }","duration":"317.382281ms","start":"2026-06-16T20:02:11.401336Z","end":"2026-06-16T20:02:11.718719Z","steps":["trace[1827573543] 'agreement among raft nodes before linearized reading' (duration: 317.227318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:02:11.718741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:02:11.354057Z","time spent":"364.606381ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T20:02:11.718762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:02:11.401306Z","time spent":"317.447703ms","remote":"127.0.0.1:49648","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":"warn","ts":"2026-06-16T20:02:11.71883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.723919ms","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-06-16T20:02:11.71889Z","caller":"traceutil/trace.go:171","msg":"trace[2125815068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1594; }","duration":"314.815001ms","start":"2026-06-16T20:02:11.404058Z","end":"2026-06-16T20:02:11.718873Z","steps":["trace[2125815068] 'agreement among raft nodes before linearized reading' (duration: 314.600866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:02:11.718962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:02:11.404045Z","time spent":"314.907413ms","remote":"127.0.0.1:49648","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-06-16T20:02:11.718968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.683118ms","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-06-16T20:02:11.719005Z","caller":"traceutil/trace.go:171","msg":"trace[411200034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1594; }","duration":"314.728999ms","start":"2026-06-16T20:02:11.404266Z","end":"2026-06-16T20:02:11.718995Z","steps":["trace[411200034] 'agreement among raft nodes before linearized reading' (duration: 314.581405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T20:02:11.719035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T20:02:11.40423Z","time spent":"314.798531ms","remote":"127.0.0.1:49648","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\" "}