{"level":"warn","ts":"2026-07-14T22:50:36.423228Z","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-14T22:50:36.423444Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-14T22:50:36.423539Z","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-14T22:50:36.42356Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-14T22:50:36.423589Z","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-14T22:50:36.424463Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-07-14T22:50:36.424587Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-14T22:50:36.428408Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.559434ms"} {"level":"info","ts":"2026-07-14T22:50:36.434736Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-07-14T22:50:36.434793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T22:50:36.434808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-07-14T22:50:36.434819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T22:50:36.434826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-07-14T22:50:36.434855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-07-14T22:50:36.441428Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T22:50:36.442791Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T22:50:36.443887Z","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-14T22:50:36.445786Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T22:50:36.446038Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T22:50:36.447044Z","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-14T22:50:36.44724Z","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-14T22:50:36.447402Z","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-14T22:50:36.448117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-07-14T22:50:36.44876Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-14T22:50:36.450756Z","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-14T22:50:36.450892Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-14T22:50:36.450957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-14T22:50:36.451275Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T22:50:36.451318Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T22:50:37.036019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T22:50:37.03609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T22:50:37.036126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-07-14T22:50:37.036147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-07-14T22:50:37.036176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-14T22:50:37.036197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-07-14T22:50:37.036215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-14T22:50:37.037371Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:50:37.038301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T22:50:37.038325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T22:50:37.038288Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T22:50:37.039102Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:50:37.039098Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T22:50:37.039177Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T22:50:37.03919Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:50:37.03922Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:50:37.042199Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-07-14T22:50:37.04261Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-14T22:50:39.858238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.554579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-14T22:50:39.8585Z","caller":"traceutil/trace.go:171","msg":"trace[623660546] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"416.203422ms","start":"2026-07-14T22:50:39.442268Z","end":"2026-07-14T22:50:39.858472Z","steps":["trace[623660546] 'process raft request' (duration: 176.751865ms)","trace[623660546] 'compare' (duration: 238.307379ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:39.858619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:39.442255Z","time spent":"416.314472ms","remote":"127.0.0.1:41818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:50:40.511146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.555294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-14T22:50:40.511275Z","caller":"traceutil/trace.go:171","msg":"trace[1638715780] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"646.358632ms","start":"2026-07-14T22:50:39.864875Z","end":"2026-07-14T22:50:40.511234Z","steps":["trace[1638715780] 'process raft request' (duration: 272.641308ms)","trace[1638715780] 'compare' (duration: 373.382095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:40.511332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:39.864846Z","time spent":"646.463302ms","remote":"127.0.0.1:41818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T22:50:46.55546Z","caller":"traceutil/trace.go:171","msg":"trace[62828366] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"119.878536ms","start":"2026-07-14T22:50:46.435487Z","end":"2026-07-14T22:50:46.555366Z","steps":["trace[62828366] 'process raft request' (duration: 119.063708ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:46.922495Z","caller":"traceutil/trace.go:171","msg":"trace[738839168] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:319; }","duration":"284.214685ms","start":"2026-07-14T22:50:46.638264Z","end":"2026-07-14T22:50:46.922479Z","steps":["trace[738839168] 'read index received' (duration: 185.774298ms)","trace[738839168] 'applied index is now lower than readState.Index' (duration: 98.439547ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:46.922623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.327045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-07-14T22:50:46.922652Z","caller":"traceutil/trace.go:171","msg":"trace[126883792] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:309; }","duration":"284.418875ms","start":"2026-07-14T22:50:46.638224Z","end":"2026-07-14T22:50:46.922643Z","steps":["trace[126883792] 'agreement among raft nodes before linearized reading' (duration: 284.335405ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:46.922622Z","caller":"traceutil/trace.go:171","msg":"trace[1431948453] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"285.155615ms","start":"2026-07-14T22:50:46.637401Z","end":"2026-07-14T22:50:46.922557Z","steps":["trace[1431948453] 'process raft request' (duration: 186.622348ms)","trace[1431948453] 'compare' (duration: 98.365997ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:47.473161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.159825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:50:47.47327Z","caller":"traceutil/trace.go:171","msg":"trace[1298811815] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"550.708104ms","start":"2026-07-14T22:50:46.922543Z","end":"2026-07-14T22:50:47.473251Z","steps":["trace[1298811815] 'read index received' (duration: 422.149149ms)","trace[1298811815] 'applied index is now lower than readState.Index' (duration: 128.557575ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:47.473385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.836704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"warn","ts":"2026-07-14T22:50:47.473433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.204931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-07-14T22:50:47.473441Z","caller":"traceutil/trace.go:171","msg":"trace[1238662362] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:310; }","duration":"653.911903ms","start":"2026-07-14T22:50:46.819518Z","end":"2026-07-14T22:50:47.47343Z","steps":["trace[1238662362] 'agreement among raft nodes before linearized reading' (duration: 653.751014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.473429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.278164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-14T22:50:47.473463Z","caller":"traceutil/trace.go:171","msg":"trace[511656169] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:310; }","duration":"690.28058ms","start":"2026-07-14T22:50:46.783173Z","end":"2026-07-14T22:50:47.473454Z","steps":["trace[511656169] 'agreement among raft nodes before linearized reading' (duration: 690.15727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.473457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.564602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-07-14T22:50:47.473469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.819504Z","time spent":"653.958273ms","remote":"127.0.0.1:41626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4346,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-14T22:50:47.473495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.783149Z","time spent":"690.33917ms","remote":"127.0.0.1:41626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7318,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-07-14T22:50:47.473505Z","caller":"traceutil/trace.go:171","msg":"trace[834043720] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:310; }","duration":"654.650732ms","start":"2026-07-14T22:50:46.81884Z","end":"2026-07-14T22:50:47.473491Z","steps":["trace[834043720] 'agreement among raft nodes before linearized reading' (duration: 654.554662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.473549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.81882Z","time spent":"654.718412ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-07-14T22:50:47.473646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.826916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-14T22:50:47.473733Z","caller":"traceutil/trace.go:171","msg":"trace[1943664208] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:310; }","duration":"809.979665ms","start":"2026-07-14T22:50:46.663735Z","end":"2026-07-14T22:50:47.473715Z","steps":["trace[1943664208] 'agreement among raft nodes before linearized reading' (duration: 809.784535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.473739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.419444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-14T22:50:47.473771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.663713Z","time spent":"810.051655ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-07-14T22:50:47.473776Z","caller":"traceutil/trace.go:171","msg":"trace[1135927835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:310; }","duration":"509.490394ms","start":"2026-07-14T22:50:46.964274Z","end":"2026-07-14T22:50:47.473764Z","steps":["trace[1135927835] 'agreement among raft nodes before linearized reading' (duration: 509.427874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.473788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.477117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T22:50:47.473793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.280672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-07-14T22:50:47.473805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.964247Z","time spent":"509.551434ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-07-14T22:50:47.473842Z","caller":"traceutil/trace.go:171","msg":"trace[468546283] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:310; }","duration":"559.367552ms","start":"2026-07-14T22:50:46.914447Z","end":"2026-07-14T22:50:47.473815Z","steps":["trace[468546283] 'agreement among raft nodes before linearized reading' (duration: 559.316182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.473867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.914425Z","time spent":"559.43561ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-07-14T22:50:47.473902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.92336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T22:50:47.47394Z","caller":"traceutil/trace.go:171","msg":"trace[1412775820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:310; }","duration":"449.98277ms","start":"2026-07-14T22:50:47.02395Z","end":"2026-07-14T22:50:47.473932Z","steps":["trace[1412775820] 'agreement among raft nodes before linearized reading' (duration: 449.92478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.473997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:47.023939Z","time spent":"450.04774ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T22:50:47.474025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.080058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-07-14T22:50:47.474046Z","caller":"traceutil/trace.go:171","msg":"trace[367969161] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:310; }","duration":"655.168231ms","start":"2026-07-14T22:50:46.818872Z","end":"2026-07-14T22:50:47.47404Z","steps":["trace[367969161] 'agreement among raft nodes before linearized reading' (duration: 655.112381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.279811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-14T22:50:47.474092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.818856Z","time spent":"655.210981ms","remote":"127.0.0.1:41626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7318,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-07-14T22:50:47.474103Z","caller":"traceutil/trace.go:171","msg":"trace[405663469] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:310; }","duration":"710.352301ms","start":"2026-07-14T22:50:46.763744Z","end":"2026-07-14T22:50:47.474096Z","steps":["trace[405663469] 'agreement among raft nodes before linearized reading' (duration: 710.300421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.763721Z","time spent":"710.397411ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-14T22:50:47.474174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.784937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-14T22:50:47.474215Z","caller":"traceutil/trace.go:171","msg":"trace[665875991] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:310; }","duration":"458.885467ms","start":"2026-07-14T22:50:47.015321Z","end":"2026-07-14T22:50:47.474206Z","steps":["trace[665875991] 'agreement among raft nodes before linearized reading' (duration: 458.813377ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:47.473813Z","caller":"traceutil/trace.go:171","msg":"trace[1710865093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:310; }","duration":"203.552147ms","start":"2026-07-14T22:50:47.270252Z","end":"2026-07-14T22:50:47.473805Z","steps":["trace[1710865093] 'agreement among raft nodes before linearized reading' (duration: 203.512897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:47.015297Z","time spent":"458.957967ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-07-14T22:50:47.47427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.530984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-07-14T22:50:47.474301Z","caller":"traceutil/trace.go:171","msg":"trace[1169763518] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:310; }","duration":"653.632424ms","start":"2026-07-14T22:50:46.820661Z","end":"2026-07-14T22:50:47.474294Z","steps":["trace[1169763518] 'agreement among raft nodes before linearized reading' (duration: 653.535464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.820614Z","time spent":"653.713323ms","remote":"127.0.0.1:41626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5350,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-07-14T22:50:47.473502Z","caller":"traceutil/trace.go:171","msg":"trace[358581501] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:310; }","duration":"660.381194ms","start":"2026-07-14T22:50:46.813101Z","end":"2026-07-14T22:50:47.473482Z","steps":["trace[358581501] 'agreement among raft nodes before linearized reading' (duration: 660.234464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.87113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T22:50:47.473969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.868495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-14T22:50:47.474386Z","caller":"traceutil/trace.go:171","msg":"trace[1550860331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:310; }","duration":"477.94985ms","start":"2026-07-14T22:50:46.996429Z","end":"2026-07-14T22:50:47.474379Z","steps":["trace[1550860331] 'agreement among raft nodes before linearized reading' (duration: 477.90653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.81309Z","time spent":"661.283613ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-07-14T22:50:47.4744Z","caller":"traceutil/trace.go:171","msg":"trace[1801279219] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:310; }","duration":"611.341855ms","start":"2026-07-14T22:50:46.86305Z","end":"2026-07-14T22:50:47.474392Z","steps":["trace[1801279219] 'agreement among raft nodes before linearized reading' (duration: 610.894095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.863026Z","time spent":"611.423744ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-14T22:50:47.474178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.868523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-07-14T22:50:47.474498Z","caller":"traceutil/trace.go:171","msg":"trace[273814768] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:310; }","duration":"654.205562ms","start":"2026-07-14T22:50:46.820285Z","end":"2026-07-14T22:50:47.474491Z","steps":["trace[273814768] 'agreement among raft nodes before linearized reading' (duration: 653.852793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.820273Z","time spent":"654.253522ms","remote":"127.0.0.1:41626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-07-14T22:50:47.47372Z","caller":"traceutil/trace.go:171","msg":"trace[1889068198] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"834.998299ms","start":"2026-07-14T22:50:46.638711Z","end":"2026-07-14T22:50:47.473709Z","steps":["trace[1889068198] 'process raft request' (duration: 706.060495ms)","trace[1889068198] 'compare' (duration: 127.888585ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:50:47.474406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.996399Z","time spent":"478.002979ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T22:50:47.474641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.638678Z","time spent":"835.889007ms","remote":"127.0.0.1:41818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:50:47.473646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.873142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"warn","ts":"2026-07-14T22:50:47.474635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.728396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-14T22:50:47.474775Z","caller":"traceutil/trace.go:171","msg":"trace[669913180] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:310; }","duration":"655.93775ms","start":"2026-07-14T22:50:46.818739Z","end":"2026-07-14T22:50:47.474677Z","steps":["trace[669913180] 'agreement among raft nodes before linearized reading' (duration: 654.790422ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:47.474842Z","caller":"traceutil/trace.go:171","msg":"trace[24187072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:310; }","duration":"761.088416ms","start":"2026-07-14T22:50:46.713722Z","end":"2026-07-14T22:50:47.474811Z","steps":["trace[24187072] 'agreement among raft nodes before linearized reading' (duration: 760.153597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.474979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.713693Z","time spent":"761.271255ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-07-14T22:50:47.474977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.818727Z","time spent":"656.16201ms","remote":"127.0.0.1:41614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4220,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-14T22:50:47.474951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.506161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4659"} {"level":"info","ts":"2026-07-14T22:50:47.475512Z","caller":"traceutil/trace.go:171","msg":"trace[1386166604] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:310; }","duration":"656.10103ms","start":"2026-07-14T22:50:46.819393Z","end":"2026-07-14T22:50:47.475495Z","steps":["trace[1386166604] 'agreement among raft nodes before linearized reading' (duration: 654.065713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.475578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:50:46.819346Z","time spent":"656.21953ms","remote":"127.0.0.1:41626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4683,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-07-14T22:50:47.618358Z","caller":"traceutil/trace.go:171","msg":"trace[1107629289] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"129.787402ms","start":"2026-07-14T22:50:47.488511Z","end":"2026-07-14T22:50:47.618298Z","steps":["trace[1107629289] 'process raft request' (duration: 86.540895ms)","trace[1107629289] 'compare' (duration: 42.905188ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:50:47.618723Z","caller":"traceutil/trace.go:171","msg":"trace[1951278613] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"128.559165ms","start":"2026-07-14T22:50:47.490143Z","end":"2026-07-14T22:50:47.618702Z","steps":["trace[1951278613] 'process raft request' (duration: 127.977645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.619308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.781379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-07-14T22:50:47.619428Z","caller":"traceutil/trace.go:171","msg":"trace[174495871] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:324; }","duration":"132.958378ms","start":"2026-07-14T22:50:47.486441Z","end":"2026-07-14T22:50:47.619399Z","steps":["trace[174495871] 'agreement among raft nodes before linearized reading' (duration: 132.543159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.619655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.550228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"warn","ts":"2026-07-14T22:50:47.619692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.091877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-07-14T22:50:47.61973Z","caller":"traceutil/trace.go:171","msg":"trace[527861343] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:325; }","duration":"133.766157ms","start":"2026-07-14T22:50:47.485946Z","end":"2026-07-14T22:50:47.619712Z","steps":["trace[527861343] 'agreement among raft nodes before linearized reading' (duration: 133.273778ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:47.619773Z","caller":"traceutil/trace.go:171","msg":"trace[1320913565] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:325; }","duration":"134.251307ms","start":"2026-07-14T22:50:47.4855Z","end":"2026-07-14T22:50:47.619752Z","steps":["trace[1320913565] 'agreement among raft nodes before linearized reading' (duration: 133.798727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.619935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.363646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-07-14T22:50:47.61999Z","caller":"traceutil/trace.go:171","msg":"trace[1380555357] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:325; }","duration":"134.438326ms","start":"2026-07-14T22:50:47.485538Z","end":"2026-07-14T22:50:47.619976Z","steps":["trace[1380555357] 'agreement among raft nodes before linearized reading' (duration: 133.650108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:50:47.620043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.383126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-07-14T22:50:47.620161Z","caller":"traceutil/trace.go:171","msg":"trace[1386919296] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:325; }","duration":"134.519966ms","start":"2026-07-14T22:50:47.485624Z","end":"2026-07-14T22:50:47.620144Z","steps":["trace[1386919296] 'agreement among raft nodes before linearized reading' (duration: 133.628508ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:50:59.018895Z","caller":"traceutil/trace.go:171","msg":"trace[932588026] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"279.847178ms","start":"2026-07-14T22:50:58.739024Z","end":"2026-07-14T22:50:59.018871Z","steps":["trace[932588026] 'process raft request' (duration: 279.673288ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:16.090281Z","caller":"traceutil/trace.go:171","msg":"trace[1472130844] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"186.954917ms","start":"2026-07-14T22:51:15.903309Z","end":"2026-07-14T22:51:16.090263Z","steps":["trace[1472130844] 'process raft request' (duration: 186.81975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.42458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.674139ms","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-14T22:51:16.424665Z","caller":"traceutil/trace.go:171","msg":"trace[1520012637] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"521.263631ms","start":"2026-07-14T22:51:15.903366Z","end":"2026-07-14T22:51:16.42463Z","steps":["trace[1520012637] 'process raft request' (duration: 516.24243ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:16.424718Z","caller":"traceutil/trace.go:171","msg":"trace[1344549199] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:488; }","duration":"219.821417ms","start":"2026-07-14T22:51:16.204871Z","end":"2026-07-14T22:51:16.424693Z","steps":["trace[1344549199] 'range keys from in-memory index tree' (duration: 219.657688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.424802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.903338Z","time spent":"521.397088ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T22:51:16.42497Z","caller":"traceutil/trace.go:171","msg":"trace[1128551041] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:505; }","duration":"408.358692ms","start":"2026-07-14T22:51:16.016591Z","end":"2026-07-14T22:51:16.42495Z","steps":["trace[1128551041] 'read index received' (duration: 73.90831ms)","trace[1128551041] 'applied index is now lower than readState.Index' (duration: 334.449342ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:51:16.424983Z","caller":"traceutil/trace.go:171","msg":"trace[1210595613] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"502.138162ms","start":"2026-07-14T22:51:15.922809Z","end":"2026-07-14T22:51:16.424947Z","steps":["trace[1210595613] 'process raft request' (duration: 501.866079ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:16.425059Z","caller":"traceutil/trace.go:171","msg":"trace[2020812277] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"493.190099ms","start":"2026-07-14T22:51:15.93186Z","end":"2026-07-14T22:51:16.42505Z","steps":["trace[2020812277] 'process raft request' (duration: 492.900674ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:16.425045Z","caller":"traceutil/trace.go:171","msg":"trace[1749565642] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"509.763108ms","start":"2026-07-14T22:51:15.915228Z","end":"2026-07-14T22:51:16.424991Z","steps":["trace[1749565642] 'process raft request' (duration: 509.388618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.425121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.922806Z","time spent":"502.248968ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:16.425143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.93183Z","time spent":"493.250031ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:16.425196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.9152Z","time spent":"509.927245ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T22:51:16.425278Z","caller":"traceutil/trace.go:171","msg":"trace[1083331875] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"502.49644ms","start":"2026-07-14T22:51:15.92276Z","end":"2026-07-14T22:51:16.425257Z","steps":["trace[1083331875] 'process raft request' (duration: 501.884679ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:16.425316Z","caller":"traceutil/trace.go:171","msg":"trace[869995867] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"465.501236ms","start":"2026-07-14T22:51:15.95981Z","end":"2026-07-14T22:51:16.425311Z","steps":["trace[869995867] 'process raft request' (duration: 465.099576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.425353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.959791Z","time spent":"465.543748ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:16.42535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.922746Z","time spent":"502.569114ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T22:51:16.425294Z","caller":"traceutil/trace.go:171","msg":"trace[497405389] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"488.071368ms","start":"2026-07-14T22:51:15.937213Z","end":"2026-07-14T22:51:16.425284Z","steps":["trace[497405389] 'process raft request' (duration: 487.620676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.425411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.93718Z","time spent":"488.219956ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T22:51:16.425401Z","caller":"traceutil/trace.go:171","msg":"trace[1539457983] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"502.034256ms","start":"2026-07-14T22:51:15.923302Z","end":"2026-07-14T22:51:16.425336Z","steps":["trace[1539457983] 'process raft request' (duration: 501.401174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.425434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.830625ms","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-14T22:51:16.425449Z","caller":"traceutil/trace.go:171","msg":"trace[1777404311] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"515.54145ms","start":"2026-07-14T22:51:15.909896Z","end":"2026-07-14T22:51:16.425437Z","steps":["trace[1777404311] 'process raft request' (duration: 514.686296ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:16.425474Z","caller":"traceutil/trace.go:171","msg":"trace[1148736806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"408.903799ms","start":"2026-07-14T22:51:16.016558Z","end":"2026-07-14T22:51:16.425462Z","steps":["trace[1148736806] 'agreement among raft nodes before linearized reading' (duration: 408.784273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.425492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.909885Z","time spent":"515.587332ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:16.425495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.923279Z","time spent":"502.165952ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:16.425512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:16.016545Z","time spent":"408.960762ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T22:51:16.425632Z","caller":"traceutil/trace.go:171","msg":"trace[1480229257] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"499.520239ms","start":"2026-07-14T22:51:15.925425Z","end":"2026-07-14T22:51:16.424945Z","steps":["trace[1480229257] 'process raft request' (duration: 499.304129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.425739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:15.925419Z","time spent":"500.266437ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:16.431602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.989978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-14T22:51:16.431642Z","caller":"traceutil/trace.go:171","msg":"trace[435668936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"181.074262ms","start":"2026-07-14T22:51:16.250558Z","end":"2026-07-14T22:51:16.431633Z","steps":["trace[435668936] 'agreement among raft nodes before linearized reading' (duration: 180.990858ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:16.431738Z","caller":"traceutil/trace.go:171","msg":"trace[1228660587] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"335.02051ms","start":"2026-07-14T22:51:16.096704Z","end":"2026-07-14T22:51:16.431724Z","steps":["trace[1228660587] 'process raft request' (duration: 334.738926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.431795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:16.096696Z","time spent":"335.075353ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T22:51:16.431868Z","caller":"traceutil/trace.go:171","msg":"trace[1579950696] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"333.750196ms","start":"2026-07-14T22:51:16.098097Z","end":"2026-07-14T22:51:16.431847Z","steps":["trace[1579950696] 'process raft request' (duration: 333.414259ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:16.431994Z","caller":"traceutil/trace.go:171","msg":"trace[869291970] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"335.551426ms","start":"2026-07-14T22:51:16.096435Z","end":"2026-07-14T22:51:16.431986Z","steps":["trace[869291970] 'process raft request' (duration: 334.914984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.432044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:16.096416Z","time spent":"335.609368ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:16.432068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.472153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:51:16.432092Z","caller":"traceutil/trace.go:171","msg":"trace[1222947573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"159.530096ms","start":"2026-07-14T22:51:16.272555Z","end":"2026-07-14T22:51:16.432085Z","steps":["trace[1222947573] 'agreement among raft nodes before linearized reading' (duration: 159.491325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:16.432078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:16.098092Z","time spent":"333.872972ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:51:20.301181Z","caller":"traceutil/trace.go:171","msg":"trace[494613332] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"250.745555ms","start":"2026-07-14T22:51:20.050401Z","end":"2026-07-14T22:51:20.301146Z","steps":["trace[494613332] 'process raft request' (duration: 250.172338ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:20.834137Z","caller":"traceutil/trace.go:171","msg":"trace[1415367934] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"366.870127ms","start":"2026-07-14T22:51:20.467239Z","end":"2026-07-14T22:51:20.834109Z","steps":["trace[1415367934] 'read index received' (duration: 366.286469ms)","trace[1415367934] 'applied index is now lower than readState.Index' (duration: 581.938µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:51:20.834319Z","caller":"traceutil/trace.go:171","msg":"trace[339678247] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"469.661619ms","start":"2026-07-14T22:51:20.364638Z","end":"2026-07-14T22:51:20.8343Z","steps":["trace[339678247] 'process raft request' (duration: 468.953825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:20.834552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.303578ms","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-14T22:51:20.834636Z","caller":"traceutil/trace.go:171","msg":"trace[260417581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"367.429953ms","start":"2026-07-14T22:51:20.467184Z","end":"2026-07-14T22:51:20.834614Z","steps":["trace[260417581] 'agreement among raft nodes before linearized reading' (duration: 367.209233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:20.834653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:20.364609Z","time spent":"469.938923ms","remote":"127.0.0.1:41796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:20.834683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:20.467155Z","time spent":"367.518818ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T22:51:20.836698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.737634ms","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-14T22:51:20.836754Z","caller":"traceutil/trace.go:171","msg":"trace[892194365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"358.822508ms","start":"2026-07-14T22:51:20.477917Z","end":"2026-07-14T22:51:20.83674Z","steps":["trace[892194365] 'agreement among raft nodes before linearized reading' (duration: 356.992282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:20.836785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:20.477907Z","time spent":"358.87261ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T22:51:23.43406Z","caller":"traceutil/trace.go:171","msg":"trace[423992955] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"163.234359ms","start":"2026-07-14T22:51:23.270798Z","end":"2026-07-14T22:51:23.434033Z","steps":["trace[423992955] 'read index received' (duration: 162.443133ms)","trace[423992955] 'applied index is now lower than readState.Index' (duration: 789.816µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:51:23.434283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.459269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:51:23.434346Z","caller":"traceutil/trace.go:171","msg":"trace[436376536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"163.554824ms","start":"2026-07-14T22:51:23.270775Z","end":"2026-07-14T22:51:23.434329Z","steps":["trace[436376536] 'agreement among raft nodes before linearized reading' (duration: 163.441059ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:23.434211Z","caller":"traceutil/trace.go:171","msg":"trace[457887026] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"257.029085ms","start":"2026-07-14T22:51:23.177161Z","end":"2026-07-14T22:51:23.434191Z","steps":["trace[457887026] 'process raft request' (duration: 256.338653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:24.220907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.329016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:51:24.221021Z","caller":"traceutil/trace.go:171","msg":"trace[458160540] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"514.342454ms","start":"2026-07-14T22:51:23.706666Z","end":"2026-07-14T22:51:24.221009Z","steps":["trace[458160540] 'read index received' (duration: 364.81642ms)","trace[458160540] 'applied index is now lower than readState.Index' (duration: 149.524864ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:51:24.221168Z","caller":"traceutil/trace.go:171","msg":"trace[1354740662] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"807.960376ms","start":"2026-07-14T22:51:23.413198Z","end":"2026-07-14T22:51:24.221158Z","steps":["trace[1354740662] 'process raft request' (duration: 658.307115ms)","trace[1354740662] 'compare' (duration: 149.243911ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:51:24.221212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:23.413119Z","time spent":"808.072431ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:51:24.221388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.456716ms","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-14T22:51:24.221413Z","caller":"traceutil/trace.go:171","msg":"trace[1330992836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"513.499798ms","start":"2026-07-14T22:51:23.707906Z","end":"2026-07-14T22:51:24.221405Z","steps":["trace[1330992836] 'agreement among raft nodes before linearized reading' (duration: 513.415654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:24.221433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:23.707893Z","time spent":"513.535799ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T22:51:24.221678Z","caller":"traceutil/trace.go:171","msg":"trace[1345201094] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"729.570657ms","start":"2026-07-14T22:51:23.492079Z","end":"2026-07-14T22:51:24.221649Z","steps":["trace[1345201094] 'process raft request' (duration: 728.900047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:24.221795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:23.491987Z","time spent":"729.757626ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:51:24.221724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.038176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T22:51:24.222069Z","caller":"traceutil/trace.go:171","msg":"trace[1759349808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"515.310868ms","start":"2026-07-14T22:51:23.706625Z","end":"2026-07-14T22:51:24.221936Z","steps":["trace[1759349808] 'agreement among raft nodes before linearized reading' (duration: 514.586296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:24.222152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:23.706604Z","time spent":"515.534388ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T22:51:24.456045Z","caller":"traceutil/trace.go:171","msg":"trace[1644453913] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"229.86685ms","start":"2026-07-14T22:51:24.226161Z","end":"2026-07-14T22:51:24.456028Z","steps":["trace[1644453913] 'process raft request' (duration: 190.42474ms)","trace[1644453913] 'compare' (duration: 39.308564ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:51:24.456182Z","caller":"traceutil/trace.go:171","msg":"trace[8184221] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"226.28535ms","start":"2026-07-14T22:51:24.229891Z","end":"2026-07-14T22:51:24.456176Z","steps":["trace[8184221] 'read index received' (duration: 186.708014ms)","trace[8184221] 'applied index is now lower than readState.Index' (duration: 39.576666ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:51:24.456285Z","caller":"traceutil/trace.go:171","msg":"trace[634288903] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"228.99016ms","start":"2026-07-14T22:51:24.227286Z","end":"2026-07-14T22:51:24.456276Z","steps":["trace[634288903] 'process raft request' (duration: 228.706448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:24.456426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.515971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-07-14T22:51:24.456442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.892398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:51:24.456594Z","caller":"traceutil/trace.go:171","msg":"trace[2073889908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"184.078857ms","start":"2026-07-14T22:51:24.272494Z","end":"2026-07-14T22:51:24.456573Z","steps":["trace[2073889908] 'agreement among raft nodes before linearized reading' (duration: 183.875468ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:24.456518Z","caller":"traceutil/trace.go:171","msg":"trace[1525137672] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:603; }","duration":"226.640246ms","start":"2026-07-14T22:51:24.229858Z","end":"2026-07-14T22:51:24.456498Z","steps":["trace[1525137672] 'agreement among raft nodes before linearized reading' (duration: 226.50421ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:36.066534Z","caller":"traceutil/trace.go:171","msg":"trace[1353981632] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"100.055246ms","start":"2026-07-14T22:51:35.966454Z","end":"2026-07-14T22:51:36.06651Z","steps":["trace[1353981632] 'process raft request' (duration: 99.862389ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:36.620046Z","caller":"traceutil/trace.go:171","msg":"trace[1423334350] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"114.211042ms","start":"2026-07-14T22:51:36.505808Z","end":"2026-07-14T22:51:36.620019Z","steps":["trace[1423334350] 'process raft request' (duration: 85.140322ms)","trace[1423334350] 'compare' (duration: 28.959845ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:51:40.106407Z","caller":"traceutil/trace.go:171","msg":"trace[1669068883] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"280.463171ms","start":"2026-07-14T22:51:39.825914Z","end":"2026-07-14T22:51:40.106378Z","steps":["trace[1669068883] 'process raft request' (duration: 280.291915ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:51:40.220073Z","caller":"traceutil/trace.go:171","msg":"trace[1782483518] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:785; }","duration":"244.252971ms","start":"2026-07-14T22:51:39.975795Z","end":"2026-07-14T22:51:40.220048Z","steps":["trace[1782483518] 'read index received' (duration: 130.466041ms)","trace[1782483518] 'applied index is now lower than readState.Index' (duration: 113.78516ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:51:40.220223Z","caller":"traceutil/trace.go:171","msg":"trace[1978876037] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"389.956865ms","start":"2026-07-14T22:51:39.830225Z","end":"2026-07-14T22:51:40.220182Z","steps":["trace[1978876037] 'process raft request' (duration: 389.368893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:40.220363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.553901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fr775\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-14T22:51:40.220447Z","caller":"traceutil/trace.go:171","msg":"trace[588899934] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fr775; range_end:; response_count:1; response_revision:762; }","duration":"244.682196ms","start":"2026-07-14T22:51:39.975741Z","end":"2026-07-14T22:51:40.220423Z","steps":["trace[588899934] 'agreement among raft nodes before linearized reading' (duration: 244.454708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:51:40.220527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:51:39.83021Z","time spent":"390.118201ms","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:52:41.399176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.808128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:52:41.399241Z","caller":"traceutil/trace.go:171","msg":"trace[1681419171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"125.892759ms","start":"2026-07-14T22:52:41.273334Z","end":"2026-07-14T22:52:41.399227Z","steps":["trace[1681419171] 'range keys from in-memory index tree' (duration: 125.744716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:25.88651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.005068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:53:25.886601Z","caller":"traceutil/trace.go:171","msg":"trace[1622936628] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"275.15782ms","start":"2026-07-14T22:53:25.61143Z","end":"2026-07-14T22:53:25.886588Z","steps":["trace[1622936628] 'process raft request' (duration: 163.010341ms)","trace[1622936628] 'compare' (duration: 111.917077ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:53:31.330401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.83677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:53:31.330476Z","caller":"traceutil/trace.go:171","msg":"trace[15752545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"232.956461ms","start":"2026-07-14T22:53:31.097502Z","end":"2026-07-14T22:53:31.330458Z","steps":["trace[15752545] 'range keys from in-memory index tree' (duration: 232.721889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:31.331008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.54069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:53:31.331112Z","caller":"traceutil/trace.go:171","msg":"trace[447931141] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1298; }","duration":"180.698051ms","start":"2026-07-14T22:53:31.150388Z","end":"2026-07-14T22:53:31.331086Z","steps":["trace[447931141] 'count revisions from in-memory index tree' (duration: 180.441639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:31.331196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.899527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T22:53:31.333399Z","caller":"traceutil/trace.go:171","msg":"trace[1897491441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"169.178301ms","start":"2026-07-14T22:53:31.1642Z","end":"2026-07-14T22:53:31.333378Z","steps":["trace[1897491441] 'range keys from in-memory index tree' (duration: 166.796365ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:53:52.079417Z","caller":"traceutil/trace.go:171","msg":"trace[1005854687] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"244.88395ms","start":"2026-07-14T22:53:51.834516Z","end":"2026-07-14T22:53:52.0794Z","steps":["trace[1005854687] 'process raft request' (duration: 244.791099ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:53:52.079669Z","caller":"traceutil/trace.go:171","msg":"trace[1758305339] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1429; }","duration":"210.283536ms","start":"2026-07-14T22:53:51.869373Z","end":"2026-07-14T22:53:52.079656Z","steps":["trace[1758305339] 'read index received' (duration: 209.872133ms)","trace[1758305339] 'applied index is now lower than readState.Index' (duration: 410.623µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:53:52.079801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.420647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-07-14T22:53:52.079867Z","caller":"traceutil/trace.go:171","msg":"trace[680679509] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1373; }","duration":"210.527368ms","start":"2026-07-14T22:53:51.869332Z","end":"2026-07-14T22:53:52.079859Z","steps":["trace[680679509] 'agreement among raft nodes before linearized reading' (duration: 210.379747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:53:52.080039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.792074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3302"} {"level":"info","ts":"2026-07-14T22:53:52.080123Z","caller":"traceutil/trace.go:171","msg":"trace[665039578] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/; range_end:/registry/rolebindings/envoy-gateway-system0; response_count:3; response_revision:1373; }","duration":"207.948125ms","start":"2026-07-14T22:53:51.872163Z","end":"2026-07-14T22:53:52.080111Z","steps":["trace[665039578] 'agreement among raft nodes before linearized reading' (duration: 207.745642ms)"],"step_count":1}