{"level":"warn","ts":"2026-05-27T19:37:49.181372Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:37:49.181453Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.105: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.19.213.105:2380","--initial-cluster=instance=https://199.19.213.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.105:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T19:37:49.181531Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:37:49.181549Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-05-27T19:37:49.181566Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T19:37:49.182313Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"]} {"level":"info","ts":"2026-05-27T19:37:49.18239Z","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.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.105:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T19:37:49.186996Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.367627ms"} {"level":"info","ts":"2026-05-27T19:37:49.197508Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"eb6db91b2c71522f","cluster-id":"f43476f3367c14fb"} {"level":"info","ts":"2026-05-27T19:37:49.197601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:37:49.197622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 0"} {"level":"info","ts":"2026-05-27T19:37:49.197632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft eb6db91b2c71522f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:37:49.197638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 1"} {"level":"info","ts":"2026-05-27T19:37:49.197668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"warn","ts":"2026-05-27T19:37:49.215823Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:37:49.246798Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:37:49.272955Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T19:37:49.290077Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"eb6db91b2c71522f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:37:49.290405Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"eb6db91b2c71522f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:37:49.290494Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:37:49.290527Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:37:49.290534Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:37:49.297806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"info","ts":"2026-05-27T19:37:49.298153Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","added-peer-id":"eb6db91b2c71522f","added-peer-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-05-27T19:37:49.29849Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T19:37:49.298578Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-05-27T19:37:49.298775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-05-27T19:37:49.299556Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"eb6db91b2c71522f","initial-advertise-peer-urls":["https://199.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:37:49.29958Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:37:49.798211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:37:49.7983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:37:49.798363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgPreVoteResp from eb6db91b2c71522f at term 1"} {"level":"info","ts":"2026-05-27T19:37:49.798389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:37:49.798401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgVoteResp from eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-05-27T19:37:49.798416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became leader at term 2"} {"level":"info","ts":"2026-05-27T19:37:49.798433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: eb6db91b2c71522f elected leader eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-05-27T19:37:49.931087Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:37:49.931137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:37:49.931516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:37:49.934338Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:37:49.935041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.105:2379"} {"level":"info","ts":"2026-05-27T19:37:49.931086Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"eb6db91b2c71522f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.105:2379]}","request-path":"/0/members/eb6db91b2c71522f/attributes","cluster-id":"f43476f3367c14fb","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:37:49.94184Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:37:49.941899Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:37:49.984523Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:37:49.98466Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:37:49.9847Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:37:52.803961Z","caller":"traceutil/trace.go:171","msg":"trace[496912888] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"107.584937ms","start":"2026-05-27T19:37:52.696348Z","end":"2026-05-27T19:37:52.803933Z","steps":["trace[496912888] 'process raft request' (duration: 95.558488ms)","trace[496912888] 'compare' (duration: 11.910506ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:54.591521Z","caller":"traceutil/trace.go:171","msg":"trace[1589322243] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"139.858073ms","start":"2026-05-27T19:37:54.451638Z","end":"2026-05-27T19:37:54.591496Z","steps":["trace[1589322243] 'process raft request' (duration: 139.667338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:54.95672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.788875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:54.956862Z","caller":"traceutil/trace.go:171","msg":"trace[1303783414] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"315.989699ms","start":"2026-05-27T19:37:54.64085Z","end":"2026-05-27T19:37:54.95684Z","steps":["trace[1303783414] 'process raft request' (duration: 172.522556ms)","trace[1303783414] 'compare' (duration: 142.609289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:54.956934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:54.640826Z","time spent":"316.074631ms","remote":"127.0.0.1:60410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11444,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:37:55.377443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.359203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:55.377703Z","caller":"traceutil/trace.go:171","msg":"trace[1599913788] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"239.116564ms","start":"2026-05-27T19:37:55.138575Z","end":"2026-05-27T19:37:55.377691Z","steps":["trace[1599913788] 'process raft request' (duration: 36.294608ms)","trace[1599913788] 'compare' (duration: 202.232419ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:55.377842Z","caller":"traceutil/trace.go:171","msg":"trace[2071772768] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:261; }","duration":"232.826499ms","start":"2026-05-27T19:37:55.144692Z","end":"2026-05-27T19:37:55.377519Z","steps":["trace[2071772768] 'read index received' (duration: 727.62µs)","trace[2071772768] 'applied index is now lower than readState.Index' (duration: 232.097809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:55.377861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.139607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:55.377894Z","caller":"traceutil/trace.go:171","msg":"trace[4518409] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:258; }","duration":"233.22804ms","start":"2026-05-27T19:37:55.144656Z","end":"2026-05-27T19:37:55.377884Z","steps":["trace[4518409] 'agreement among raft nodes before linearized reading' (duration: 233.133247ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:55.673096Z","caller":"traceutil/trace.go:171","msg":"trace[481296141] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"170.760045ms","start":"2026-05-27T19:37:55.50231Z","end":"2026-05-27T19:37:55.67307Z","steps":["trace[481296141] 'read index received' (duration: 129.528949ms)","trace[481296141] 'applied index is now lower than readState.Index' (duration: 41.229946ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:55.673269Z","caller":"traceutil/trace.go:171","msg":"trace[475772256] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"171.631589ms","start":"2026-05-27T19:37:55.501618Z","end":"2026-05-27T19:37:55.673249Z","steps":["trace[475772256] 'process raft request' (duration: 130.29226ms)","trace[475772256] 'compare' (duration: 41.003599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:55.67342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.078513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-27T19:37:55.673739Z","caller":"traceutil/trace.go:171","msg":"trace[1197211758] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:266; }","duration":"171.439663ms","start":"2026-05-27T19:37:55.502282Z","end":"2026-05-27T19:37:55.673722Z","steps":["trace[1197211758] 'agreement among raft nodes before linearized reading' (duration: 171.007751ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:55.992045Z","caller":"traceutil/trace.go:171","msg":"trace[1418672078] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"161.176758ms","start":"2026-05-27T19:37:55.830827Z","end":"2026-05-27T19:37:55.992004Z","steps":["trace[1418672078] 'process raft request' (duration: 80.640671ms)","trace[1418672078] 'compare' (duration: 80.144856ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:55.992057Z","caller":"traceutil/trace.go:171","msg":"trace[1958412517] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"116.527838ms","start":"2026-05-27T19:37:55.875501Z","end":"2026-05-27T19:37:55.992029Z","steps":["trace[1958412517] 'process raft request' (duration: 116.459066ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:55.992184Z","caller":"traceutil/trace.go:171","msg":"trace[504109825] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"117.155145ms","start":"2026-05-27T19:37:55.875011Z","end":"2026-05-27T19:37:55.992166Z","steps":["trace[504109825] 'read index received' (duration: 36.442242ms)","trace[504109825] 'applied index is now lower than readState.Index' (duration: 80.711862ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:55.992291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.273878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:55.992746Z","caller":"traceutil/trace.go:171","msg":"trace[500463161] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:272; }","duration":"117.769232ms","start":"2026-05-27T19:37:55.874961Z","end":"2026-05-27T19:37:55.992731Z","steps":["trace[500463161] 'agreement among raft nodes before linearized reading' (duration: 117.243438ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:56.361931Z","caller":"traceutil/trace.go:171","msg":"trace[1325223760] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"138.558292ms","start":"2026-05-27T19:37:56.223345Z","end":"2026-05-27T19:37:56.361903Z","steps":["trace[1325223760] 'process raft request' (duration: 138.420358ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:56.362108Z","caller":"traceutil/trace.go:171","msg":"trace[1281016111] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"140.717992ms","start":"2026-05-27T19:37:56.221363Z","end":"2026-05-27T19:37:56.362081Z","steps":["trace[1281016111] 'process raft request' (duration: 106.950928ms)","trace[1281016111] 'compare' (duration: 33.27705ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:57.413996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.060849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:57.414226Z","caller":"traceutil/trace.go:171","msg":"trace[1904033370] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:315; }","duration":"180.377398ms","start":"2026-05-27T19:37:57.233821Z","end":"2026-05-27T19:37:57.414199Z","steps":["trace[1904033370] 'range keys from in-memory index tree' (duration: 179.927475ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:58.07509Z","caller":"traceutil/trace.go:171","msg":"trace[1250782071] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"144.063308ms","start":"2026-05-27T19:37:57.931005Z","end":"2026-05-27T19:37:58.075069Z","steps":["trace[1250782071] 'process raft request' (duration: 144.010177ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:58.075739Z","caller":"traceutil/trace.go:171","msg":"trace[1009956361] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"146.774395ms","start":"2026-05-27T19:37:57.928936Z","end":"2026-05-27T19:37:58.07571Z","steps":["trace[1009956361] 'process raft request' (duration: 96.835225ms)","trace[1009956361] 'compare' (duration: 49.104117ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:58.203836Z","caller":"traceutil/trace.go:171","msg":"trace[1249200788] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"120.438812ms","start":"2026-05-27T19:37:58.083369Z","end":"2026-05-27T19:37:58.203808Z","steps":["trace[1249200788] 'read index received' (duration: 102.497755ms)","trace[1249200788] 'applied index is now lower than readState.Index' (duration: 17.940047ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:58.203958Z","caller":"traceutil/trace.go:171","msg":"trace[523042382] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"122.341626ms","start":"2026-05-27T19:37:58.081586Z","end":"2026-05-27T19:37:58.203927Z","steps":["trace[523042382] 'process raft request' (duration: 104.269236ms)","trace[523042382] 'compare' (duration: 17.826033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:58.203987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.606877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-27T19:37:58.204042Z","caller":"traceutil/trace.go:171","msg":"trace[1915640284] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:328; }","duration":"120.71811ms","start":"2026-05-27T19:37:58.083307Z","end":"2026-05-27T19:37:58.204026Z","steps":["trace[1915640284] 'agreement among raft nodes before linearized reading' (duration: 120.602867ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:58.209817Z","caller":"traceutil/trace.go:171","msg":"trace[1266029665] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"125.046382ms","start":"2026-05-27T19:37:58.08475Z","end":"2026-05-27T19:37:58.209796Z","steps":["trace[1266029665] 'process raft request' (duration: 124.909618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:00.596901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.541186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:38:00.597133Z","caller":"traceutil/trace.go:171","msg":"trace[578716327] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"255.74016ms","start":"2026-05-27T19:38:00.341347Z","end":"2026-05-27T19:38:00.597087Z","steps":["trace[578716327] 'process raft request' (duration: 48.939407ms)","trace[578716327] 'compare' (duration: 206.016631ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:00.597311Z","caller":"traceutil/trace.go:171","msg":"trace[1529433134] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"236.47569ms","start":"2026-05-27T19:38:00.360817Z","end":"2026-05-27T19:38:00.597293Z","steps":["trace[1529433134] 'read index received' (duration: 29.481121ms)","trace[1529433134] 'applied index is now lower than readState.Index' (duration: 206.992729ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:00.597347Z","caller":"traceutil/trace.go:171","msg":"trace[1287369036] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"232.518518ms","start":"2026-05-27T19:38:00.364809Z","end":"2026-05-27T19:38:00.597328Z","steps":["trace[1287369036] 'process raft request' (duration: 232.213459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:00.597453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.760798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-27T19:38:00.59748Z","caller":"traceutil/trace.go:171","msg":"trace[902111065] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:361; }","duration":"236.81851ms","start":"2026-05-27T19:38:00.360653Z","end":"2026-05-27T19:38:00.597471Z","steps":["trace[902111065] 'agreement among raft nodes before linearized reading' (duration: 236.736548ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.597576Z","caller":"traceutil/trace.go:171","msg":"trace[1073075936] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"232.24594ms","start":"2026-05-27T19:38:00.365275Z","end":"2026-05-27T19:38:00.597521Z","steps":["trace[1073075936] 'process raft request' (duration: 231.836008ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.597634Z","caller":"traceutil/trace.go:171","msg":"trace[1054626454] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"231.724795ms","start":"2026-05-27T19:38:00.365889Z","end":"2026-05-27T19:38:00.597614Z","steps":["trace[1054626454] 'process raft request' (duration: 231.270172ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.597978Z","caller":"traceutil/trace.go:171","msg":"trace[291035769] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"228.351848ms","start":"2026-05-27T19:38:00.369615Z","end":"2026-05-27T19:38:00.597967Z","steps":["trace[291035769] 'process raft request' (duration: 227.621068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:00.598647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.99497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-27T19:38:00.598763Z","caller":"traceutil/trace.go:171","msg":"trace[675163334] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"228.275596ms","start":"2026-05-27T19:38:00.37041Z","end":"2026-05-27T19:38:00.598685Z","steps":["trace[675163334] 'process raft request' (duration: 227.517704ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.598713Z","caller":"traceutil/trace.go:171","msg":"trace[1131842467] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:363; }","duration":"117.084622ms","start":"2026-05-27T19:38:00.481613Z","end":"2026-05-27T19:38:00.598698Z","steps":["trace[1131842467] 'agreement among raft nodes before linearized reading' (duration: 116.975429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:00.599127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.624635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-27T19:38:00.599201Z","caller":"traceutil/trace.go:171","msg":"trace[780721366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:363; }","duration":"167.763189ms","start":"2026-05-27T19:38:00.431423Z","end":"2026-05-27T19:38:00.599187Z","steps":["trace[780721366] 'agreement among raft nodes before linearized reading' (duration: 167.620155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:00.599475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.991751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-27T19:38:00.599551Z","caller":"traceutil/trace.go:171","msg":"trace[163256500] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:363; }","duration":"219.114495ms","start":"2026-05-27T19:38:00.380419Z","end":"2026-05-27T19:38:00.599533Z","steps":["trace[163256500] 'agreement among raft nodes before linearized reading' (duration: 217.950722ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.599513Z","caller":"traceutil/trace.go:171","msg":"trace[1884105391] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"228.441211ms","start":"2026-05-27T19:38:00.371027Z","end":"2026-05-27T19:38:00.599468Z","steps":["trace[1884105391] 'process raft request' (duration: 227.232727ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.780609Z","caller":"traceutil/trace.go:171","msg":"trace[348861352] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"125.334648ms","start":"2026-05-27T19:38:00.655244Z","end":"2026-05-27T19:38:00.780578Z","steps":["trace[348861352] 'process raft request' (duration: 125.182864ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.780687Z","caller":"traceutil/trace.go:171","msg":"trace[1841282542] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"122.926469ms","start":"2026-05-27T19:38:00.657746Z","end":"2026-05-27T19:38:00.780672Z","steps":["trace[1841282542] 'process raft request' (duration: 122.757134ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.781002Z","caller":"traceutil/trace.go:171","msg":"trace[1206181586] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"127.196981ms","start":"2026-05-27T19:38:00.653791Z","end":"2026-05-27T19:38:00.780988Z","steps":["trace[1206181586] 'process raft request' (duration: 121.007104ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:00.781066Z","caller":"traceutil/trace.go:171","msg":"trace[2097269860] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"123.117264ms","start":"2026-05-27T19:38:00.657912Z","end":"2026-05-27T19:38:00.781029Z","steps":["trace[2097269860] 'process raft request' (duration: 122.655611ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:01.173471Z","caller":"traceutil/trace.go:171","msg":"trace[740176554] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"200.423395ms","start":"2026-05-27T19:38:00.972995Z","end":"2026-05-27T19:38:01.173418Z","steps":["trace[740176554] 'read index received' (duration: 167.576463ms)","trace[740176554] 'applied index is now lower than readState.Index' (duration: 32.845512ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:01.173905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.889949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-cht7z\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-27T19:38:01.173902Z","caller":"traceutil/trace.go:171","msg":"trace[2060708004] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"216.284598ms","start":"2026-05-27T19:38:00.957568Z","end":"2026-05-27T19:38:01.173852Z","steps":["trace[2060708004] 'process raft request' (duration: 183.013973ms)","trace[2060708004] 'compare' (duration: 32.664877ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:01.173992Z","caller":"traceutil/trace.go:171","msg":"trace[92551573] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-cht7z; range_end:; response_count:1; response_revision:391; }","duration":"201.033123ms","start":"2026-05-27T19:38:00.97294Z","end":"2026-05-27T19:38:01.173973Z","steps":["trace[92551573] 'agreement among raft nodes before linearized reading' (duration: 200.813627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:01.173957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.836578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-27T19:38:01.174111Z","caller":"traceutil/trace.go:171","msg":"trace[1629859872] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:391; }","duration":"201.049084ms","start":"2026-05-27T19:38:00.973046Z","end":"2026-05-27T19:38:01.174095Z","steps":["trace[1629859872] 'agreement among raft nodes before linearized reading' (duration: 200.789276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:01.920891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.421048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-cht7z\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-05-27T19:38:01.920981Z","caller":"traceutil/trace.go:171","msg":"trace[480507577] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-cht7z; range_end:; response_count:1; response_revision:401; }","duration":"118.561502ms","start":"2026-05-27T19:38:01.802401Z","end":"2026-05-27T19:38:01.920962Z","steps":["trace[480507577] 'range keys from in-memory index tree' (duration: 118.311705ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:02.189903Z","caller":"traceutil/trace.go:171","msg":"trace[428337503] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"170.751173ms","start":"2026-05-27T19:38:02.019129Z","end":"2026-05-27T19:38:02.18988Z","steps":["trace[428337503] 'process raft request' (duration: 170.621599ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:05.679559Z","caller":"traceutil/trace.go:171","msg":"trace[2032522636] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"148.198444ms","start":"2026-05-27T19:38:05.531319Z","end":"2026-05-27T19:38:05.679517Z","steps":["trace[2032522636] 'process raft request' (duration: 147.979058ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:13.89946Z","caller":"traceutil/trace.go:171","msg":"trace[1067528649] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"110.769152ms","start":"2026-05-27T19:38:13.788662Z","end":"2026-05-27T19:38:13.899432Z","steps":["trace[1067528649] 'read index received' (duration: 110.504194ms)","trace[1067528649] 'applied index is now lower than readState.Index' (duration: 261.988µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:13.89948Z","caller":"traceutil/trace.go:171","msg":"trace[1720688393] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"163.705365ms","start":"2026-05-27T19:38:13.735746Z","end":"2026-05-27T19:38:13.899451Z","steps":["trace[1720688393] 'process raft request' (duration: 163.440537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:13.899723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.017581ms","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-05-27T19:38:13.899855Z","caller":"traceutil/trace.go:171","msg":"trace[1364372516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:433; }","duration":"111.186706ms","start":"2026-05-27T19:38:13.788618Z","end":"2026-05-27T19:38:13.899805Z","steps":["trace[1364372516] 'agreement among raft nodes before linearized reading' (duration: 110.923428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:13.899905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.02107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T19:38:13.900016Z","caller":"traceutil/trace.go:171","msg":"trace[731874989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:433; }","duration":"111.213576ms","start":"2026-05-27T19:38:13.788777Z","end":"2026-05-27T19:38:13.899991Z","steps":["trace[731874989] 'agreement among raft nodes before linearized reading' (duration: 110.99419ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:14.101237Z","caller":"traceutil/trace.go:171","msg":"trace[1761111739] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"197.616313ms","start":"2026-05-27T19:38:13.903583Z","end":"2026-05-27T19:38:14.101199Z","steps":["trace[1761111739] 'process raft request' (duration: 195.160919ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:14.10135Z","caller":"traceutil/trace.go:171","msg":"trace[1482024522] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"197.481929ms","start":"2026-05-27T19:38:13.90385Z","end":"2026-05-27T19:38:14.101332Z","steps":["trace[1482024522] 'process raft request' (duration: 195.044255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:24.556749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922126217170096436,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:38:24.942286Z","caller":"traceutil/trace.go:171","msg":"trace[1258896994] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"886.027817ms","start":"2026-05-27T19:38:24.056243Z","end":"2026-05-27T19:38:24.94227Z","steps":["trace[1258896994] 'read index received' (duration: 885.866182ms)","trace[1258896994] 'applied index is now lower than readState.Index' (duration: 160.905µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:24.94233Z","caller":"traceutil/trace.go:171","msg":"trace[1192132782] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"908.191095ms","start":"2026-05-27T19:38:24.034108Z","end":"2026-05-27T19:38:24.942299Z","steps":["trace[1192132782] 'process raft request' (duration: 908.074611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:24.942418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.661793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-27T19:38:24.942423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.164502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:38:24.942452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:24.034088Z","time spent":"908.310289ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:38:24.942464Z","caller":"traceutil/trace.go:171","msg":"trace[1178723373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"761.710304ms","start":"2026-05-27T19:38:24.18073Z","end":"2026-05-27T19:38:24.942441Z","steps":["trace[1178723373] 'agreement among raft nodes before linearized reading' (duration: 761.636092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:24.942452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.48404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-27T19:38:24.942489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:24.180672Z","time spent":"761.810928ms","remote":"127.0.0.1:60564","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-05-27T19:38:24.942492Z","caller":"traceutil/trace.go:171","msg":"trace[1759041569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"886.288255ms","start":"2026-05-27T19:38:24.056187Z","end":"2026-05-27T19:38:24.942475Z","steps":["trace[1759041569] 'agreement among raft nodes before linearized reading' (duration: 886.158421ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:24.942525Z","caller":"traceutil/trace.go:171","msg":"trace[497659932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"742.595173ms","start":"2026-05-27T19:38:24.199911Z","end":"2026-05-27T19:38:24.942506Z","steps":["trace[497659932] 'agreement among raft nodes before linearized reading' (duration: 742.47841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:24.942528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:24.056162Z","time spent":"886.360038ms","remote":"127.0.0.1:60318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:38:24.942558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:24.199893Z","time spent":"742.658305ms","remote":"127.0.0.1:60564","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-05-27T19:38:24.943046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.702048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:38:24.943083Z","caller":"traceutil/trace.go:171","msg":"trace[2033549131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"526.893704ms","start":"2026-05-27T19:38:24.41618Z","end":"2026-05-27T19:38:24.943073Z","steps":["trace[2033549131] 'agreement among raft nodes before linearized reading' (duration: 526.42971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:24.943111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:24.416157Z","time spent":"526.947875ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T19:38:29.155267Z","caller":"traceutil/trace.go:171","msg":"trace[215006259] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"164.670781ms","start":"2026-05-27T19:38:28.990575Z","end":"2026-05-27T19:38:29.155246Z","steps":["trace[215006259] 'read index received' (duration: 164.534087ms)","trace[215006259] 'applied index is now lower than readState.Index' (duration: 135.784µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:29.155361Z","caller":"traceutil/trace.go:171","msg":"trace[558954274] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"194.176582ms","start":"2026-05-27T19:38:28.961117Z","end":"2026-05-27T19:38:29.155294Z","steps":["trace[558954274] 'process raft request' (duration: 193.983105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:29.155746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.498607ms","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-05-27T19:38:29.15585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.445065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:38:29.15591Z","caller":"traceutil/trace.go:171","msg":"trace[1674348109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"154.499747ms","start":"2026-05-27T19:38:29.001394Z","end":"2026-05-27T19:38:29.155894Z","steps":["trace[1674348109] 'agreement among raft nodes before linearized reading' (duration: 154.416474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:29.155746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.78493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:29.155874Z","caller":"traceutil/trace.go:171","msg":"trace[984734075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"154.696073ms","start":"2026-05-27T19:38:29.001156Z","end":"2026-05-27T19:38:29.155852Z","steps":["trace[984734075] 'agreement among raft nodes before linearized reading' (duration: 154.516778ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:29.156095Z","caller":"traceutil/trace.go:171","msg":"trace[1333424823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"101.138802ms","start":"2026-05-27T19:38:29.054902Z","end":"2026-05-27T19:38:29.156041Z","steps":["trace[1333424823] 'agreement among raft nodes before linearized reading' (duration: 100.794261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:29.156622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.048086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T19:38:29.156691Z","caller":"traceutil/trace.go:171","msg":"trace[634342304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"166.151589ms","start":"2026-05-27T19:38:28.99052Z","end":"2026-05-27T19:38:29.156672Z","steps":["trace[634342304] 'agreement among raft nodes before linearized reading' (duration: 164.918459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:29.642609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.546394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:38:29.642744Z","caller":"traceutil/trace.go:171","msg":"trace[1902476189] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"481.647721ms","start":"2026-05-27T19:38:29.161074Z","end":"2026-05-27T19:38:29.642721Z","steps":["trace[1902476189] 'process raft request' (duration: 103.915911ms)","trace[1902476189] 'compare' (duration: 377.388379ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:29.642826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:29.161053Z","time spent":"481.733474ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:38:29.692651Z","caller":"traceutil/trace.go:171","msg":"trace[1927206026] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"530.618076ms","start":"2026-05-27T19:38:29.162001Z","end":"2026-05-27T19:38:29.692619Z","steps":["trace[1927206026] 'process raft request' (duration: 530.560694ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:29.692893Z","caller":"traceutil/trace.go:171","msg":"trace[266619491] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"531.762293ms","start":"2026-05-27T19:38:29.161096Z","end":"2026-05-27T19:38:29.692859Z","steps":["trace[266619491] 'process raft request' (duration: 531.339609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:29.692938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:29.161983Z","time spent":"530.748441ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:38:29.693056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:29.16107Z","time spent":"531.901017ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:38:29.742035Z","caller":"traceutil/trace.go:171","msg":"trace[1849479315] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:514; }","duration":"364.102765ms","start":"2026-05-27T19:38:29.377899Z","end":"2026-05-27T19:38:29.742002Z","steps":["trace[1849479315] 'read index received' (duration: 314.578872ms)","trace[1849479315] 'applied index is now lower than readState.Index' (duration: 49.522223ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:29.742195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.27316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:29.742239Z","caller":"traceutil/trace.go:171","msg":"trace[1084733365] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:499; }","duration":"364.370013ms","start":"2026-05-27T19:38:29.377856Z","end":"2026-05-27T19:38:29.742226Z","steps":["trace[1084733365] 'agreement among raft nodes before linearized reading' (duration: 364.27113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:29.742281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:29.377833Z","time spent":"364.437556ms","remote":"127.0.0.1:60512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-05-27T19:38:32.148161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922126217170096531,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:38:32.207378Z","caller":"traceutil/trace.go:171","msg":"trace[985893596] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"637.443014ms","start":"2026-05-27T19:38:31.569905Z","end":"2026-05-27T19:38:32.207348Z","steps":["trace[985893596] 'process raft request' (duration: 637.185436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.207546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.569881Z","time spent":"637.593049ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.242799Z","caller":"traceutil/trace.go:171","msg":"trace[1328807357] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"664.797057ms","start":"2026-05-27T19:38:31.577981Z","end":"2026-05-27T19:38:32.242778Z","steps":["trace[1328807357] 'process raft request' (duration: 664.57353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.242963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.577969Z","time spent":"664.941071ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.243076Z","caller":"traceutil/trace.go:171","msg":"trace[1729794955] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"656.173059ms","start":"2026-05-27T19:38:31.58689Z","end":"2026-05-27T19:38:32.243063Z","steps":["trace[1729794955] 'process raft request' (duration: 655.901631ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:32.243103Z","caller":"traceutil/trace.go:171","msg":"trace[1423688968] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:552; }","duration":"595.719987ms","start":"2026-05-27T19:38:31.647365Z","end":"2026-05-27T19:38:32.243085Z","steps":["trace[1423688968] 'read index received' (duration: 559.845481ms)","trace[1423688968] 'applied index is now lower than readState.Index' (duration: 35.873776ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:32.243135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.586884Z","time spent":"656.225842ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.243176Z","caller":"traceutil/trace.go:171","msg":"trace[1090319130] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"653.780353ms","start":"2026-05-27T19:38:31.589389Z","end":"2026-05-27T19:38:32.24317Z","steps":["trace[1090319130] 'process raft request' (duration: 653.432482ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:32.243239Z","caller":"traceutil/trace.go:171","msg":"trace[560203080] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"656.937045ms","start":"2026-05-27T19:38:31.586293Z","end":"2026-05-27T19:38:32.24323Z","steps":["trace[560203080] 'process raft request' (duration: 656.449499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.243247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.589381Z","time spent":"653.815714ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:38:32.243284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.586287Z","time spent":"656.977296ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.243343Z","caller":"traceutil/trace.go:171","msg":"trace[925688905] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"598.890739ms","start":"2026-05-27T19:38:31.644446Z","end":"2026-05-27T19:38:32.243336Z","steps":["trace[925688905] 'process raft request' (duration: 598.542328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.243382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.644426Z","time spent":"598.94166ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.243427Z","caller":"traceutil/trace.go:171","msg":"trace[2036953464] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"477.072044ms","start":"2026-05-27T19:38:31.766346Z","end":"2026-05-27T19:38:32.243418Z","steps":["trace[2036953464] 'process raft request' (duration: 476.695482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.243482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.766315Z","time spent":"477.145936ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.243563Z","caller":"traceutil/trace.go:171","msg":"trace[765519088] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"665.51018ms","start":"2026-05-27T19:38:31.578045Z","end":"2026-05-27T19:38:32.243556Z","steps":["trace[765519088] 'process raft request' (duration: 664.599791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.243593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.226253ms","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-05-27T19:38:32.243626Z","caller":"traceutil/trace.go:171","msg":"trace[1300039028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"596.293095ms","start":"2026-05-27T19:38:31.647324Z","end":"2026-05-27T19:38:32.243618Z","steps":["trace[1300039028] 'agreement among raft nodes before linearized reading' (duration: 596.198962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.24361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.578027Z","time spent":"665.567462ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:38:32.24365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.647307Z","time spent":"596.337007ms","remote":"127.0.0.1:60564","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":"info","ts":"2026-05-27T19:38:32.243792Z","caller":"traceutil/trace.go:171","msg":"trace[335069812] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"653.408931ms","start":"2026-05-27T19:38:31.590376Z","end":"2026-05-27T19:38:32.243785Z","steps":["trace[335069812] 'process raft request' (duration: 652.46965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.243829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.590369Z","time spent":"653.442622ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.24389Z","caller":"traceutil/trace.go:171","msg":"trace[2045818850] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"637.629044ms","start":"2026-05-27T19:38:31.60625Z","end":"2026-05-27T19:38:32.243879Z","steps":["trace[2045818850] 'process raft request' (duration: 636.634692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.243952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.60623Z","time spent":"637.696706ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.243344Z","caller":"traceutil/trace.go:171","msg":"trace[624668888] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"621.213666ms","start":"2026-05-27T19:38:31.622115Z","end":"2026-05-27T19:38:32.243329Z","steps":["trace[624668888] 'process raft request' (duration: 620.828384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.244428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.622099Z","time spent":"622.117796ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:32.244794Z","caller":"traceutil/trace.go:171","msg":"trace[2071813124] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"657.10405ms","start":"2026-05-27T19:38:31.586088Z","end":"2026-05-27T19:38:32.243192Z","steps":["trace[2071813124] 'process raft request' (duration: 656.602573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.245021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.586073Z","time spent":"658.767674ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:38:32.251587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.197333ms","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-05-27T19:38:32.25164Z","caller":"traceutil/trace.go:171","msg":"trace[999097500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"554.283115ms","start":"2026-05-27T19:38:31.697345Z","end":"2026-05-27T19:38:32.251628Z","steps":["trace[999097500] 'agreement among raft nodes before linearized reading' (duration: 554.181482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.251668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.697331Z","time spent":"554.330517ms","remote":"127.0.0.1:60564","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-05-27T19:38:32.251832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.069905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:32.251858Z","caller":"traceutil/trace.go:171","msg":"trace[1107566015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"194.096026ms","start":"2026-05-27T19:38:32.057755Z","end":"2026-05-27T19:38:32.251851Z","steps":["trace[1107566015] 'agreement among raft nodes before linearized reading' (duration: 194.058564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.251954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.422631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T19:38:32.251977Z","caller":"traceutil/trace.go:171","msg":"trace[923495842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"554.447091ms","start":"2026-05-27T19:38:31.697524Z","end":"2026-05-27T19:38:32.251971Z","steps":["trace[923495842] 'agreement among raft nodes before linearized reading' (duration: 554.40828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:32.251994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:31.69746Z","time spent":"554.529124ms","remote":"127.0.0.1:60564","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":"info","ts":"2026-05-27T19:38:39.06625Z","caller":"traceutil/trace.go:171","msg":"trace[606464002] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"163.60446ms","start":"2026-05-27T19:38:38.902574Z","end":"2026-05-27T19:38:39.066178Z","steps":["trace[606464002] 'read index received' (duration: 155.130463ms)","trace[606464002] 'applied index is now lower than readState.Index' (duration: 8.472507ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:39.066299Z","caller":"traceutil/trace.go:171","msg":"trace[20522338] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"499.723593ms","start":"2026-05-27T19:38:38.566538Z","end":"2026-05-27T19:38:39.066261Z","steps":["trace[20522338] 'process raft request' (duration: 492.097413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:39.066458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.869089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f7bxv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-27T19:38:39.066524Z","caller":"traceutil/trace.go:171","msg":"trace[2059478067] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f7bxv; range_end:; response_count:1; response_revision:647; }","duration":"163.976212ms","start":"2026-05-27T19:38:38.902531Z","end":"2026-05-27T19:38:39.066508Z","steps":["trace[2059478067] 'agreement among raft nodes before linearized reading' (duration: 163.788426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:39.066616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:38.566512Z","time spent":"499.93854ms","remote":"127.0.0.1:60410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:38:39.825342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.092337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:38:39.825483Z","caller":"traceutil/trace.go:171","msg":"trace[394726366] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"741.206753ms","start":"2026-05-27T19:38:39.084258Z","end":"2026-05-27T19:38:39.825465Z","steps":["trace[394726366] 'process raft request' (duration: 462.91208ms)","trace[394726366] 'compare' (duration: 277.995693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:39.82556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:39.084237Z","time spent":"741.285336ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:38:40.235092Z","caller":"traceutil/trace.go:171","msg":"trace[848630133] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.108463393s","start":"2026-05-27T19:38:39.126589Z","end":"2026-05-27T19:38:40.235053Z","steps":["trace[848630133] 'process raft request' (duration: 1.108267037s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:40.235247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:39.126562Z","time spent":"1.108616218s","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:38:42.056865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000209187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-27T19:38:42.057012Z","caller":"traceutil/trace.go:171","msg":"trace[527381648] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000390723s","start":"2026-05-27T19:38:40.056594Z","end":"2026-05-27T19:38:42.056984Z","steps":["trace[527381648] 'agreement among raft nodes before linearized reading' (duration: 2.000235647s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:42.05709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:40.056576Z","time spent":"2.000498056s","remote":"127.0.0.1:60318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/27 19:38:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-27T19:38:42.4906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.255624606s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-27T19:38:42.49071Z","caller":"traceutil/trace.go:171","msg":"trace[81614050] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"3.296735778s","start":"2026-05-27T19:38:39.193957Z","end":"2026-05-27T19:38:42.490693Z","steps":["trace[81614050] 'read index received' (duration: 353.288742ms)","trace[81614050] 'applied index is now lower than readState.Index' (duration: 2.943445586s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:42.491553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.297583847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-27T19:38:42.491619Z","caller":"traceutil/trace.go:171","msg":"trace[512676423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"3.29769376s","start":"2026-05-27T19:38:39.193908Z","end":"2026-05-27T19:38:42.491602Z","steps":["trace[512676423] 'agreement among raft nodes before linearized reading' (duration: 3.297107081s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:42.491662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:39.193889Z","time spent":"3.297762123s","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:38:42.540826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.301090699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-27T19:38:42.540859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.418066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:42.540927Z","caller":"traceutil/trace.go:171","msg":"trace[661084304] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:650; }","duration":"2.301231134s","start":"2026-05-27T19:38:40.239671Z","end":"2026-05-27T19:38:42.540902Z","steps":["trace[661084304] 'agreement among raft nodes before linearized reading' (duration: 2.301011426s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:42.540947Z","caller":"traceutil/trace.go:171","msg":"trace[245272570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"483.669744ms","start":"2026-05-27T19:38:42.057256Z","end":"2026-05-27T19:38:42.540926Z","steps":["trace[245272570] 'agreement among raft nodes before linearized reading' (duration: 483.479798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:42.540986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:40.239653Z","time spent":"2.301318646s","remote":"127.0.0.1:60512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-27T19:38:42.541002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:42.057235Z","time spent":"483.754488ms","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:38:42.541042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.250698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:38:42.541053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.232673278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T19:38:42.54111Z","caller":"traceutil/trace.go:171","msg":"trace[899411107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"410.339701ms","start":"2026-05-27T19:38:42.130752Z","end":"2026-05-27T19:38:42.541091Z","steps":["trace[899411107] 'agreement among raft nodes before linearized reading' (duration: 410.228397ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:42.541129Z","caller":"traceutil/trace.go:171","msg":"trace[1943601439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"2.232771572s","start":"2026-05-27T19:38:40.30834Z","end":"2026-05-27T19:38:42.541112Z","steps":["trace[1943601439] 'agreement among raft nodes before linearized reading' (duration: 2.232585105s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:42.541151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:42.130733Z","time spent":"410.410913ms","remote":"127.0.0.1:60318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:38:42.541172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:40.308332Z","time spent":"2.232832103s","remote":"127.0.0.1:60564","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-05-27T19:38:42.541238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.233067921s","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-05-27T19:38:42.541349Z","caller":"traceutil/trace.go:171","msg":"trace[1905506491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"2.233227167s","start":"2026-05-27T19:38:40.308104Z","end":"2026-05-27T19:38:42.541331Z","steps":["trace[1905506491] 'agreement among raft nodes before linearized reading' (duration: 2.232700229s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:42.541438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:40.308066Z","time spent":"2.233354361s","remote":"127.0.0.1:60564","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-05-27T19:38:42.541742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.243584008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:38:42.542358Z","caller":"traceutil/trace.go:171","msg":"trace[6981403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"2.244358503s","start":"2026-05-27T19:38:40.297828Z","end":"2026-05-27T19:38:42.542187Z","steps":["trace[6981403] 'agreement among raft nodes before linearized reading' (duration: 2.243257257s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:42.542521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:40.297806Z","time spent":"2.244680854s","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T19:38:44.342318Z","caller":"traceutil/trace.go:171","msg":"trace[1461237264] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"305.22125ms","start":"2026-05-27T19:38:44.037061Z","end":"2026-05-27T19:38:44.342282Z","steps":["trace[1461237264] 'process raft request' (duration: 283.916303ms)","trace[1461237264] 'compare' (duration: 20.978986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:44.342653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:44.037037Z","time spent":"305.374806ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:38:44.34557Z","caller":"traceutil/trace.go:171","msg":"trace[998242723] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:707; }","duration":"288.300089ms","start":"2026-05-27T19:38:44.057252Z","end":"2026-05-27T19:38:44.345553Z","steps":["trace[998242723] 'read index received' (duration: 263.729663ms)","trace[998242723] 'applied index is now lower than readState.Index' (duration: 24.569796ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:44.345704Z","caller":"traceutil/trace.go:171","msg":"trace[860697226] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"151.274992ms","start":"2026-05-27T19:38:44.194386Z","end":"2026-05-27T19:38:44.345661Z","steps":["trace[860697226] 'process raft request' (duration: 151.141437ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:44.345658Z","caller":"traceutil/trace.go:171","msg":"trace[423022058] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"307.687772ms","start":"2026-05-27T19:38:44.037938Z","end":"2026-05-27T19:38:44.345626Z","steps":["trace[423022058] 'process raft request' (duration: 307.491686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:44.345739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.415653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:38:44.345982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:44.037921Z","time spent":"307.996583ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:38:44.346008Z","caller":"traceutil/trace.go:171","msg":"trace[1631522002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"288.794136ms","start":"2026-05-27T19:38:44.057203Z","end":"2026-05-27T19:38:44.345997Z","steps":["trace[1631522002] 'agreement among raft nodes before linearized reading' (duration: 288.418963ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:47.378928Z","caller":"traceutil/trace.go:171","msg":"trace[509059413] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"180.529072ms","start":"2026-05-27T19:38:47.198379Z","end":"2026-05-27T19:38:47.378908Z","steps":["trace[509059413] 'process raft request' (duration: 180.411709ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:54.997544Z","caller":"traceutil/trace.go:171","msg":"trace[1454339901] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"353.448722ms","start":"2026-05-27T19:38:54.644071Z","end":"2026-05-27T19:38:54.99752Z","steps":["trace[1454339901] 'read index received' (duration: 310.515327ms)","trace[1454339901] 'applied index is now lower than readState.Index' (duration: 42.932315ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:54.997772Z","caller":"traceutil/trace.go:171","msg":"trace[1973222316] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"356.146174ms","start":"2026-05-27T19:38:54.641606Z","end":"2026-05-27T19:38:54.997753Z","steps":["trace[1973222316] 'process raft request' (duration: 313.043613ms)","trace[1973222316] 'compare' (duration: 42.681706ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:54.997817Z","caller":"traceutil/trace.go:171","msg":"trace[2095171281] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"353.825945ms","start":"2026-05-27T19:38:54.643959Z","end":"2026-05-27T19:38:54.997785Z","steps":["trace[2095171281] 'process raft request' (duration: 353.521504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:54.997943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:54.64159Z","time spent":"356.217835ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:38:54.997969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:54.643951Z","time spent":"353.940068ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:38:54.998348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.267679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T19:38:54.998405Z","caller":"traceutil/trace.go:171","msg":"trace[146011318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"354.351002ms","start":"2026-05-27T19:38:54.644037Z","end":"2026-05-27T19:38:54.998388Z","steps":["trace[146011318] 'agreement among raft nodes before linearized reading' (duration: 353.571726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:54.998443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:54.644008Z","time spent":"354.426165ms","remote":"127.0.0.1:60564","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-05-27T19:38:58.882228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.828492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-27T19:38:58.882505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.111806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mmtsg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-27T19:38:58.882578Z","caller":"traceutil/trace.go:171","msg":"trace[2113799689] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mmtsg; range_end:; response_count:1; response_revision:814; }","duration":"885.210569ms","start":"2026-05-27T19:38:57.997349Z","end":"2026-05-27T19:38:58.88256Z","steps":["trace[2113799689] 'range keys from in-memory index tree' (duration: 884.991061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:58.882634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:57.997331Z","time spent":"885.287992ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mmtsg\" "} {"level":"warn","ts":"2026-05-27T19:38:58.882702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.227176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-27T19:38:58.882779Z","caller":"traceutil/trace.go:171","msg":"trace[1119125578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"719.360971ms","start":"2026-05-27T19:38:58.1634Z","end":"2026-05-27T19:38:58.882761Z","steps":["trace[1119125578] 'range keys from in-memory index tree' (duration: 719.055481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:58.882822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:58.16338Z","time spent":"719.432023ms","remote":"127.0.0.1:60564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T19:38:58.882318Z","caller":"traceutil/trace.go:171","msg":"trace[352151911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"205.960087ms","start":"2026-05-27T19:38:58.67634Z","end":"2026-05-27T19:38:58.8823Z","steps":["trace[352151911] 'range keys from in-memory index tree' (duration: 205.674937ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:59.111156Z","caller":"traceutil/trace.go:171","msg":"trace[1768787356] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"223.564794ms","start":"2026-05-27T19:38:58.887559Z","end":"2026-05-27T19:38:59.111124Z","steps":["trace[1768787356] 'process raft request' (duration: 223.381907ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:59.137411Z","caller":"traceutil/trace.go:171","msg":"trace[2102201369] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"246.742606ms","start":"2026-05-27T19:38:58.890641Z","end":"2026-05-27T19:38:59.137384Z","steps":["trace[2102201369] 'process raft request' (duration: 246.482907ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:59.137418Z","caller":"traceutil/trace.go:171","msg":"trace[1747257308] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"249.5024ms","start":"2026-05-27T19:38:58.887839Z","end":"2026-05-27T19:38:59.137342Z","steps":["trace[1747257308] 'process raft request' (duration: 249.090325ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:59.149943Z","caller":"traceutil/trace.go:171","msg":"trace[1847888487] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:844; }","duration":"132.680704ms","start":"2026-05-27T19:38:59.017239Z","end":"2026-05-27T19:38:59.14992Z","steps":["trace[1847888487] 'read index received' (duration: 93.829437ms)","trace[1847888487] 'applied index is now lower than readState.Index' (duration: 38.849967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:59.150113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.867741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:38:59.150177Z","caller":"traceutil/trace.go:171","msg":"trace[2145162869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:817; }","duration":"132.983895ms","start":"2026-05-27T19:38:59.01718Z","end":"2026-05-27T19:38:59.150164Z","steps":["trace[2145162869] 'agreement among raft nodes before linearized reading' (duration: 132.815149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:59.150192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.406011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T19:38:59.150222Z","caller":"traceutil/trace.go:171","msg":"trace[1201283165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:817; }","duration":"100.438402ms","start":"2026-05-27T19:38:59.049775Z","end":"2026-05-27T19:38:59.150213Z","steps":["trace[1201283165] 'agreement among raft nodes before linearized reading' (duration: 100.349099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:59.150415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.722855ms","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-05-27T19:38:59.150477Z","caller":"traceutil/trace.go:171","msg":"trace[486353555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:817; }","duration":"115.824508ms","start":"2026-05-27T19:38:59.034637Z","end":"2026-05-27T19:38:59.150461Z","steps":["trace[486353555] 'agreement among raft nodes before linearized reading' (duration: 115.419244ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:13.698677Z","caller":"traceutil/trace.go:171","msg":"trace[374142398] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"165.459065ms","start":"2026-05-27T19:39:13.533184Z","end":"2026-05-27T19:39:13.698643Z","steps":["trace[374142398] 'process raft request' (duration: 165.270438ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:48.818722Z","caller":"traceutil/trace.go:171","msg":"trace[896090796] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"262.679556ms","start":"2026-05-27T19:39:48.556011Z","end":"2026-05-27T19:39:48.818691Z","steps":["trace[896090796] 'process raft request' (duration: 262.477599ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.782772Z","caller":"traceutil/trace.go:171","msg":"trace[1984363755] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"170.17157ms","start":"2026-05-27T19:40:05.612564Z","end":"2026-05-27T19:40:05.782736Z","steps":["trace[1984363755] 'process raft request' (duration: 169.89719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:05.783517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.45455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:40:05.783533Z","caller":"traceutil/trace.go:171","msg":"trace[212804671] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"169.061729ms","start":"2026-05-27T19:40:05.614449Z","end":"2026-05-27T19:40:05.783511Z","steps":["trace[212804671] 'process raft request' (duration: 168.143455ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.783592Z","caller":"traceutil/trace.go:171","msg":"trace[1668582432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1167; }","duration":"170.574804ms","start":"2026-05-27T19:40:05.613Z","end":"2026-05-27T19:40:05.783575Z","steps":["trace[1668582432] 'agreement among raft nodes before linearized reading' (duration: 170.217161ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.78266Z","caller":"traceutil/trace.go:171","msg":"trace[1243159068] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1210; }","duration":"169.601189ms","start":"2026-05-27T19:40:05.613034Z","end":"2026-05-27T19:40:05.782635Z","steps":["trace[1243159068] 'read index received' (duration: 49.22815ms)","trace[1243159068] 'applied index is now lower than readState.Index' (duration: 120.371929ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:08.96995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:08.526946Z","time spent":"442.995983ms","remote":"127.0.0.1:58064","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T19:41:08.970092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.993178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-05-27T19:41:08.970176Z","caller":"traceutil/trace.go:171","msg":"trace[1517717168] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1417; }","duration":"601.116822ms","start":"2026-05-27T19:41:08.369038Z","end":"2026-05-27T19:41:08.970155Z","steps":["trace[1517717168] 'range keys from in-memory index tree' (duration: 600.897934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:08.970236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:08.369006Z","time spent":"601.213836ms","remote":"127.0.0.1:60648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":795,"request content":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" "} {"level":"warn","ts":"2026-05-27T19:41:08.970318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.198198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-05-27T19:41:08.970395Z","caller":"traceutil/trace.go:171","msg":"trace[125215860] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1417; }","duration":"558.293993ms","start":"2026-05-27T19:41:08.412083Z","end":"2026-05-27T19:41:08.970377Z","steps":["trace[125215860] 'range keys from in-memory index tree' (duration: 558.120516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:08.970437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:08.412057Z","time spent":"558.370526ms","remote":"127.0.0.1:60646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":749,"request content":"key:\"/registry/clusterroles/system:controller:namespace-controller\" "}