{"level":"warn","ts":"2026-05-09T16:51:15.739953Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:51:15.740056Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T16:51:15.740138Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:51:15.740149Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-09T16:51:15.740204Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T16:51:15.741045Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-05-09T16:51:15.741145Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T16:51:15.746255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.814173ms"} {"level":"info","ts":"2026-05-09T16:51:15.754805Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-05-09T16:51:15.754884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T16:51:15.754903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-05-09T16:51:15.754912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T16:51:15.754924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-05-09T16:51:15.754951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-05-09T16:51:15.761728Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T16:51:15.762972Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T16:51:15.764036Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T16:51:15.766193Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T16:51:15.76644Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T16:51:15.766443Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:51:15.769323Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:51:15.769339Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:51:15.769963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-05-09T16:51:15.770325Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-09T16:51:15.772708Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T16:51:15.772996Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-09T16:51:15.773085Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-09T16:51:15.773373Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T16:51:15.773436Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T16:51:16.456106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T16:51:16.456178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T16:51:16.456215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-05-09T16:51:16.456234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-05-09T16:51:16.456252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-09T16:51:16.456268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-05-09T16:51:16.456284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-09T16:51:16.457519Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:16.458654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:51:16.459497Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:16.459585Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:16.459652Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:16.459971Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:51:16.46175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-05-09T16:51:16.458612Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T16:51:16.462925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T16:51:16.46297Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T16:51:16.463032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T16:51:25.794313Z","caller":"traceutil/trace.go:171","msg":"trace[938564990] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"146.623162ms","start":"2026-05-09T16:51:25.647659Z","end":"2026-05-09T16:51:25.794283Z","steps":["trace[938564990] 'read index received' (duration: 146.449621ms)","trace[938564990] 'applied index is now lower than readState.Index' (duration: 172.131µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:25.794361Z","caller":"traceutil/trace.go:171","msg":"trace[1551265746] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"187.617843ms","start":"2026-05-09T16:51:25.606714Z","end":"2026-05-09T16:51:25.794332Z","steps":["trace[1551265746] 'process raft request' (duration: 187.396011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:25.794767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.102966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-09T16:51:25.79493Z","caller":"traceutil/trace.go:171","msg":"trace[1587159038] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:340; }","duration":"147.305007ms","start":"2026-05-09T16:51:25.647606Z","end":"2026-05-09T16:51:25.794911Z","steps":["trace[1587159038] 'agreement among raft nodes before linearized reading' (duration: 146.838814ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:25.913115Z","caller":"traceutil/trace.go:171","msg":"trace[388871735] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"114.10881ms","start":"2026-05-09T16:51:25.79898Z","end":"2026-05-09T16:51:25.913089Z","steps":["trace[388871735] 'process raft request' (duration: 42.086849ms)","trace[388871735] 'compare' (duration: 71.89797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:26.139062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.344019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:26.139322Z","caller":"traceutil/trace.go:171","msg":"trace[1137653209] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"340.066672ms","start":"2026-05-09T16:51:25.799216Z","end":"2026-05-09T16:51:26.139282Z","steps":["trace[1137653209] 'process raft request' (duration: 217.809696ms)","trace[1137653209] 'compare' (duration: 121.208398ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:26.139487Z","caller":"traceutil/trace.go:171","msg":"trace[558414882] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"335.832888ms","start":"2026-05-09T16:51:25.803634Z","end":"2026-05-09T16:51:26.139467Z","steps":["trace[558414882] 'process raft request' (duration: 335.710787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.139532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.7992Z","time spent":"340.212784ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:26.139517Z","caller":"traceutil/trace.go:171","msg":"trace[2143276450] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"336.764386ms","start":"2026-05-09T16:51:25.80272Z","end":"2026-05-09T16:51:26.139485Z","steps":["trace[2143276450] 'process raft request' (duration: 336.568524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.139612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.803615Z","time spent":"335.930779ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:26.139603Z","caller":"traceutil/trace.go:171","msg":"trace[382900014] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"337.473192ms","start":"2026-05-09T16:51:25.8021Z","end":"2026-05-09T16:51:26.139573Z","steps":["trace[382900014] 'process raft request' (duration: 337.096089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.139784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.802704Z","time spent":"336.969498ms","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:26.139889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.802086Z","time spent":"337.663804ms","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:26.139978Z","caller":"traceutil/trace.go:171","msg":"trace[988634894] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"335.728128ms","start":"2026-05-09T16:51:25.804238Z","end":"2026-05-09T16:51:26.139966Z","steps":["trace[988634894] 'process raft request' (duration: 335.543347ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.140016Z","caller":"traceutil/trace.go:171","msg":"trace[1555395259] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:356; }","duration":"292.019156ms","start":"2026-05-09T16:51:25.847951Z","end":"2026-05-09T16:51:26.13997Z","steps":["trace[1555395259] 'read index received' (duration: 169.022874ms)","trace[1555395259] 'applied index is now lower than readState.Index' (duration: 122.995092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:26.140084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.80423Z","time spent":"335.796029ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:26.139928Z","caller":"traceutil/trace.go:171","msg":"trace[83740936] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"336.097111ms","start":"2026-05-09T16:51:25.803814Z","end":"2026-05-09T16:51:26.139911Z","steps":["trace[83740936] 'process raft request' (duration: 335.578256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.140271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.803805Z","time spent":"336.427273ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:26.140342Z","caller":"traceutil/trace.go:171","msg":"trace[528068199] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"334.319477ms","start":"2026-05-09T16:51:25.806004Z","end":"2026-05-09T16:51:26.140324Z","steps":["trace[528068199] 'process raft request' (duration: 333.853874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.140167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.929522ms","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-05-09T16:51:26.140444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.805991Z","time spent":"334.406248ms","remote":"127.0.0.1:34100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:26.140486Z","caller":"traceutil/trace.go:171","msg":"trace[587682094] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:348; }","duration":"242.244474ms","start":"2026-05-09T16:51:25.89818Z","end":"2026-05-09T16:51:26.140425Z","steps":["trace[587682094] 'agreement among raft nodes before linearized reading' (duration: 241.940772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.140535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.822036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-09T16:51:26.140588Z","caller":"traceutil/trace.go:171","msg":"trace[1190960541] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:348; }","duration":"192.895786ms","start":"2026-05-09T16:51:25.947675Z","end":"2026-05-09T16:51:26.140571Z","steps":["trace[1190960541] 'agreement among raft nodes before linearized reading' (duration: 192.810105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.140748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.5963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-09T16:51:26.140846Z","caller":"traceutil/trace.go:171","msg":"trace[1782521135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"142.654961ms","start":"2026-05-09T16:51:25.998136Z","end":"2026-05-09T16:51:26.140791Z","steps":["trace[1782521135] 'agreement among raft nodes before linearized reading' (duration: 142.55317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.141323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.620931ms","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-05-09T16:51:26.141402Z","caller":"traceutil/trace.go:171","msg":"trace[2063483144] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"293.498607ms","start":"2026-05-09T16:51:25.847886Z","end":"2026-05-09T16:51:26.141385Z","steps":["trace[2063483144] 'agreement among raft nodes before linearized reading' (duration: 292.223548ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.346743Z","caller":"traceutil/trace.go:171","msg":"trace[1336474082] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"198.371595ms","start":"2026-05-09T16:51:26.148336Z","end":"2026-05-09T16:51:26.346708Z","steps":["trace[1336474082] 'process raft request' (duration: 158.401906ms)","trace[1336474082] 'compare' (duration: 39.723507ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:26.346842Z","caller":"traceutil/trace.go:171","msg":"trace[1413302787] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"197.430527ms","start":"2026-05-09T16:51:26.149172Z","end":"2026-05-09T16:51:26.346603Z","steps":["trace[1413302787] 'read index received' (duration: 157.62809ms)","trace[1413302787] 'applied index is now lower than readState.Index' (duration: 39.801617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:26.346892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.67738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-09T16:51:26.346971Z","caller":"traceutil/trace.go:171","msg":"trace[1558318547] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:349; }","duration":"197.811121ms","start":"2026-05-09T16:51:26.149142Z","end":"2026-05-09T16:51:26.346953Z","steps":["trace[1558318547] 'agreement among raft nodes before linearized reading' (duration: 197.634329ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.365808Z","caller":"traceutil/trace.go:171","msg":"trace[380464411] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"216.45226ms","start":"2026-05-09T16:51:26.149341Z","end":"2026-05-09T16:51:26.365793Z","steps":["trace[380464411] 'process raft request' (duration: 216.270669ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.365975Z","caller":"traceutil/trace.go:171","msg":"trace[855362090] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"216.43145ms","start":"2026-05-09T16:51:26.14952Z","end":"2026-05-09T16:51:26.365952Z","steps":["trace[855362090] 'process raft request' (duration: 216.209578ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.366106Z","caller":"traceutil/trace.go:171","msg":"trace[553795815] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"215.742155ms","start":"2026-05-09T16:51:26.150348Z","end":"2026-05-09T16:51:26.36609Z","steps":["trace[553795815] 'process raft request' (duration: 215.632674ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.366256Z","caller":"traceutil/trace.go:171","msg":"trace[1116248583] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"216.303639ms","start":"2026-05-09T16:51:26.149947Z","end":"2026-05-09T16:51:26.366251Z","steps":["trace[1116248583] 'process raft request' (duration: 215.833475ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.366103Z","caller":"traceutil/trace.go:171","msg":"trace[380342915] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"215.694785ms","start":"2026-05-09T16:51:26.150393Z","end":"2026-05-09T16:51:26.366088Z","steps":["trace[380342915] 'process raft request' (duration: 215.643704ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.366234Z","caller":"traceutil/trace.go:171","msg":"trace[1555489061] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"214.777296ms","start":"2026-05-09T16:51:26.15145Z","end":"2026-05-09T16:51:26.366228Z","steps":["trace[1555489061] 'process raft request' (duration: 214.612444ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.366689Z","caller":"traceutil/trace.go:171","msg":"trace[1544274735] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"214.450295ms","start":"2026-05-09T16:51:26.152228Z","end":"2026-05-09T16:51:26.366678Z","steps":["trace[1544274735] 'process raft request' (duration: 213.97254ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.36704Z","caller":"traceutil/trace.go:171","msg":"trace[1457275294] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"212.302038ms","start":"2026-05-09T16:51:26.154713Z","end":"2026-05-09T16:51:26.367015Z","steps":["trace[1457275294] 'process raft request' (duration: 211.778614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.367137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.609296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-09T16:51:26.367195Z","caller":"traceutil/trace.go:171","msg":"trace[1649001640] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:359; }","duration":"119.709487ms","start":"2026-05-09T16:51:26.247473Z","end":"2026-05-09T16:51:26.367183Z","steps":["trace[1649001640] 'agreement among raft nodes before linearized reading' (duration: 119.605126ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.367349Z","caller":"traceutil/trace.go:171","msg":"trace[1137658457] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"212.256437ms","start":"2026-05-09T16:51:26.155082Z","end":"2026-05-09T16:51:26.367339Z","steps":["trace[1137658457] 'process raft request' (duration: 211.498811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.367763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.251663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-09T16:51:26.367799Z","caller":"traceutil/trace.go:171","msg":"trace[849137132] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:359; }","duration":"169.320854ms","start":"2026-05-09T16:51:26.198468Z","end":"2026-05-09T16:51:26.367789Z","steps":["trace[849137132] 'agreement among raft nodes before linearized reading' (duration: 169.255963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.368054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.118113ms","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-05-09T16:51:26.367706Z","caller":"traceutil/trace.go:171","msg":"trace[1159089449] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"214.577816ms","start":"2026-05-09T16:51:26.153114Z","end":"2026-05-09T16:51:26.367692Z","steps":["trace[1159089449] 'process raft request' (duration: 213.300555ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.368098Z","caller":"traceutil/trace.go:171","msg":"trace[1968165252] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:359; }","duration":"218.192184ms","start":"2026-05-09T16:51:26.149896Z","end":"2026-05-09T16:51:26.368088Z","steps":["trace[1968165252] 'agreement among raft nodes before linearized reading' (duration: 216.773303ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.523185Z","caller":"traceutil/trace.go:171","msg":"trace[290843636] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"168.755149ms","start":"2026-05-09T16:51:26.354402Z","end":"2026-05-09T16:51:26.523158Z","steps":["trace[290843636] 'process raft request' (duration: 153.579928ms)","trace[290843636] 'compare' (duration: 15.05282ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:26.524061Z","caller":"traceutil/trace.go:171","msg":"trace[2019347246] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"153.355885ms","start":"2026-05-09T16:51:26.370679Z","end":"2026-05-09T16:51:26.524035Z","steps":["trace[2019347246] 'process raft request' (duration: 152.66798ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.524125Z","caller":"traceutil/trace.go:171","msg":"trace[213925896] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"151.37373ms","start":"2026-05-09T16:51:26.372724Z","end":"2026-05-09T16:51:26.524098Z","steps":["trace[213925896] 'process raft request' (duration: 151.226199ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.524382Z","caller":"traceutil/trace.go:171","msg":"trace[117514932] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"146.103558ms","start":"2026-05-09T16:51:26.378267Z","end":"2026-05-09T16:51:26.52437Z","steps":["trace[117514932] 'process raft request' (duration: 146.009667ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.52439Z","caller":"traceutil/trace.go:171","msg":"trace[2038063301] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"148.505627ms","start":"2026-05-09T16:51:26.375872Z","end":"2026-05-09T16:51:26.524378Z","steps":["trace[2038063301] 'process raft request' (duration: 148.253815ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.524251Z","caller":"traceutil/trace.go:171","msg":"trace[891068400] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"150.687324ms","start":"2026-05-09T16:51:26.373441Z","end":"2026-05-09T16:51:26.524128Z","steps":["trace[891068400] 'process raft request' (duration: 150.578403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:26.524458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.49304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-09T16:51:26.524519Z","caller":"traceutil/trace.go:171","msg":"trace[1101358942] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"149.820148ms","start":"2026-05-09T16:51:26.374687Z","end":"2026-05-09T16:51:26.524507Z","steps":["trace[1101358942] 'process raft request' (duration: 149.410165ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.52455Z","caller":"traceutil/trace.go:171","msg":"trace[845513730] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:367; }","duration":"151.649122ms","start":"2026-05-09T16:51:26.372881Z","end":"2026-05-09T16:51:26.52453Z","steps":["trace[845513730] 'agreement among raft nodes before linearized reading' (duration: 151.508721ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.524336Z","caller":"traceutil/trace.go:171","msg":"trace[396462759] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"149.838158ms","start":"2026-05-09T16:51:26.374482Z","end":"2026-05-09T16:51:26.52432Z","steps":["trace[396462759] 'process raft request' (duration: 149.587976ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:26.524366Z","caller":"traceutil/trace.go:171","msg":"trace[712732397] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:374; }","duration":"151.38955ms","start":"2026-05-09T16:51:26.372921Z","end":"2026-05-09T16:51:26.524311Z","steps":["trace[712732397] 'read index received' (duration: 135.016159ms)","trace[712732397] 'applied index is now lower than readState.Index' (duration: 16.372771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:26.524779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.487104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-05-09T16:51:26.524816Z","caller":"traceutil/trace.go:171","msg":"trace[1175012821] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:367; }","duration":"149.557195ms","start":"2026-05-09T16:51:26.375249Z","end":"2026-05-09T16:51:26.524806Z","steps":["trace[1175012821] 'agreement among raft nodes before linearized reading' (duration: 149.451305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:27.237251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.319005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-09T16:51:27.237634Z","caller":"traceutil/trace.go:171","msg":"trace[912727952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:389; }","duration":"504.738768ms","start":"2026-05-09T16:51:26.732864Z","end":"2026-05-09T16:51:27.237603Z","steps":["trace[912727952] 'range keys from in-memory index tree' (duration: 504.176243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:27.237689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:26.732843Z","time spent":"504.829139ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-05-09T16:51:28.414445Z","caller":"traceutil/trace.go:171","msg":"trace[1226178955] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"268.430319ms","start":"2026-05-09T16:51:28.145999Z","end":"2026-05-09T16:51:28.41443Z","steps":["trace[1226178955] 'read index received' (duration: 268.232828ms)","trace[1226178955] 'applied index is now lower than readState.Index' (duration: 196.771µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:28.41473Z","caller":"traceutil/trace.go:171","msg":"trace[143768498] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"305.786162ms","start":"2026-05-09T16:51:28.108936Z","end":"2026-05-09T16:51:28.414722Z","steps":["trace[143768498] 'process raft request' (duration: 305.396009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.414808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:28.108912Z","time spent":"305.842163ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:28.415212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.182215ms","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-05-09T16:51:28.415321Z","caller":"traceutil/trace.go:171","msg":"trace[1063086162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:390; }","duration":"269.328906ms","start":"2026-05-09T16:51:28.145966Z","end":"2026-05-09T16:51:28.415294Z","steps":["trace[1063086162] 'agreement among raft nodes before linearized reading' (duration: 269.031123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.636997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.556076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:28.637448Z","caller":"traceutil/trace.go:171","msg":"trace[639307098] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"218.803079ms","start":"2026-05-09T16:51:28.418594Z","end":"2026-05-09T16:51:28.637397Z","steps":["trace[639307098] 'process raft request' (duration: 45.692859ms)","trace[639307098] 'compare' (duration: 172.330604ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:28.63763Z","caller":"traceutil/trace.go:171","msg":"trace[1736870999] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"218.92131ms","start":"2026-05-09T16:51:28.418685Z","end":"2026-05-09T16:51:28.637606Z","steps":["trace[1736870999] 'process raft request' (duration: 218.487407ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:28.637747Z","caller":"traceutil/trace.go:171","msg":"trace[689253564] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"218.537477ms","start":"2026-05-09T16:51:28.419133Z","end":"2026-05-09T16:51:28.63767Z","steps":["trace[689253564] 'process raft request' (duration: 218.175695ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:30.1673Z","caller":"traceutil/trace.go:171","msg":"trace[245982982] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"264.843002ms","start":"2026-05-09T16:51:29.902428Z","end":"2026-05-09T16:51:30.167271Z","steps":["trace[245982982] 'process raft request' (duration: 254.520883ms)","trace[245982982] 'compare' (duration: 10.187598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:30.397957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.014781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:30.398078Z","caller":"traceutil/trace.go:171","msg":"trace[450067413] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"138.780503ms","start":"2026-05-09T16:51:30.259281Z","end":"2026-05-09T16:51:30.398062Z","steps":["trace[450067413] 'read index received' (duration: 28.523ms)","trace[450067413] 'applied index is now lower than readState.Index' (duration: 110.256493ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:30.3982Z","caller":"traceutil/trace.go:171","msg":"trace[563470] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"220.093731ms","start":"2026-05-09T16:51:30.178071Z","end":"2026-05-09T16:51:30.398165Z","steps":["trace[563470] 'process raft request' (duration: 109.788968ms)","trace[563470] 'compare' (duration: 109.88786ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:30.398246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.953494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T16:51:30.39848Z","caller":"traceutil/trace.go:171","msg":"trace[1379672127] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:402; }","duration":"139.207716ms","start":"2026-05-09T16:51:30.259235Z","end":"2026-05-09T16:51:30.398443Z","steps":["trace[1379672127] 'agreement among raft nodes before linearized reading' (duration: 138.874234ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:30.599754Z","caller":"traceutil/trace.go:171","msg":"trace[482358602] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"193.536506ms","start":"2026-05-09T16:51:30.406161Z","end":"2026-05-09T16:51:30.599698Z","steps":["trace[482358602] 'process raft request' (duration: 93.580283ms)","trace[482358602] 'compare' (duration: 99.839492ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:30.601477Z","caller":"traceutil/trace.go:171","msg":"trace[961632637] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"189.101261ms","start":"2026-05-09T16:51:30.412358Z","end":"2026-05-09T16:51:30.601459Z","steps":["trace[961632637] 'process raft request' (duration: 189.063591ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:30.601746Z","caller":"traceutil/trace.go:171","msg":"trace[185894017] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"194.524393ms","start":"2026-05-09T16:51:30.407216Z","end":"2026-05-09T16:51:30.60174Z","steps":["trace[185894017] 'process raft request' (duration: 194.10641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:30.842917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.395117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T16:51:30.842991Z","caller":"traceutil/trace.go:171","msg":"trace[1253153795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"104.527348ms","start":"2026-05-09T16:51:30.738448Z","end":"2026-05-09T16:51:30.842975Z","steps":["trace[1253153795] 'range keys from in-memory index tree' (duration: 104.256135ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:35.261925Z","caller":"traceutil/trace.go:171","msg":"trace[167606632] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"430.021819ms","start":"2026-05-09T16:51:34.831877Z","end":"2026-05-09T16:51:35.261899Z","steps":["trace[167606632] 'read index received' (duration: 429.785267ms)","trace[167606632] 'applied index is now lower than readState.Index' (duration: 232.602µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:35.261965Z","caller":"traceutil/trace.go:171","msg":"trace[878437677] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"521.472234ms","start":"2026-05-09T16:51:34.740463Z","end":"2026-05-09T16:51:35.261935Z","steps":["trace[878437677] 'process raft request' (duration: 521.227952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:35.262166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.359312ms","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-05-09T16:51:35.262216Z","caller":"traceutil/trace.go:171","msg":"trace[752182558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"426.402172ms","start":"2026-05-09T16:51:34.835801Z","end":"2026-05-09T16:51:35.262203Z","steps":["trace[752182558] 'agreement among raft nodes before linearized reading' (duration: 426.345311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:35.262258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:34.83578Z","time spent":"426.469712ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T16:51:35.262301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.939798ms","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-05-09T16:51:35.262105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.21344ms","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-05-09T16:51:35.262454Z","caller":"traceutil/trace.go:171","msg":"trace[1416650373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"430.619884ms","start":"2026-05-09T16:51:34.83182Z","end":"2026-05-09T16:51:35.26244Z","steps":["trace[1416650373] 'agreement among raft nodes before linearized reading' (duration: 430.205801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:35.26252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:34.83179Z","time spent":"430.714144ms","remote":"127.0.0.1:34194","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":"warn","ts":"2026-05-09T16:51:35.262338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:34.740433Z","time spent":"521.725706ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:35.262792Z","caller":"traceutil/trace.go:171","msg":"trace[726635919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"306.187301ms","start":"2026-05-09T16:51:34.956274Z","end":"2026-05-09T16:51:35.262461Z","steps":["trace[726635919] 'agreement among raft nodes before linearized reading' (duration: 305.946519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:35.262896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:34.956252Z","time spent":"306.593713ms","remote":"127.0.0.1:34194","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-05-09T16:51:40.898698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:40.260861Z","time spent":"637.823039ms","remote":"127.0.0.1:34024","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-09T16:51:40.899416Z","caller":"traceutil/trace.go:171","msg":"trace[730578244] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:482; }","duration":"220.112013ms","start":"2026-05-09T16:51:40.679285Z","end":"2026-05-09T16:51:40.899397Z","steps":["trace[730578244] 'read index received' (duration: 220.104753ms)","trace[730578244] 'applied index is now lower than readState.Index' (duration: 5.75µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:41.342692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.330406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:41.342833Z","caller":"traceutil/trace.go:171","msg":"trace[986662782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"663.559718ms","start":"2026-05-09T16:51:40.679236Z","end":"2026-05-09T16:51:41.342796Z","steps":["trace[986662782] 'agreement among raft nodes before linearized reading' (duration: 220.234213ms)","trace[986662782] 'range keys from in-memory index tree' (duration: 443.110673ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:41.342998Z","caller":"traceutil/trace.go:171","msg":"trace[2108134158] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"442.936111ms","start":"2026-05-09T16:51:40.900034Z","end":"2026-05-09T16:51:41.34297Z","steps":["trace[2108134158] 'process raft request' (duration: 430.742693ms)","trace[2108134158] 'compare' (duration: 11.778025ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:41.342966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:40.679213Z","time spent":"663.73431ms","remote":"127.0.0.1:33964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T16:51:41.343104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:40.900014Z","time spent":"443.033521ms","remote":"127.0.0.1:34024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:42.418334Z","caller":"traceutil/trace.go:171","msg":"trace[204675233] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"284.536752ms","start":"2026-05-09T16:51:42.133768Z","end":"2026-05-09T16:51:42.418304Z","steps":["trace[204675233] 'process raft request' (duration: 284.356251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.178846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532275055996800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:51:53.620496Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.176973899s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:51:53.627597Z","caller":"traceutil/trace.go:171","msg":"trace[382650967] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.1841889s","start":"2026-05-09T16:51:52.443392Z","end":"2026-05-09T16:51:53.627581Z","steps":["trace[382650967] 'process raft request' (duration: 1.177523158s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.627708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.443369Z","time spent":"1.184270014s","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:53.633883Z","caller":"traceutil/trace.go:171","msg":"trace[887178767] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.180053988s","start":"2026-05-09T16:51:52.451189Z","end":"2026-05-09T16:51:53.631243Z","steps":["trace[887178767] 'process raft request' (duration: 1.179772184s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.634103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.451171Z","time spent":"1.182794945s","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:53.634925Z","caller":"traceutil/trace.go:171","msg":"trace[722410014] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:581; }","duration":"956.651251ms","start":"2026-05-09T16:51:52.67808Z","end":"2026-05-09T16:51:53.634731Z","steps":["trace[722410014] 'read index received' (duration: 942.78418ms)","trace[722410014] 'applied index is now lower than readState.Index' (duration: 13.865901ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:53.635364Z","caller":"traceutil/trace.go:171","msg":"trace[1350407611] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"906.806944ms","start":"2026-05-09T16:51:52.728528Z","end":"2026-05-09T16:51:53.635335Z","steps":["trace[1350407611] 'process raft request' (duration: 906.110898ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:53.635643Z","caller":"traceutil/trace.go:171","msg":"trace[2093617636] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.161542841s","start":"2026-05-09T16:51:52.474078Z","end":"2026-05-09T16:51:53.635621Z","steps":["trace[2093617636] 'process raft request' (duration: 1.157005314s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.635863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.7285Z","time spent":"906.903028ms","remote":"127.0.0.1:34194","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-05-09T16:51:53.636011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.474057Z","time spent":"1.161636256s","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:53.63621Z","caller":"traceutil/trace.go:171","msg":"trace[134463478] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.123302508s","start":"2026-05-09T16:51:52.51288Z","end":"2026-05-09T16:51:53.636183Z","steps":["trace[134463478] 'process raft request' (duration: 1.118290788s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.63631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.512859Z","time spent":"1.123399124s","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:51:53.637083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.151467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-09T16:51:53.637705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.367947ms","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-05-09T16:51:53.637736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.669452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:53.639007Z","caller":"traceutil/trace.go:171","msg":"trace[1472293242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"960.942766ms","start":"2026-05-09T16:51:52.678019Z","end":"2026-05-09T16:51:53.638962Z","steps":["trace[1472293242] 'agreement among raft nodes before linearized reading' (duration: 959.680333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.639115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.677999Z","time spent":"961.080123ms","remote":"127.0.0.1:33964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T16:51:53.637775Z","caller":"traceutil/trace.go:171","msg":"trace[95222242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"111.471742ms","start":"2026-05-09T16:51:53.526276Z","end":"2026-05-09T16:51:53.637747Z","steps":["trace[95222242] 'agreement among raft nodes before linearized reading' (duration: 111.303843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.637896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.83062ms","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-05-09T16:51:53.638091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.815581ms","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-05-09T16:51:53.639945Z","caller":"traceutil/trace.go:171","msg":"trace[975848340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"190.722076ms","start":"2026-05-09T16:51:53.449206Z","end":"2026-05-09T16:51:53.639928Z","steps":["trace[975848340] 'agreement among raft nodes before linearized reading' (duration: 188.730027ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:53.64004Z","caller":"traceutil/trace.go:171","msg":"trace[328558658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"196.245751ms","start":"2026-05-09T16:51:53.440884Z","end":"2026-05-09T16:51:53.63713Z","steps":["trace[328558658] 'agreement among raft nodes before linearized reading' (duration: 195.840811ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:53.640015Z","caller":"traceutil/trace.go:171","msg":"trace[2083850057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"114.677672ms","start":"2026-05-09T16:51:53.525019Z","end":"2026-05-09T16:51:53.639696Z","steps":["trace[2083850057] 'agreement among raft nodes before linearized reading' (duration: 112.792539ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:57.590257Z","caller":"traceutil/trace.go:171","msg":"trace[699010221] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"131.511419ms","start":"2026-05-09T16:51:57.45872Z","end":"2026-05-09T16:51:57.590232Z","steps":["trace[699010221] 'process raft request' (duration: 131.066428ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:00.114591Z","caller":"traceutil/trace.go:171","msg":"trace[683771145] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"214.040315ms","start":"2026-05-09T16:51:59.900524Z","end":"2026-05-09T16:52:00.114564Z","steps":["trace[683771145] 'process raft request' (duration: 213.885247ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:00.722913Z","caller":"traceutil/trace.go:171","msg":"trace[1473918841] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"635.641666ms","start":"2026-05-09T16:52:00.087235Z","end":"2026-05-09T16:52:00.722877Z","steps":["trace[1473918841] 'process raft request' (duration: 634.87467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:00.723056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:00.087206Z","time spent":"635.776671ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:52:00.723211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.664102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T16:52:00.723317Z","caller":"traceutil/trace.go:171","msg":"trace[1304864670] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:638; }","duration":"459.878402ms","start":"2026-05-09T16:52:00.263419Z","end":"2026-05-09T16:52:00.723297Z","steps":["trace[1304864670] 'agreement among raft nodes before linearized reading' (duration: 459.595659ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:00.723413Z","caller":"traceutil/trace.go:171","msg":"trace[1052526329] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"459.320346ms","start":"2026-05-09T16:52:00.263472Z","end":"2026-05-09T16:52:00.722792Z","steps":["trace[1052526329] 'read index received' (duration: 458.701087ms)","trace[1052526329] 'applied index is now lower than readState.Index' (duration: 618.079µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:00.723576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:00.263385Z","time spent":"460.177375ms","remote":"127.0.0.1:34024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-05-09T16:52:00.904774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.274014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:52:00.905003Z","caller":"traceutil/trace.go:171","msg":"trace[2140320709] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"121.402493ms","start":"2026-05-09T16:52:00.783578Z","end":"2026-05-09T16:52:00.904981Z","steps":["trace[2140320709] 'process raft request' (duration: 121.32466ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:00.905111Z","caller":"traceutil/trace.go:171","msg":"trace[96534580] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"160.875652ms","start":"2026-05-09T16:52:00.744207Z","end":"2026-05-09T16:52:00.905083Z","steps":["trace[96534580] 'process raft request' (duration: 43.182899ms)","trace[96534580] 'compare' (duration: 117.077914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:02.051447Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.130352957s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-09T16:52:02.769641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.93496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:52:02.769737Z","caller":"traceutil/trace.go:171","msg":"trace[1841067712] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"1.092680391s","start":"2026-05-09T16:52:01.677042Z","end":"2026-05-09T16:52:02.769722Z","steps":["trace[1841067712] 'read index received' (duration: 374.50748ms)","trace[1841067712] 'applied index is now lower than readState.Index' (duration: 718.171351ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:02.769875Z","caller":"traceutil/trace.go:171","msg":"trace[666151662] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.848864702s","start":"2026-05-09T16:52:00.920998Z","end":"2026-05-09T16:52:02.769863Z","steps":["trace[666151662] 'process raft request' (duration: 1.13064691s)","trace[666151662] 'compare' (duration: 717.363614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:02.769925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:00.920984Z","time spent":"1.848919614s","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:52:02.770093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093043258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:02.770116Z","caller":"traceutil/trace.go:171","msg":"trace[1230319283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.09310627s","start":"2026-05-09T16:52:01.677002Z","end":"2026-05-09T16:52:02.770108Z","steps":["trace[1230319283] 'agreement among raft nodes before linearized reading' (duration: 1.093041427s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:02.770137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:01.676982Z","time spent":"1.093149902s","remote":"127.0.0.1:33964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T16:52:02.770316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024864791s","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-05-09T16:52:02.77034Z","caller":"traceutil/trace.go:171","msg":"trace[993555382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"1.024914243s","start":"2026-05-09T16:52:01.745417Z","end":"2026-05-09T16:52:02.770331Z","steps":["trace[993555382] 'agreement among raft nodes before linearized reading' (duration: 1.024812227s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:02.770358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:01.7454Z","time spent":"1.024953165s","remote":"127.0.0.1:34194","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":"warn","ts":"2026-05-09T16:52:02.770703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021914007s","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-05-09T16:52:02.770724Z","caller":"traceutil/trace.go:171","msg":"trace[1419484543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.021973359s","start":"2026-05-09T16:52:01.748745Z","end":"2026-05-09T16:52:02.770718Z","steps":["trace[1419484543] 'agreement among raft nodes before linearized reading' (duration: 1.021907206s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:02.770746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:01.74872Z","time spent":"1.022021352s","remote":"127.0.0.1:34194","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":"warn","ts":"2026-05-09T16:52:02.771002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022276803s","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-05-09T16:52:02.771075Z","caller":"traceutil/trace.go:171","msg":"trace[102763990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.022398018s","start":"2026-05-09T16:52:01.748661Z","end":"2026-05-09T16:52:02.771059Z","steps":["trace[102763990] 'agreement among raft nodes before linearized reading' (duration: 1.022153107s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:02.771088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02551236s","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-05-09T16:52:02.771116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:01.748653Z","time spent":"1.022453081s","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T16:52:02.771139Z","caller":"traceutil/trace.go:171","msg":"trace[1804991919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.025577442s","start":"2026-05-09T16:52:01.745546Z","end":"2026-05-09T16:52:02.771124Z","steps":["trace[1804991919] 'agreement among raft nodes before linearized reading' (duration: 1.025436506s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:02.771175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:01.74554Z","time spent":"1.025626834s","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T16:52:04.554238Z","caller":"traceutil/trace.go:171","msg":"trace[166034890] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"512.569934ms","start":"2026-05-09T16:52:04.041647Z","end":"2026-05-09T16:52:04.554217Z","steps":["trace[166034890] 'process raft request' (duration: 512.240649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:04.554389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:04.04162Z","time spent":"512.687659ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:17.038936Z","caller":"traceutil/trace.go:171","msg":"trace[1367305269] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"121.270438ms","start":"2026-05-09T16:52:16.917643Z","end":"2026-05-09T16:52:17.038913Z","steps":["trace[1367305269] 'process raft request' (duration: 121.194535ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:17.038958Z","caller":"traceutil/trace.go:171","msg":"trace[634988804] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"122.175094ms","start":"2026-05-09T16:52:16.916758Z","end":"2026-05-09T16:52:17.038933Z","steps":["trace[634988804] 'process raft request' (duration: 121.672275ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:17.039001Z","caller":"traceutil/trace.go:171","msg":"trace[1506210176] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"121.837101ms","start":"2026-05-09T16:52:16.917123Z","end":"2026-05-09T16:52:17.03896Z","steps":["trace[1506210176] 'process raft request' (duration: 121.668455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:17.265734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.426403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:17.265844Z","caller":"traceutil/trace.go:171","msg":"trace[543242958] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:796; }","duration":"119.566138ms","start":"2026-05-09T16:52:17.146254Z","end":"2026-05-09T16:52:17.26582Z","steps":["trace[543242958] 'count revisions from in-memory index tree' (duration: 119.346249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:19.543456Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532275055997374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:52:19.885395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206989674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:19.885478Z","caller":"traceutil/trace.go:171","msg":"trace[1753137863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"1.207109619s","start":"2026-05-09T16:52:18.678349Z","end":"2026-05-09T16:52:19.885459Z","steps":["trace[1753137863] 'range keys from in-memory index tree' (duration: 1.206916582s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:19.885741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127867311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T16:52:19.88581Z","caller":"traceutil/trace.go:171","msg":"trace[909697713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.127962835s","start":"2026-05-09T16:52:18.757831Z","end":"2026-05-09T16:52:19.885794Z","steps":["trace[909697713] 'range keys from in-memory index tree' (duration: 1.127647383s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:19.885861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:18.75782Z","time spent":"1.128028877s","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T16:52:19.88591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.554684ms","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-05-09T16:52:19.885994Z","caller":"traceutil/trace.go:171","msg":"trace[1092918966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"940.729121ms","start":"2026-05-09T16:52:18.945248Z","end":"2026-05-09T16:52:19.885977Z","steps":["trace[1092918966] 'range keys from in-memory index tree' (duration: 940.141849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:19.88606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:18.945214Z","time spent":"940.831894ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T16:52:19.885732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:18.678335Z","time spent":"1.207224992s","remote":"127.0.0.1:33964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T16:52:20.043877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532275055997374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:52:20.235202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.219326941s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:52:20.23615Z","caller":"traceutil/trace.go:171","msg":"trace[1179034803] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.220018856s","start":"2026-05-09T16:52:19.015767Z","end":"2026-05-09T16:52:20.235785Z","steps":["trace[1179034803] 'process raft request' (duration: 1.219816158s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:20.236319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.015745Z","time spent":"1.220491323s","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:20.23922Z","caller":"traceutil/trace.go:171","msg":"trace[810430015] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"349.226552ms","start":"2026-05-09T16:52:19.889979Z","end":"2026-05-09T16:52:20.239205Z","steps":["trace[810430015] 'process raft request' (duration: 349.138109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:20.23931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.889964Z","time spent":"349.302014ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:20.239391Z","caller":"traceutil/trace.go:171","msg":"trace[321228371] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:835; }","duration":"1.196305892s","start":"2026-05-09T16:52:19.043076Z","end":"2026-05-09T16:52:20.239382Z","steps":["trace[321228371] 'read index received' (duration: 1.192432732s)","trace[321228371] 'applied index is now lower than readState.Index' (duration: 3.87227ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:20.23946Z","caller":"traceutil/trace.go:171","msg":"trace[927310895] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"740.235595ms","start":"2026-05-09T16:52:19.499183Z","end":"2026-05-09T16:52:20.239418Z","steps":["trace[927310895] 'process raft request' (duration: 739.855742ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:20.239634Z","caller":"traceutil/trace.go:171","msg":"trace[1372871521] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"349.233982ms","start":"2026-05-09T16:52:19.890387Z","end":"2026-05-09T16:52:20.239621Z","steps":["trace[1372871521] 'process raft request' (duration: 348.769805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:20.239753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.890377Z","time spent":"349.323925ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:52:20.239793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196692827s","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-05-09T16:52:20.239835Z","caller":"traceutil/trace.go:171","msg":"trace[1106479513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"1.1967923s","start":"2026-05-09T16:52:19.043032Z","end":"2026-05-09T16:52:20.239824Z","steps":["trace[1106479513] 'agreement among raft nodes before linearized reading' (duration: 1.196668886s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:20.239883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.04301Z","time spent":"1.196866063s","remote":"127.0.0.1:34194","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":"warn","ts":"2026-05-09T16:52:20.239954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.43274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:52:20.239993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196918606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T16:52:20.240001Z","caller":"traceutil/trace.go:171","msg":"trace[1995142669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"352.505093ms","start":"2026-05-09T16:52:19.887485Z","end":"2026-05-09T16:52:20.23999Z","steps":["trace[1995142669] 'agreement among raft nodes before linearized reading' (duration: 352.423139ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:20.240012Z","caller":"traceutil/trace.go:171","msg":"trace[817703852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"1.196974188s","start":"2026-05-09T16:52:19.043032Z","end":"2026-05-09T16:52:20.240007Z","steps":["trace[817703852] 'agreement among raft nodes before linearized reading' (duration: 1.196915996s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:20.240031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.04302Z","time spent":"1.197007458s","remote":"127.0.0.1:34194","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-05-09T16:52:20.24003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.887465Z","time spent":"352.556964ms","remote":"127.0.0.1:33972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T16:52:20.239623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.499143Z","time spent":"740.393711ms","remote":"127.0.0.1:34194","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-05-09T16:52:20.240184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.505132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:20.240238Z","caller":"traceutil/trace.go:171","msg":"trace[1828595441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"452.580664ms","start":"2026-05-09T16:52:19.787636Z","end":"2026-05-09T16:52:20.240217Z","steps":["trace[1828595441] 'agreement among raft nodes before linearized reading' (duration: 452.366687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:20.240991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197855609s","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-05-09T16:52:20.241084Z","caller":"traceutil/trace.go:171","msg":"trace[1699073193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"1.198005564s","start":"2026-05-09T16:52:19.043045Z","end":"2026-05-09T16:52:20.241051Z","steps":["trace[1699073193] 'agreement among raft nodes before linearized reading' (duration: 1.196629044s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:20.24125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.043023Z","time spent":"1.198205842s","remote":"127.0.0.1:34194","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":"warn","ts":"2026-05-09T16:52:20.240754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:19.78762Z","time spent":"453.122703ms","remote":"127.0.0.1:33964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T16:52:34.443607Z","caller":"traceutil/trace.go:171","msg":"trace[1398202624] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"112.581458ms","start":"2026-05-09T16:52:34.330991Z","end":"2026-05-09T16:52:34.443573Z","steps":["trace[1398202624] 'process raft request' (duration: 112.279198ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:34.443645Z","caller":"traceutil/trace.go:171","msg":"trace[1620003572] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"112.550406ms","start":"2026-05-09T16:52:34.331059Z","end":"2026-05-09T16:52:34.443609Z","steps":["trace[1620003572] 'process raft request' (duration: 112.341069ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:34.443894Z","caller":"traceutil/trace.go:171","msg":"trace[803196101] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"111.858515ms","start":"2026-05-09T16:52:34.332026Z","end":"2026-05-09T16:52:34.443884Z","steps":["trace[803196101] 'process raft request' (duration: 111.435692ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:34.831568Z","caller":"traceutil/trace.go:171","msg":"trace[350682285] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"112.790554ms","start":"2026-05-09T16:52:34.718752Z","end":"2026-05-09T16:52:34.831542Z","steps":["trace[350682285] 'process raft request' (duration: 85.414136ms)","trace[350682285] 'compare' (duration: 27.179132ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:40.6643Z","caller":"traceutil/trace.go:171","msg":"trace[123374101] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"163.094009ms","start":"2026-05-09T16:52:40.501188Z","end":"2026-05-09T16:52:40.664282Z","steps":["trace[123374101] 'process raft request' (duration: 163.069209ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:40.664502Z","caller":"traceutil/trace.go:171","msg":"trace[683705071] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"163.395378ms","start":"2026-05-09T16:52:40.501085Z","end":"2026-05-09T16:52:40.664481Z","steps":["trace[683705071] 'process raft request' (duration: 163.093929ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:11.638281Z","caller":"traceutil/trace.go:171","msg":"trace[895770995] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"125.041899ms","start":"2026-05-09T16:53:11.513212Z","end":"2026-05-09T16:53:11.638254Z","steps":["trace[895770995] 'process raft request' (duration: 124.854385ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:12.81691Z","caller":"traceutil/trace.go:171","msg":"trace[2079847639] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"116.535218ms","start":"2026-05-09T16:53:12.700353Z","end":"2026-05-09T16:53:12.816889Z","steps":["trace[2079847639] 'read index received' (duration: 39.903653ms)","trace[2079847639] 'applied index is now lower than readState.Index' (duration: 76.630645ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:53:12.816975Z","caller":"traceutil/trace.go:171","msg":"trace[932005539] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"121.916048ms","start":"2026-05-09T16:53:12.695038Z","end":"2026-05-09T16:53:12.816954Z","steps":["trace[932005539] 'process raft request' (duration: 45.291272ms)","trace[932005539] 'compare' (duration: 76.476193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:53:12.817086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.713022ms","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-05-09T16:53:12.817163Z","caller":"traceutil/trace.go:171","msg":"trace[711683962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"116.821884ms","start":"2026-05-09T16:53:12.700327Z","end":"2026-05-09T16:53:12.817149Z","steps":["trace[711683962] 'agreement among raft nodes before linearized reading' (duration: 116.64272ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:12.932071Z","caller":"traceutil/trace.go:171","msg":"trace[1227244925] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"111.104108ms","start":"2026-05-09T16:53:12.820935Z","end":"2026-05-09T16:53:12.932039Z","steps":["trace[1227244925] 'process raft request' (duration: 111.038106ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:12.932107Z","caller":"traceutil/trace.go:171","msg":"trace[419267962] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"111.459076ms","start":"2026-05-09T16:53:12.820628Z","end":"2026-05-09T16:53:12.932087Z","steps":["trace[419267962] 'process raft request' (duration: 109.767908ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:02.654036Z","caller":"traceutil/trace.go:171","msg":"trace[1627165919] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"142.235396ms","start":"2026-05-09T16:54:02.511768Z","end":"2026-05-09T16:54:02.654003Z","steps":["trace[1627165919] 'process raft request' (duration: 142.040273ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:13.668181Z","caller":"traceutil/trace.go:171","msg":"trace[736056662] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"324.815681ms","start":"2026-05-09T16:54:13.343319Z","end":"2026-05-09T16:54:13.668135Z","steps":["trace[736056662] 'process raft request' (duration: 297.815101ms)","trace[736056662] 'compare' (duration: 26.736406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:13.66848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:13.343254Z","time spent":"325.063925ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:54:13.688504Z","caller":"traceutil/trace.go:171","msg":"trace[1214510161] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1410; }","duration":"302.815347ms","start":"2026-05-09T16:54:13.385657Z","end":"2026-05-09T16:54:13.688472Z","steps":["trace[1214510161] 'read index received' (duration: 255.54902ms)","trace[1214510161] 'applied index is now lower than readState.Index' (duration: 47.265387ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:13.68864Z","caller":"traceutil/trace.go:171","msg":"trace[562821027] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"344.606581ms","start":"2026-05-09T16:54:13.343997Z","end":"2026-05-09T16:54:13.688603Z","steps":["trace[562821027] 'process raft request' (duration: 344.324187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:13.68879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.097741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-09T16:54:13.688808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:13.343976Z","time spent":"344.737374ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:54:13.688837Z","caller":"traceutil/trace.go:171","msg":"trace[2049461327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"303.241244ms","start":"2026-05-09T16:54:13.385584Z","end":"2026-05-09T16:54:13.688825Z","steps":["trace[2049461327] 'agreement among raft nodes before linearized reading' (duration: 303.031111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:13.688891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:13.385558Z","time spent":"303.323394ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T16:54:15.309807Z","caller":"traceutil/trace.go:171","msg":"trace[1331111107] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"283.653139ms","start":"2026-05-09T16:54:15.026134Z","end":"2026-05-09T16:54:15.309787Z","steps":["trace[1331111107] 'process raft request' (duration: 283.469266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:15.653815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.262191ms","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-05-09T16:54:15.65395Z","caller":"traceutil/trace.go:171","msg":"trace[173045411] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1358; }","duration":"152.433564ms","start":"2026-05-09T16:54:15.501498Z","end":"2026-05-09T16:54:15.653932Z","steps":["trace[173045411] 'range keys from in-memory index tree' (duration: 152.241511ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:15.654632Z","caller":"traceutil/trace.go:171","msg":"trace[1165413053] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"279.176261ms","start":"2026-05-09T16:54:15.375419Z","end":"2026-05-09T16:54:15.654596Z","steps":["trace[1165413053] 'process raft request' (duration: 259.690888ms)","trace[1165413053] 'compare' (duration: 18.661511ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:16.075609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.772638ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T16:54:16.075763Z","caller":"traceutil/trace.go:171","msg":"trace[226831577] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"402.561823ms","start":"2026-05-09T16:54:15.673175Z","end":"2026-05-09T16:54:16.075736Z","steps":["trace[226831577] 'read index received' (duration: 60.624563ms)","trace[226831577] 'applied index is now lower than readState.Index' (duration: 341.93452ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:16.076269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.06159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T16:54:16.076439Z","caller":"traceutil/trace.go:171","msg":"trace[174469507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1359; }","duration":"403.231162ms","start":"2026-05-09T16:54:15.673123Z","end":"2026-05-09T16:54:16.076354Z","steps":["trace[174469507] 'agreement among raft nodes before linearized reading' (duration: 402.919518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:16.076625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:15.673101Z","time spent":"403.378975ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T16:54:16.248605Z","caller":"traceutil/trace.go:171","msg":"trace[448597472] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"172.777549ms","start":"2026-05-09T16:54:16.075801Z","end":"2026-05-09T16:54:16.248579Z","steps":["trace[448597472] 'read index received' (duration: 172.602927ms)","trace[448597472] 'applied index is now lower than readState.Index' (duration: 173.292µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:16.248728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.459643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:54:16.248773Z","caller":"traceutil/trace.go:171","msg":"trace[1049099521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"569.530974ms","start":"2026-05-09T16:54:15.679225Z","end":"2026-05-09T16:54:16.248756Z","steps":["trace[1049099521] 'agreement among raft nodes before linearized reading' (duration: 569.450743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:16.248919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.029906ms","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-05-09T16:54:16.248983Z","caller":"traceutil/trace.go:171","msg":"trace[1404704575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1360; }","duration":"555.137557ms","start":"2026-05-09T16:54:15.693831Z","end":"2026-05-09T16:54:16.248968Z","steps":["trace[1404704575] 'agreement among raft nodes before linearized reading' (duration: 554.948154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:16.249016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:15.693813Z","time spent":"555.196868ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T16:54:16.24901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:15.679215Z","time spent":"569.601755ms","remote":"127.0.0.1:33964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T16:54:16.249039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.950317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T16:54:16.249163Z","caller":"traceutil/trace.go:171","msg":"trace[77038097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1360; }","duration":"506.14563ms","start":"2026-05-09T16:54:15.742993Z","end":"2026-05-09T16:54:16.249139Z","steps":["trace[77038097] 'agreement among raft nodes before linearized reading' (duration: 505.762885ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:16.249184Z","caller":"traceutil/trace.go:171","msg":"trace[1037155034] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"543.897828ms","start":"2026-05-09T16:54:15.705272Z","end":"2026-05-09T16:54:16.249169Z","steps":["trace[1037155034] 'process raft request' (duration: 543.154267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:16.249237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:15.742975Z","time spent":"506.251422ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T16:54:16.249311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:15.705237Z","time spent":"543.98872ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:54:16.347093Z","caller":"traceutil/trace.go:171","msg":"trace[485577722] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"266.872198ms","start":"2026-05-09T16:54:16.080165Z","end":"2026-05-09T16:54:16.347037Z","steps":["trace[485577722] 'process raft request' (duration: 220.054396ms)","trace[485577722] 'compare' (duration: 46.471627ms)"],"step_count":2}