{"level":"warn","ts":"2026-07-02T05:01:41.920518Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:01:41.920624Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-07-02T05:01:41.920736Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:01:41.920746Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-02T05:01:41.920763Z","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-07-02T05:01:41.921448Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-02T05:01:41.921528Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-07-02T05:01:41.925547Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.733698ms"} {"level":"info","ts":"2026-07-02T05:01:41.931436Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-02T05:01:41.931538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:01:41.931567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-02T05:01:41.931591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:01:41.931604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-02T05:01:41.931662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-02T05:01:41.944456Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:01:41.946824Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:01:41.948172Z","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-07-02T05:01:41.950035Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:01:41.950325Z","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-07-02T05:01:41.950583Z","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-07-02T05:01:41.950623Z","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-07-02T05:01:41.950355Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:01:41.952398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-02T05:01:41.952627Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-02T05:01:41.954827Z","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-07-02T05:01:41.955029Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-02T05:01:41.955095Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-02T05:01:41.955276Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:01:41.955383Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:01:42.432233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:01:42.432296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:01:42.432347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-02T05:01:42.432381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:01:42.432395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-02T05:01:42.432417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-02T05:01:42.432436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-02T05:01:42.433359Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:01:42.433633Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:01:42.433727Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:01:42.433812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:01:42.434193Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:01:42.434227Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:01:42.435579Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-02T05:01:42.436763Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:01:42.436838Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:01:42.436858Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:01:42.436923Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:01:44.081666Z","caller":"traceutil/trace.go:171","msg":"trace[1433620841] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"174.678106ms","start":"2026-07-02T05:01:43.906942Z","end":"2026-07-02T05:01:44.08162Z","steps":["trace[1433620841] 'process raft request' (duration: 97.861923ms)","trace[1433620841] 'compare' (duration: 76.491791ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:01:44.081747Z","caller":"traceutil/trace.go:171","msg":"trace[1609127785] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"173.929661ms","start":"2026-07-02T05:01:43.907769Z","end":"2026-07-02T05:01:44.081699Z","steps":["trace[1609127785] 'process raft request' (duration: 173.77815ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.082061Z","caller":"traceutil/trace.go:171","msg":"trace[1751969500] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"105.024781ms","start":"2026-07-02T05:01:43.977022Z","end":"2026-07-02T05:01:44.082047Z","steps":["trace[1751969500] 'process raft request' (duration: 104.979341ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.082261Z","caller":"traceutil/trace.go:171","msg":"trace[813996721] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"171.961387ms","start":"2026-07-02T05:01:43.910276Z","end":"2026-07-02T05:01:44.082237Z","steps":["trace[813996721] 'process raft request' (duration: 171.423143ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.082278Z","caller":"traceutil/trace.go:171","msg":"trace[2129650572] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"172.897813ms","start":"2026-07-02T05:01:43.909337Z","end":"2026-07-02T05:01:44.082235Z","steps":["trace[2129650572] 'process raft request' (duration: 172.255178ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.08231Z","caller":"traceutil/trace.go:171","msg":"trace[223483330] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"107.139018ms","start":"2026-07-02T05:01:43.975147Z","end":"2026-07-02T05:01:44.082286Z","steps":["trace[223483330] 'process raft request' (duration: 106.760405ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.082473Z","caller":"traceutil/trace.go:171","msg":"trace[805341037] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"167.704414ms","start":"2026-07-02T05:01:43.91475Z","end":"2026-07-02T05:01:44.082454Z","steps":["trace[805341037] 'process raft request' (duration: 166.995719ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.082523Z","caller":"traceutil/trace.go:171","msg":"trace[2126874663] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:19; }","duration":"110.546152ms","start":"2026-07-02T05:01:43.971947Z","end":"2026-07-02T05:01:44.082493Z","steps":["trace[2126874663] 'read index received' (duration: 32.884183ms)","trace[2126874663] 'applied index is now lower than readState.Index' (duration: 77.658739ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:01:44.08267Z","caller":"traceutil/trace.go:171","msg":"trace[377407706] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"107.423289ms","start":"2026-07-02T05:01:43.975163Z","end":"2026-07-02T05:01:44.082586Z","steps":["trace[377407706] 'process raft request' (duration: 106.777525ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.082721Z","caller":"traceutil/trace.go:171","msg":"trace[1687925568] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"106.798446ms","start":"2026-07-02T05:01:43.975828Z","end":"2026-07-02T05:01:44.082626Z","steps":["trace[1687925568] 'process raft request' (duration: 106.143351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:01:44.082756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.612287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-vg74b\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-07-02T05:01:44.082828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.183705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-02T05:01:44.082847Z","caller":"traceutil/trace.go:171","msg":"trace[1925421205] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-vg74b; range_end:; response_count:1; response_revision:24; }","duration":"174.748298ms","start":"2026-07-02T05:01:43.908083Z","end":"2026-07-02T05:01:44.082831Z","steps":["trace[1925421205] 'agreement among raft nodes before linearized reading' (duration: 174.575467ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.082887Z","caller":"traceutil/trace.go:171","msg":"trace[992762100] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:24; }","duration":"174.277975ms","start":"2026-07-02T05:01:43.908595Z","end":"2026-07-02T05:01:44.082873Z","steps":["trace[992762100] 'agreement among raft nodes before linearized reading' (duration: 174.153804ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.2671Z","caller":"traceutil/trace.go:171","msg":"trace[525770172] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"177.830805ms","start":"2026-07-02T05:01:44.089229Z","end":"2026-07-02T05:01:44.26706Z","steps":["trace[525770172] 'process raft request' (duration: 126.241357ms)","trace[525770172] 'compare' (duration: 51.322586ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:01:44.267187Z","caller":"traceutil/trace.go:171","msg":"trace[99469502] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"177.729953ms","start":"2026-07-02T05:01:44.0894Z","end":"2026-07-02T05:01:44.26713Z","steps":["trace[99469502] 'process raft request' (duration: 177.537622ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.268344Z","caller":"traceutil/trace.go:171","msg":"trace[394585780] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"178.899663ms","start":"2026-07-02T05:01:44.089421Z","end":"2026-07-02T05:01:44.268321Z","steps":["trace[394585780] 'process raft request' (duration: 177.599363ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.268359Z","caller":"traceutil/trace.go:171","msg":"trace[1238045557] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"178.331829ms","start":"2026-07-02T05:01:44.089994Z","end":"2026-07-02T05:01:44.268326Z","steps":["trace[1238045557] 'process raft request' (duration: 177.985016ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.268429Z","caller":"traceutil/trace.go:171","msg":"trace[168375756] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"178.049967ms","start":"2026-07-02T05:01:44.090358Z","end":"2026-07-02T05:01:44.268408Z","steps":["trace[168375756] 'process raft request' (duration: 177.695245ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.268563Z","caller":"traceutil/trace.go:171","msg":"trace[877256349] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"178.973144ms","start":"2026-07-02T05:01:44.089468Z","end":"2026-07-02T05:01:44.268441Z","steps":["trace[877256349] 'process raft request' (duration: 178.43449ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.26872Z","caller":"traceutil/trace.go:171","msg":"trace[683862467] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"178.266728ms","start":"2026-07-02T05:01:44.090434Z","end":"2026-07-02T05:01:44.268701Z","steps":["trace[683862467] 'process raft request' (duration: 177.678034ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.269259Z","caller":"traceutil/trace.go:171","msg":"trace[741789857] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"178.670911ms","start":"2026-07-02T05:01:44.090552Z","end":"2026-07-02T05:01:44.269222Z","steps":["trace[741789857] 'process raft request' (duration: 178.077537ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.269381Z","caller":"traceutil/trace.go:171","msg":"trace[234210817] linearizableReadLoop","detail":"{readStateIndex:33; appliedIndex:28; }","duration":"179.730649ms","start":"2026-07-02T05:01:44.089586Z","end":"2026-07-02T05:01:44.269317Z","steps":["trace[234210817] 'read index received' (duration: 87.101309ms)","trace[234210817] 'applied index is now lower than readState.Index' (duration: 92.62609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:01:44.269659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.044431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T05:01:44.269733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.275795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T05:01:44.26976Z","caller":"traceutil/trace.go:171","msg":"trace[2106762497] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:34; }","duration":"180.149461ms","start":"2026-07-02T05:01:44.089566Z","end":"2026-07-02T05:01:44.269715Z","steps":["trace[2106762497] 'agreement among raft nodes before linearized reading' (duration: 179.90362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:01:44.269746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.62148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T05:01:44.269995Z","caller":"traceutil/trace.go:171","msg":"trace[277316896] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:34; }","duration":"144.909322ms","start":"2026-07-02T05:01:44.125066Z","end":"2026-07-02T05:01:44.269975Z","steps":["trace[277316896] 'agreement among raft nodes before linearized reading' (duration: 144.58438ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.269823Z","caller":"traceutil/trace.go:171","msg":"trace[718049650] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:34; }","duration":"179.425417ms","start":"2026-07-02T05:01:44.090378Z","end":"2026-07-02T05:01:44.269803Z","steps":["trace[718049650] 'agreement among raft nodes before linearized reading' (duration: 179.214726ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.476758Z","caller":"traceutil/trace.go:171","msg":"trace[883434230] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:48; }","duration":"110.28786ms","start":"2026-07-02T05:01:44.366445Z","end":"2026-07-02T05:01:44.476733Z","steps":["trace[883434230] 'read index received' (duration: 74.7764ms)","trace[883434230] 'applied index is now lower than readState.Index' (duration: 35.50677ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:01:44.476884Z","caller":"traceutil/trace.go:171","msg":"trace[1122893288] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"110.010568ms","start":"2026-07-02T05:01:44.366855Z","end":"2026-07-02T05:01:44.476866Z","steps":["trace[1122893288] 'process raft request' (duration: 109.924717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:01:44.476985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.516511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T05:01:44.477101Z","caller":"traceutil/trace.go:171","msg":"trace[433852686] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:46; }","duration":"110.649632ms","start":"2026-07-02T05:01:44.366379Z","end":"2026-07-02T05:01:44.477028Z","steps":["trace[433852686] 'agreement among raft nodes before linearized reading' (duration: 110.46136ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.477237Z","caller":"traceutil/trace.go:171","msg":"trace[1608761648] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"111.227996ms","start":"2026-07-02T05:01:44.365979Z","end":"2026-07-02T05:01:44.477207Z","steps":["trace[1608761648] 'process raft request' (duration: 75.255223ms)","trace[1608761648] 'compare' (duration: 35.344528ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:01:44.811062Z","caller":"traceutil/trace.go:171","msg":"trace[2045416968] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"185.986155ms","start":"2026-07-02T05:01:44.625045Z","end":"2026-07-02T05:01:44.811032Z","steps":["trace[2045416968] 'process raft request' (duration: 185.844173ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.811471Z","caller":"traceutil/trace.go:171","msg":"trace[1790099561] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"140.747164ms","start":"2026-07-02T05:01:44.670708Z","end":"2026-07-02T05:01:44.811455Z","steps":["trace[1790099561] 'process raft request' (duration: 140.284011ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:01:44.8112Z","caller":"traceutil/trace.go:171","msg":"trace[388569589] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"187.427655ms","start":"2026-07-02T05:01:44.623697Z","end":"2026-07-02T05:01:44.811125Z","steps":["trace[388569589] 'process raft request' (duration: 104.857709ms)","trace[388569589] 'compare' (duration: 82.092512ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:01:47.087517Z","caller":"traceutil/trace.go:171","msg":"trace[1529699324] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"175.677928ms","start":"2026-07-02T05:01:46.911801Z","end":"2026-07-02T05:01:47.087479Z","steps":["trace[1529699324] 'process raft request' (duration: 175.451247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:07.146698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.115279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T05:02:07.146831Z","caller":"traceutil/trace.go:171","msg":"trace[1942484202] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:431; }","duration":"858.31238ms","start":"2026-07-02T05:02:06.288491Z","end":"2026-07-02T05:02:07.146804Z","steps":["trace[1942484202] 'range keys from in-memory index tree' (duration: 857.993657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:07.146806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001891798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-02T05:02:07.14687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.839354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-07-02T05:02:07.146944Z","caller":"traceutil/trace.go:171","msg":"trace[63254620] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:431; }","duration":"1.002068799s","start":"2026-07-02T05:02:06.144837Z","end":"2026-07-02T05:02:07.146906Z","steps":["trace[63254620] 'range keys from in-memory index tree' (duration: 1.001793138s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:07.146907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:06.288467Z","time spent":"858.42466ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T05:02:07.147003Z","caller":"traceutil/trace.go:171","msg":"trace[1118432463] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:431; }","duration":"881.963745ms","start":"2026-07-02T05:02:06.265006Z","end":"2026-07-02T05:02:07.14697Z","steps":["trace[1118432463] 'range keys from in-memory index tree' (duration: 881.751573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:07.147012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:06.144827Z","time spent":"1.0021734s","remote":"127.0.0.1:44902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-07-02T05:02:07.147065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:06.264993Z","time spent":"882.057775ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4078,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-02T05:02:07.147044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13211485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-02T05:02:07.146842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308457007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T05:02:07.147177Z","caller":"traceutil/trace.go:171","msg":"trace[1418471766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"1.132290991s","start":"2026-07-02T05:02:06.014857Z","end":"2026-07-02T05:02:07.147148Z","steps":["trace[1418471766] 'range keys from in-memory index tree' (duration: 1.131984328s)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:07.147231Z","caller":"traceutil/trace.go:171","msg":"trace[944858710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"1.308860759s","start":"2026-07-02T05:02:05.838303Z","end":"2026-07-02T05:02:07.147163Z","steps":["trace[944858710] 'range keys from in-memory index tree' (duration: 1.308349916s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:07.147238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:06.014843Z","time spent":"1.132384911s","remote":"127.0.0.1:59018","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-07-02T05:02:07.147315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:05.838286Z","time spent":"1.308985549s","remote":"127.0.0.1:59018","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-07-02T05:02:07.147479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.193532ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:07.14778Z","caller":"traceutil/trace.go:171","msg":"trace[685884188] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:431; }","duration":"883.499144ms","start":"2026-07-02T05:02:06.264266Z","end":"2026-07-02T05:02:07.147765Z","steps":["trace[685884188] 'range keys from in-memory index tree' (duration: 883.183302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:07.714799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:07.148562Z","time spent":"566.23006ms","remote":"127.0.0.1:44902","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-02T05:02:08.035469Z","caller":"traceutil/trace.go:171","msg":"trace[2008084244] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"883.965585ms","start":"2026-07-02T05:02:07.151464Z","end":"2026-07-02T05:02:08.03543Z","steps":["trace[2008084244] 'process raft request' (duration: 883.590367ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:08.0355Z","caller":"traceutil/trace.go:171","msg":"trace[833001016] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"883.932916ms","start":"2026-07-02T05:02:07.151533Z","end":"2026-07-02T05:02:08.035466Z","steps":["trace[833001016] 'process raft request' (duration: 883.717675ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:08.035485Z","caller":"traceutil/trace.go:171","msg":"trace[1824443787] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:449; }","duration":"622.185781ms","start":"2026-07-02T05:02:07.413275Z","end":"2026-07-02T05:02:08.03546Z","steps":["trace[1824443787] 'read index received' (duration: 301.475275ms)","trace[1824443787] 'applied index is now lower than readState.Index' (duration: 320.709606ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:02:08.035653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.368519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:08.035697Z","caller":"traceutil/trace.go:171","msg":"trace[1641634409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:435; }","duration":"622.493472ms","start":"2026-07-02T05:02:07.413192Z","end":"2026-07-02T05:02:08.035686Z","steps":["trace[1641634409] 'agreement among raft nodes before linearized reading' (duration: 622.372056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:08.035785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:07.413164Z","time spent":"622.557134ms","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:02:08.035809Z","caller":"traceutil/trace.go:171","msg":"trace[445259471] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"883.028453ms","start":"2026-07-02T05:02:07.152769Z","end":"2026-07-02T05:02:08.035797Z","steps":["trace[445259471] 'process raft request' (duration: 882.580841ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:08.035902Z","caller":"traceutil/trace.go:171","msg":"trace[346027171] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"876.357615ms","start":"2026-07-02T05:02:07.159536Z","end":"2026-07-02T05:02:08.035894Z","steps":["trace[346027171] 'process raft request' (duration: 875.871751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:08.036155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:07.152758Z","time spent":"883.077466ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:08.036151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:07.151442Z","time spent":"884.124282ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:08.036331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:07.151513Z","time spent":"884.070052ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:08.036334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:07.159511Z","time spent":"876.410996ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:08.810319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.459789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:02:08.810438Z","caller":"traceutil/trace.go:171","msg":"trace[735007150] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"774.815883ms","start":"2026-07-02T05:02:08.035604Z","end":"2026-07-02T05:02:08.81042Z","steps":["trace[735007150] 'read index received' (duration: 406.982275ms)","trace[735007150] 'applied index is now lower than readState.Index' (duration: 367.832148ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:02:08.810573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196742392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T05:02:08.810546Z","caller":"traceutil/trace.go:171","msg":"trace[230062840] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"1.09407164s","start":"2026-07-02T05:02:07.716427Z","end":"2026-07-02T05:02:08.810499Z","steps":["trace[230062840] 'process raft request' (duration: 726.131087ms)","trace[230062840] 'compare' (duration: 367.227267ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:08.810609Z","caller":"traceutil/trace.go:171","msg":"trace[1747353216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"1.196806543s","start":"2026-07-02T05:02:07.613791Z","end":"2026-07-02T05:02:08.810598Z","steps":["trace[1747353216] 'agreement among raft nodes before linearized reading' (duration: 1.196702228s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:08.810651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:07.613777Z","time spent":"1.196865215s","remote":"127.0.0.1:59018","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-07-02T05:02:08.810732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:07.716388Z","time spent":"1.094251857s","remote":"127.0.0.1:44902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:02:08.811235Z","caller":"traceutil/trace.go:171","msg":"trace[1514817257] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"768.464938ms","start":"2026-07-02T05:02:08.042748Z","end":"2026-07-02T05:02:08.811213Z","steps":["trace[1514817257] 'process raft request' (duration: 768.383224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:08.811371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:08.042729Z","time spent":"768.580454ms","remote":"127.0.0.1:59214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:02:08.812024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.617013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:08.812077Z","caller":"traceutil/trace.go:171","msg":"trace[310334379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"774.710237ms","start":"2026-07-02T05:02:08.037354Z","end":"2026-07-02T05:02:08.812064Z","steps":["trace[310334379] 'agreement among raft nodes before linearized reading' (duration: 774.599592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:08.81211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:08.037037Z","time spent":"775.066145ms","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:02:08.812271Z","caller":"traceutil/trace.go:171","msg":"trace[18291224] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"354.233323ms","start":"2026-07-02T05:02:08.458028Z","end":"2026-07-02T05:02:08.812261Z","steps":["trace[18291224] 'process raft request' (duration: 353.795971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:08.812335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:08.457995Z","time spent":"354.304947ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:11.752768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.092592ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-02T05:02:12.911451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220900327337757,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:02:13.148371Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.093589888s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T05:02:13.148972Z","caller":"traceutil/trace.go:171","msg":"trace[1020317533] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"1.094262029s","start":"2026-07-02T05:02:12.054683Z","end":"2026-07-02T05:02:13.148945Z","steps":["trace[1020317533] 'process raft request' (duration: 1.094046719s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:13.14916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:12.054663Z","time spent":"1.094399725s","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:14.410605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000147463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-02T05:02:14.410678Z","caller":"traceutil/trace.go:171","msg":"trace[1107712592] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000277819s","start":"2026-07-02T05:02:12.410384Z","end":"2026-07-02T05:02:14.410662Z","steps":["trace[1107712592] 'agreement among raft nodes before linearized reading' (duration: 2.000183494s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:14.410714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:12.410365Z","time spent":"2.000340482s","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/02 05:02:14 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-02T05:02:14.55482Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.406058742s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T05:02:14.555435Z","caller":"traceutil/trace.go:171","msg":"trace[1955876334] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:491; }","duration":"2.144955735s","start":"2026-07-02T05:02:12.410456Z","end":"2026-07-02T05:02:14.555412Z","steps":["trace[1955876334] 'read index received' (duration: 738.238982ms)","trace[1955876334] 'applied index is now lower than readState.Index' (duration: 1.406715683s)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:14.555656Z","caller":"traceutil/trace.go:171","msg":"trace[1337334142] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"2.500465107s","start":"2026-07-02T05:02:12.05518Z","end":"2026-07-02T05:02:14.555645Z","steps":["trace[1337334142] 'process raft request' (duration: 2.499838319s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:14.555775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:12.055144Z","time spent":"2.500541801s","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:02:14.555975Z","caller":"traceutil/trace.go:171","msg":"trace[1710772404] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"2.499699052s","start":"2026-07-02T05:02:12.05625Z","end":"2026-07-02T05:02:14.555949Z","steps":["trace[1710772404] 'process raft request' (duration: 2.499105014s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:14.555999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.724094542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-02T05:02:14.556057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.115908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:14.55606Z","caller":"traceutil/trace.go:171","msg":"trace[1428599625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"1.724206527s","start":"2026-07-02T05:02:12.831837Z","end":"2026-07-02T05:02:14.556044Z","steps":["trace[1428599625] 'agreement among raft nodes before linearized reading' (duration: 1.724097573s)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:14.556092Z","caller":"traceutil/trace.go:171","msg":"trace[894000872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"145.195171ms","start":"2026-07-02T05:02:14.410885Z","end":"2026-07-02T05:02:14.55608Z","steps":["trace[894000872] 'agreement among raft nodes before linearized reading' (duration: 145.142109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:14.556096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:12.831801Z","time spent":"1.724287441s","remote":"127.0.0.1:59018","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-07-02T05:02:14.5561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:12.056236Z","time spent":"2.499809267s","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:14.828252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.134231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:02:14.828512Z","caller":"traceutil/trace.go:171","msg":"trace[791347239] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:494; }","duration":"265.618003ms","start":"2026-07-02T05:02:14.56287Z","end":"2026-07-02T05:02:14.828488Z","steps":["trace[791347239] 'read index received' (duration: 117.98286ms)","trace[791347239] 'applied index is now lower than readState.Index' (duration: 147.633853ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:14.828781Z","caller":"traceutil/trace.go:171","msg":"trace[1189631370] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"268.618501ms","start":"2026-07-02T05:02:14.559997Z","end":"2026-07-02T05:02:14.828615Z","steps":["trace[1189631370] 'process raft request' (duration: 120.982597ms)","trace[1189631370] 'compare' (duration: 146.940932ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:14.828927Z","caller":"traceutil/trace.go:171","msg":"trace[2141541391] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"268.221183ms","start":"2026-07-02T05:02:14.560496Z","end":"2026-07-02T05:02:14.828717Z","steps":["trace[2141541391] 'process raft request' (duration: 267.906919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:14.829585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.671431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:14.829689Z","caller":"traceutil/trace.go:171","msg":"trace[687688129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"266.825818ms","start":"2026-07-02T05:02:14.562838Z","end":"2026-07-02T05:02:14.829664Z","steps":["trace[687688129] 'agreement among raft nodes before linearized reading' (duration: 265.731698ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:15.295348Z","caller":"traceutil/trace.go:171","msg":"trace[1788666751] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"138.838772ms","start":"2026-07-02T05:02:15.156485Z","end":"2026-07-02T05:02:15.295323Z","steps":["trace[1788666751] 'process raft request' (duration: 138.693615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.913535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220900327337872,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T05:02:22.929344Z","caller":"traceutil/trace.go:171","msg":"trace[547118957] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"544.111418ms","start":"2026-07-02T05:02:22.385195Z","end":"2026-07-02T05:02:22.929306Z","steps":["trace[547118957] 'process raft request' (duration: 543.826476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.92957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.38517Z","time spent":"544.301736ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:02:22.939993Z","caller":"traceutil/trace.go:171","msg":"trace[1081150488] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"553.73078ms","start":"2026-07-02T05:02:22.386237Z","end":"2026-07-02T05:02:22.939967Z","steps":["trace[1081150488] 'process raft request' (duration: 553.562723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.940168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.386208Z","time spent":"553.865275ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:02:22.94039Z","caller":"traceutil/trace.go:171","msg":"trace[505826322] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"539.192942ms","start":"2026-07-02T05:02:22.401172Z","end":"2026-07-02T05:02:22.940365Z","steps":["trace[505826322] 'process raft request' (duration: 538.816887ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:22.940475Z","caller":"traceutil/trace.go:171","msg":"trace[286049004] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:547; }","duration":"527.456882ms","start":"2026-07-02T05:02:22.413005Z","end":"2026-07-02T05:02:22.940462Z","steps":["trace[286049004] 'read index received' (duration: 516.063105ms)","trace[286049004] 'applied index is now lower than readState.Index' (duration: 11.393247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:02:22.940516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.40116Z","time spent":"539.297058ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:02:22.940535Z","caller":"traceutil/trace.go:171","msg":"trace[798886869] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"539.302708ms","start":"2026-07-02T05:02:22.401226Z","end":"2026-07-02T05:02:22.940529Z","steps":["trace[798886869] 'process raft request' (duration: 538.795376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.940576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.401221Z","time spent":"539.331549ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:02:22.940634Z","caller":"traceutil/trace.go:171","msg":"trace[1830452267] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"511.284236ms","start":"2026-07-02T05:02:22.429334Z","end":"2026-07-02T05:02:22.940619Z","steps":["trace[1830452267] 'process raft request' (duration: 510.981982ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:22.940714Z","caller":"traceutil/trace.go:171","msg":"trace[1881972475] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"546.941417ms","start":"2026-07-02T05:02:22.393767Z","end":"2026-07-02T05:02:22.940709Z","steps":["trace[1881972475] 'process raft request' (duration: 546.160294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.940748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.393745Z","time spent":"546.986699ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:02:22.940815Z","caller":"traceutil/trace.go:171","msg":"trace[722420824] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"498.748821ms","start":"2026-07-02T05:02:22.442038Z","end":"2026-07-02T05:02:22.940787Z","steps":["trace[722420824] 'process raft request' (duration: 498.328112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.940845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.429306Z","time spent":"511.419861ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:02:22.940862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.442007Z","time spent":"498.836085ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:02:22.940921Z","caller":"traceutil/trace.go:171","msg":"trace[1471560696] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"538.883421ms","start":"2026-07-02T05:02:22.402021Z","end":"2026-07-02T05:02:22.940904Z","steps":["trace[1471560696] 'process raft request' (duration: 538.032044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.940974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.402002Z","time spent":"538.947943ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:02:22.940997Z","caller":"traceutil/trace.go:171","msg":"trace[1665678010] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"398.144233ms","start":"2026-07-02T05:02:22.542845Z","end":"2026-07-02T05:02:22.940989Z","steps":["trace[1665678010] 'process raft request' (duration: 397.573309ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:22.941044Z","caller":"traceutil/trace.go:171","msg":"trace[2103497964] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"523.83055ms","start":"2026-07-02T05:02:22.417207Z","end":"2026-07-02T05:02:22.941038Z","steps":["trace[2103497964] 'process raft request' (duration: 522.87744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.941073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.542806Z","time spent":"398.232027ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:02:22.94108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.417188Z","time spent":"523.876972ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:02:22.94069Z","caller":"traceutil/trace.go:171","msg":"trace[1593902050] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"552.071812ms","start":"2026-07-02T05:02:22.38859Z","end":"2026-07-02T05:02:22.940662Z","steps":["trace[1593902050] 'process raft request' (duration: 551.300709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.941171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.158991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:22.941214Z","caller":"traceutil/trace.go:171","msg":"trace[1828814698] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"539.802989ms","start":"2026-07-02T05:02:22.401153Z","end":"2026-07-02T05:02:22.940956Z","steps":["trace[1828814698] 'process raft request' (duration: 538.804716ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:22.941357Z","caller":"traceutil/trace.go:171","msg":"trace[572599006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"528.212393ms","start":"2026-07-02T05:02:22.412979Z","end":"2026-07-02T05:02:22.941191Z","steps":["trace[572599006] 'agreement among raft nodes before linearized reading' (duration: 527.826847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.94139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.412962Z","time spent":"528.422052ms","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:02:22.941582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.388575Z","time spent":"552.723448ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:02:22.941864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.40113Z","time spent":"540.222736ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:02:22.950554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.27642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T05:02:22.950628Z","caller":"traceutil/trace.go:171","msg":"trace[1073216186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"355.399486ms","start":"2026-07-02T05:02:22.595219Z","end":"2026-07-02T05:02:22.950618Z","steps":["trace[1073216186] 'agreement among raft nodes before linearized reading' (duration: 354.883044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.950656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.595196Z","time spent":"355.448478ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:02:22.950858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.236237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T05:02:22.950952Z","caller":"traceutil/trace.go:171","msg":"trace[422610369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"349.363552ms","start":"2026-07-02T05:02:22.601556Z","end":"2026-07-02T05:02:22.95092Z","steps":["trace[422610369] 'agreement among raft nodes before linearized reading' (duration: 348.530077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:22.951008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:22.601535Z","time spent":"349.464376ms","remote":"127.0.0.1:59018","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-07-02T05:02:29.347713Z","caller":"traceutil/trace.go:171","msg":"trace[1134139078] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"403.656155ms","start":"2026-07-02T05:02:28.944016Z","end":"2026-07-02T05:02:29.347672Z","steps":["trace[1134139078] 'process raft request' (duration: 402.755889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:29.347932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:28.94398Z","time spent":"403.836342ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:29.348043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.080274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T05:02:29.348146Z","caller":"traceutil/trace.go:171","msg":"trace[1412021284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"307.264021ms","start":"2026-07-02T05:02:29.040865Z","end":"2026-07-02T05:02:29.348129Z","steps":["trace[1412021284] 'agreement among raft nodes before linearized reading' (duration: 306.911398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:29.348228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.040839Z","time spent":"307.378136ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T05:02:29.347725Z","caller":"traceutil/trace.go:171","msg":"trace[1530295531] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"306.682379ms","start":"2026-07-02T05:02:29.040919Z","end":"2026-07-02T05:02:29.347602Z","steps":["trace[1530295531] 'read index received' (duration: 305.42646ms)","trace[1530295531] 'applied index is now lower than readState.Index' (duration: 1.254309ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:02:29.521836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.890711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:02:29.522017Z","caller":"traceutil/trace.go:171","msg":"trace[221631651] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"168.773109ms","start":"2026-07-02T05:02:29.353228Z","end":"2026-07-02T05:02:29.522001Z","steps":["trace[221631651] 'process raft request' (duration: 168.726967ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:29.522087Z","caller":"traceutil/trace.go:171","msg":"trace[1366377953] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"173.674778ms","start":"2026-07-02T05:02:29.34839Z","end":"2026-07-02T05:02:29.522065Z","steps":["trace[1366377953] 'read index received' (duration: 37.514287ms)","trace[1366377953] 'applied index is now lower than readState.Index' (duration: 136.158531ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:02:29.522318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.968508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bdv72\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-02T05:02:29.52236Z","caller":"traceutil/trace.go:171","msg":"trace[1251544964] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bdv72; range_end:; response_count:1; response_revision:634; }","duration":"244.050382ms","start":"2026-07-02T05:02:29.2783Z","end":"2026-07-02T05:02:29.52235Z","steps":["trace[1251544964] 'agreement among raft nodes before linearized reading' (duration: 243.859135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:29.522558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.358546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T05:02:29.522655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.98041ms","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-07-02T05:02:29.52266Z","caller":"traceutil/trace.go:171","msg":"trace[1130331080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"480.488921ms","start":"2026-07-02T05:02:29.042148Z","end":"2026-07-02T05:02:29.522637Z","steps":["trace[1130331080] 'agreement among raft nodes before linearized reading' (duration: 480.175229ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:29.522745Z","caller":"traceutil/trace.go:171","msg":"trace[1082105672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"481.152747ms","start":"2026-07-02T05:02:29.041578Z","end":"2026-07-02T05:02:29.522731Z","steps":["trace[1082105672] 'agreement among raft nodes before linearized reading' (duration: 480.97499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:29.522783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.041533Z","time spent":"481.2422ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:02:29.522798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.042138Z","time spent":"480.612475ms","remote":"127.0.0.1:59018","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-07-02T05:02:29.52261Z","caller":"traceutil/trace.go:171","msg":"trace[1233930472] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"383.553443ms","start":"2026-07-02T05:02:29.138859Z","end":"2026-07-02T05:02:29.522413Z","steps":["trace[1233930472] 'process raft request' (duration: 246.998136ms)","trace[1233930472] 'compare' (duration: 135.770467ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:02:29.523221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.138825Z","time spent":"384.183837ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:02:29.838787Z","caller":"traceutil/trace.go:171","msg":"trace[1383437598] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"316.61691ms","start":"2026-07-02T05:02:29.522132Z","end":"2026-07-02T05:02:29.838749Z","steps":["trace[1383437598] 'read index received' (duration: 263.547723ms)","trace[1383437598] 'applied index is now lower than readState.Index' (duration: 53.067657ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:29.838865Z","caller":"traceutil/trace.go:171","msg":"trace[810700697] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"433.925114ms","start":"2026-07-02T05:02:29.404903Z","end":"2026-07-02T05:02:29.838828Z","steps":["trace[810700697] 'process raft request' (duration: 380.756574ms)","trace[810700697] 'compare' (duration: 52.707213ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:02:29.839017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.404882Z","time spent":"434.053719ms","remote":"127.0.0.1:59258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:02:29.839003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.07254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:29.839096Z","caller":"traceutil/trace.go:171","msg":"trace[705483856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"427.205484ms","start":"2026-07-02T05:02:29.411864Z","end":"2026-07-02T05:02:29.839069Z","steps":["trace[705483856] 'agreement among raft nodes before linearized reading' (duration: 426.972526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:29.839139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.411845Z","time spent":"427.285258ms","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:02:29.839212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.791465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T05:02:29.839283Z","caller":"traceutil/trace.go:171","msg":"trace[1008780061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"435.89991ms","start":"2026-07-02T05:02:29.403366Z","end":"2026-07-02T05:02:29.839266Z","steps":["trace[1008780061] 'agreement among raft nodes before linearized reading' (duration: 435.526626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:29.839349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.403341Z","time spent":"435.997794ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T05:02:29.853222Z","caller":"traceutil/trace.go:171","msg":"trace[1851635674] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"318.111378ms","start":"2026-07-02T05:02:29.535095Z","end":"2026-07-02T05:02:29.853206Z","steps":["trace[1851635674] 'process raft request' (duration: 318.051856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:29.853358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.535085Z","time spent":"318.203601ms","remote":"127.0.0.1:45054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:02:29.853602Z","caller":"traceutil/trace.go:171","msg":"trace[882594633] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"325.69963ms","start":"2026-07-02T05:02:29.527887Z","end":"2026-07-02T05:02:29.853587Z","steps":["trace[882594633] 'process raft request' (duration: 325.226592ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:29.853584Z","caller":"traceutil/trace.go:171","msg":"trace[971889199] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"326.083256ms","start":"2026-07-02T05:02:29.527406Z","end":"2026-07-02T05:02:29.853489Z","steps":["trace[971889199] 'process raft request' (duration: 325.583657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:29.853656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.527879Z","time spent":"325.753852ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:29.853741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.527387Z","time spent":"326.271182ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:02:30.286304Z","caller":"traceutil/trace.go:171","msg":"trace[962503450] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:662; }","duration":"435.955382ms","start":"2026-07-02T05:02:29.850316Z","end":"2026-07-02T05:02:30.286271Z","steps":["trace[962503450] 'read index received' (duration: 2.679584ms)","trace[962503450] 'applied index is now lower than readState.Index' (duration: 433.274138ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:30.286347Z","caller":"traceutil/trace.go:171","msg":"trace[1427948224] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"440.763837ms","start":"2026-07-02T05:02:29.84555Z","end":"2026-07-02T05:02:30.286314Z","steps":["trace[1427948224] 'process raft request' (duration: 440.552169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:30.286478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.134528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:30.286548Z","caller":"traceutil/trace.go:171","msg":"trace[2038974521] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:639; }","duration":"436.256013ms","start":"2026-07-02T05:02:29.850275Z","end":"2026-07-02T05:02:30.286531Z","steps":["trace[2038974521] 'agreement among raft nodes before linearized reading' (duration: 436.129478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:30.286627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.845516Z","time spent":"440.951944ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:30.28672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:29.850265Z","time spent":"436.43399ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-02T05:02:31.914041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220900327338062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T05:02:32.145953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.455216471s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T05:02:32.147508Z","caller":"traceutil/trace.go:171","msg":"trace[1018029019] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.459382758s","start":"2026-07-02T05:02:30.688093Z","end":"2026-07-02T05:02:32.147475Z","steps":["trace[1018029019] 'process raft request' (duration: 1.458397012s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:32.147695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:30.688043Z","time spent":"1.459555344s","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:02:32.167711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.317302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:32.167679Z","caller":"traceutil/trace.go:171","msg":"trace[1906567132] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"754.188117ms","start":"2026-07-02T05:02:31.413366Z","end":"2026-07-02T05:02:32.167555Z","steps":["trace[1906567132] 'read index received' (duration: 732.77033ms)","trace[1906567132] 'applied index is now lower than readState.Index' (duration: 21.416057ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:32.167775Z","caller":"traceutil/trace.go:171","msg":"trace[1477348883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"754.406305ms","start":"2026-07-02T05:02:31.413354Z","end":"2026-07-02T05:02:32.16776Z","steps":["trace[1477348883] 'agreement among raft nodes before linearized reading' (duration: 754.257859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:32.167809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:31.41319Z","time spent":"754.612272ms","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:02:32.16843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.05686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T05:02:32.168468Z","caller":"traceutil/trace.go:171","msg":"trace[1906342936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"641.124394ms","start":"2026-07-02T05:02:31.527336Z","end":"2026-07-02T05:02:32.16846Z","steps":["trace[1906342936] 'agreement among raft nodes before linearized reading' (duration: 640.984919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:32.168494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:31.527322Z","time spent":"641.164335ms","remote":"127.0.0.1:59018","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-07-02T05:02:32.168605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.050381ms","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-07-02T05:02:32.168701Z","caller":"traceutil/trace.go:171","msg":"trace[793582457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"310.191797ms","start":"2026-07-02T05:02:31.858487Z","end":"2026-07-02T05:02:32.168679Z","steps":["trace[793582457] 'agreement among raft nodes before linearized reading' (duration: 309.897136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:32.168741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:31.85848Z","time spent":"310.253079ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:02:32.169537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.443976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:02:32.169596Z","caller":"traceutil/trace.go:171","msg":"trace[2049723337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"311.580018ms","start":"2026-07-02T05:02:31.858002Z","end":"2026-07-02T05:02:32.169582Z","steps":["trace[2049723337] 'agreement among raft nodes before linearized reading' (duration: 310.385754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:32.169628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:31.857981Z","time spent":"311.6375ms","remote":"127.0.0.1:59018","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-07-02T05:02:37.745554Z","caller":"traceutil/trace.go:171","msg":"trace[1052517759] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:747; }","duration":"333.027773ms","start":"2026-07-02T05:02:37.412499Z","end":"2026-07-02T05:02:37.745527Z","steps":["trace[1052517759] 'read index received' (duration: 14.41833ms)","trace[1052517759] 'applied index is now lower than readState.Index' (duration: 318.604953ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:37.745966Z","caller":"traceutil/trace.go:171","msg":"trace[1380253205] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"397.913191ms","start":"2026-07-02T05:02:37.347997Z","end":"2026-07-02T05:02:37.74591Z","steps":["trace[1380253205] 'process raft request' (duration: 397.360891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:37.746019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.472139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:37.746117Z","caller":"traceutil/trace.go:171","msg":"trace[410607722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"333.626755ms","start":"2026-07-02T05:02:37.412469Z","end":"2026-07-02T05:02:37.746096Z","steps":["trace[410607722] 'agreement among raft nodes before linearized reading' (duration: 333.411636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:37.746167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:37.412459Z","time spent":"333.698287ms","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:02:37.746226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:02:37.347976Z","time spent":"398.122719ms","remote":"127.0.0.1:45054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18497,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:02:37.786Z","caller":"traceutil/trace.go:171","msg":"trace[1156281783] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"245.639031ms","start":"2026-07-02T05:02:37.540327Z","end":"2026-07-02T05:02:37.785966Z","steps":["trace[1156281783] 'process raft request' (duration: 245.33159ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:44.41816Z","caller":"traceutil/trace.go:171","msg":"trace[1016023130] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"155.617466ms","start":"2026-07-02T05:02:44.262516Z","end":"2026-07-02T05:02:44.418133Z","steps":["trace[1016023130] 'read index received' (duration: 155.397009ms)","trace[1016023130] 'applied index is now lower than readState.Index' (duration: 219.157µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:02:44.418388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.846694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-02T05:02:44.418456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.024783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:02:44.41848Z","caller":"traceutil/trace.go:171","msg":"trace[1475197339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"155.970078ms","start":"2026-07-02T05:02:44.262488Z","end":"2026-07-02T05:02:44.418458Z","steps":["trace[1475197339] 'agreement among raft nodes before linearized reading' (duration: 155.756991ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:44.418496Z","caller":"traceutil/trace.go:171","msg":"trace[1634416408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"146.116406ms","start":"2026-07-02T05:02:44.272368Z","end":"2026-07-02T05:02:44.418484Z","steps":["trace[1634416408] 'agreement among raft nodes before linearized reading' (duration: 145.93542ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:44.418347Z","caller":"traceutil/trace.go:171","msg":"trace[405935377] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"165.651684ms","start":"2026-07-02T05:02:44.252557Z","end":"2026-07-02T05:02:44.418209Z","steps":["trace[405935377] 'process raft request' (duration: 165.419486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:47.645369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.704087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:47.645461Z","caller":"traceutil/trace.go:171","msg":"trace[834331348] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:803; }","duration":"285.825911ms","start":"2026-07-02T05:02:47.359615Z","end":"2026-07-02T05:02:47.645441Z","steps":["trace[834331348] 'count revisions from in-memory index tree' (duration: 285.611175ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:02:47.647692Z","caller":"traceutil/trace.go:171","msg":"trace[1769384085] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"234.952871ms","start":"2026-07-02T05:02:47.412721Z","end":"2026-07-02T05:02:47.647674Z","steps":["trace[1769384085] 'read index received' (duration: 234.769155ms)","trace[1769384085] 'applied index is now lower than readState.Index' (duration: 182.706µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:02:47.647761Z","caller":"traceutil/trace.go:171","msg":"trace[530041060] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"238.635296ms","start":"2026-07-02T05:02:47.40908Z","end":"2026-07-02T05:02:47.647715Z","steps":["trace[530041060] 'process raft request' (duration: 238.473131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:02:47.64785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.103915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:02:47.647927Z","caller":"traceutil/trace.go:171","msg":"trace[479342790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"235.286392ms","start":"2026-07-02T05:02:47.412631Z","end":"2026-07-02T05:02:47.647917Z","steps":["trace[479342790] 'agreement among raft nodes before linearized reading' (duration: 235.150647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:16.652755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220900327339430,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T05:04:16.69688Z","caller":"traceutil/trace.go:171","msg":"trace[1640318345] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"544.97156ms","start":"2026-07-02T05:04:16.15188Z","end":"2026-07-02T05:04:16.696852Z","steps":["trace[1640318345] 'read index received' (duration: 544.808298ms)","trace[1640318345] 'applied index is now lower than readState.Index' (duration: 162.042µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:04:16.696951Z","caller":"traceutil/trace.go:171","msg":"trace[1217621601] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"733.314978ms","start":"2026-07-02T05:04:15.963598Z","end":"2026-07-02T05:04:16.696913Z","steps":["trace[1217621601] 'process raft request' (duration: 733.055404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:16.697144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.235084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-02T05:04:16.697152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.522467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:04:16.697239Z","caller":"traceutil/trace.go:171","msg":"trace[604661529] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1225; }","duration":"545.375636ms","start":"2026-07-02T05:04:16.151844Z","end":"2026-07-02T05:04:16.69722Z","steps":["trace[604661529] 'agreement among raft nodes before linearized reading' (duration: 545.099371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:16.697226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.099454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:04:16.697294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:16.151828Z","time spent":"545.457187ms","remote":"127.0.0.1:44902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-07-02T05:04:16.697171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:15.96357Z","time spent":"733.48354ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:04:16.697364Z","caller":"traceutil/trace.go:171","msg":"trace[1799273047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"285.283526ms","start":"2026-07-02T05:04:16.412045Z","end":"2026-07-02T05:04:16.697328Z","steps":["trace[1799273047] 'agreement among raft nodes before linearized reading' (duration: 285.004252ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:16.697267Z","caller":"traceutil/trace.go:171","msg":"trace[1308190413] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1225; }","duration":"462.7184ms","start":"2026-07-02T05:04:16.234524Z","end":"2026-07-02T05:04:16.697242Z","steps":["trace[1308190413] 'agreement among raft nodes before linearized reading' (duration: 462.505797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:16.697485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:04:16.234505Z","time spent":"462.963154ms","remote":"127.0.0.1:45010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:04:59.001506Z","caller":"traceutil/trace.go:171","msg":"trace[64240328] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"120.471521ms","start":"2026-07-02T05:04:58.881012Z","end":"2026-07-02T05:04:59.001484Z","steps":["trace[64240328] 'process raft request' (duration: 120.010015ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:59.804896Z","caller":"traceutil/trace.go:171","msg":"trace[2129465444] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"172.665638ms","start":"2026-07-02T05:04:59.632212Z","end":"2026-07-02T05:04:59.804877Z","steps":["trace[2129465444] 'process raft request' (duration: 172.477336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:59.805047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.473438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:04:59.805147Z","caller":"traceutil/trace.go:171","msg":"trace[306828976] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1412; }","duration":"142.616119ms","start":"2026-07-02T05:04:59.662499Z","end":"2026-07-02T05:04:59.805116Z","steps":["trace[306828976] 'agreement among raft nodes before linearized reading' (duration: 142.465967ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:04:59.804845Z","caller":"traceutil/trace.go:171","msg":"trace[1609341741] linearizableReadLoop","detail":"{readStateIndex:1475; appliedIndex:1474; }","duration":"142.280275ms","start":"2026-07-02T05:04:59.662545Z","end":"2026-07-02T05:04:59.804825Z","steps":["trace[1609341741] 'read index received' (duration: 142.032582ms)","trace[1609341741] 'applied index is now lower than readState.Index' (duration: 246.653µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:04:59.805409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.650973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-07-02T05:04:59.805457Z","caller":"traceutil/trace.go:171","msg":"trace[1278965995] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1412; }","duration":"131.732465ms","start":"2026-07-02T05:04:59.673707Z","end":"2026-07-02T05:04:59.805439Z","steps":["trace[1278965995] 'agreement among raft nodes before linearized reading' (duration: 131.645694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:04:59.805613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.272127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:04:59.80565Z","caller":"traceutil/trace.go:171","msg":"trace[827324608] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1412; }","duration":"112.359629ms","start":"2026-07-02T05:04:59.693281Z","end":"2026-07-02T05:04:59.805641Z","steps":["trace[827324608] 'agreement among raft nodes before linearized reading' (duration: 112.299948ms)"],"step_count":1}