{"level":"warn","ts":"2026-03-11T05:06:55.482965Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T05:06:55.483044Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.67: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.67:2380","--initial-cluster=instance=https://199.204.45.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.67: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-03-11T05:06:55.483099Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T05:06:55.483108Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-03-11T05:06:55.483123Z","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-03-11T05:06:55.483846Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"]} {"level":"info","ts":"2026-03-11T05:06:55.483913Z","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.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.67: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-03-11T05:06:55.49311Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.000662ms"} {"level":"info","ts":"2026-03-11T05:06:55.50718Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cddd08233fdda28a","cluster-id":"72e3bf4964adc703"} {"level":"info","ts":"2026-03-11T05:06:55.507453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=()"} {"level":"info","ts":"2026-03-11T05:06:55.507543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 0"} {"level":"info","ts":"2026-03-11T05:06:55.507606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cddd08233fdda28a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-11T05:06:55.507668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 1"} {"level":"info","ts":"2026-03-11T05:06:55.507819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"warn","ts":"2026-03-11T05:06:55.530512Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-11T05:06:55.543738Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-11T05:06:55.545588Z","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-03-11T05:06:55.549239Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cddd08233fdda28a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-11T05:06:55.551957Z","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-03-11T05:06:55.552048Z","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-03-11T05:06:55.55206Z","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-03-11T05:06:55.553271Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cddd08233fdda28a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-11T05:06:55.55682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"info","ts":"2026-03-11T05:06:55.557079Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","added-peer-id":"cddd08233fdda28a","added-peer-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-03-11T05:06:55.558468Z","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-03-11T05:06:55.558766Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cddd08233fdda28a","initial-advertise-peer-urls":["https://199.204.45.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-11T05:06:55.55878Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-03-11T05:06:55.558886Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-03-11T05:06:55.558862Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-11T05:06:56.112757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a is starting a new election at term 1"} {"level":"info","ts":"2026-03-11T05:06:56.112871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-11T05:06:56.112922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgPreVoteResp from cddd08233fdda28a at term 1"} {"level":"info","ts":"2026-03-11T05:06:56.112959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became candidate at term 2"} {"level":"info","ts":"2026-03-11T05:06:56.11298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgVoteResp from cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-03-11T05:06:56.113008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became leader at term 2"} {"level":"info","ts":"2026-03-11T05:06:56.113031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cddd08233fdda28a elected leader cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-03-11T05:06:56.116003Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T05:06:56.120416Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cddd08233fdda28a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.67:2379]}","request-path":"/0/members/cddd08233fdda28a/attributes","cluster-id":"72e3bf4964adc703","publish-timeout":"7s"} {"level":"info","ts":"2026-03-11T05:06:56.120575Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T05:06:56.120663Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T05:06:56.120927Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T05:06:56.120993Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T05:06:56.121011Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T05:06:56.121063Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-11T05:06:56.121074Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-11T05:06:56.122813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-11T05:06:56.124839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.67:2379"} {"level":"info","ts":"2026-03-11T05:06:57.631447Z","caller":"traceutil/trace.go:171","msg":"trace[1350916945] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"131.774603ms","start":"2026-03-11T05:06:57.499646Z","end":"2026-03-11T05:06:57.631421Z","steps":["trace[1350916945] 'process raft request' (duration: 47.669698ms)","trace[1350916945] 'compare' (duration: 83.677187ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:06:57.631532Z","caller":"traceutil/trace.go:171","msg":"trace[240549627] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:7; }","duration":"130.701183ms","start":"2026-03-11T05:06:57.500808Z","end":"2026-03-11T05:06:57.631509Z","steps":["trace[240549627] 'read index received' (duration: 46.67782ms)","trace[240549627] 'applied index is now lower than readState.Index' (duration: 84.022433ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:06:57.631663Z","caller":"traceutil/trace.go:171","msg":"trace[1762797217] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"131.502628ms","start":"2026-03-11T05:06:57.500124Z","end":"2026-03-11T05:06:57.631627Z","steps":["trace[1762797217] 'process raft request' (duration: 131.315505ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:57.631846Z","caller":"traceutil/trace.go:171","msg":"trace[1398917709] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"131.968667ms","start":"2026-03-11T05:06:57.499868Z","end":"2026-03-11T05:06:57.631836Z","steps":["trace[1398917709] 'process raft request' (duration: 131.470938ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:57.63186Z","caller":"traceutil/trace.go:171","msg":"trace[875886682] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"131.758763ms","start":"2026-03-11T05:06:57.499818Z","end":"2026-03-11T05:06:57.631577Z","steps":["trace[875886682] 'process raft request' (duration: 131.439497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:57.63191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.824144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-11T05:06:57.632011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.382214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T05:06:57.63203Z","caller":"traceutil/trace.go:171","msg":"trace[703090738] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"132.031818ms","start":"2026-03-11T05:06:57.499976Z","end":"2026-03-11T05:06:57.632007Z","steps":["trace[703090738] 'agreement among raft nodes before linearized reading' (duration: 131.780614ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:57.632047Z","caller":"traceutil/trace.go:171","msg":"trace[919404585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9; }","duration":"132.452777ms","start":"2026-03-11T05:06:57.499583Z","end":"2026-03-11T05:06:57.632036Z","steps":["trace[919404585] 'agreement among raft nodes before linearized reading' (duration: 132.373214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:57.631977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.310784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T05:06:57.631935Z","caller":"traceutil/trace.go:171","msg":"trace[618016495] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"131.869894ms","start":"2026-03-11T05:06:57.499993Z","end":"2026-03-11T05:06:57.631862Z","steps":["trace[618016495] 'process raft request' (duration: 131.395596ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:57.632158Z","caller":"traceutil/trace.go:171","msg":"trace[1192417372] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"131.62524ms","start":"2026-03-11T05:06:57.500517Z","end":"2026-03-11T05:06:57.632142Z","steps":["trace[1192417372] 'agreement among raft nodes before linearized reading' (duration: 131.183192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:57.632393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.313724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T05:06:57.632429Z","caller":"traceutil/trace.go:171","msg":"trace[518115164] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"131.915786ms","start":"2026-03-11T05:06:57.500503Z","end":"2026-03-11T05:06:57.632419Z","steps":["trace[518115164] 'agreement among raft nodes before linearized reading' (duration: 131.314765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.241906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.099575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-11T05:06:58.242212Z","caller":"traceutil/trace.go:171","msg":"trace[1022026052] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"738.058427ms","start":"2026-03-11T05:06:57.50411Z","end":"2026-03-11T05:06:58.242168Z","steps":["trace[1022026052] 'process raft request' (duration: 602.239219ms)","trace[1022026052] 'compare' (duration: 134.934563ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:06:58.242349Z","caller":"traceutil/trace.go:171","msg":"trace[47558894] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"730.391931ms","start":"2026-03-11T05:06:57.511926Z","end":"2026-03-11T05:06:58.242318Z","steps":["trace[47558894] 'process raft request' (duration: 730.084425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.242391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.50409Z","time spent":"738.208569ms","remote":"127.0.0.1:50646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T05:06:58.24234Z","caller":"traceutil/trace.go:171","msg":"trace[991321493] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:12; }","duration":"610.632781ms","start":"2026-03-11T05:06:57.631672Z","end":"2026-03-11T05:06:58.242305Z","steps":["trace[991321493] 'read index received' (duration: 474.64198ms)","trace[991321493] 'applied index is now lower than readState.Index' (duration: 135.987481ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:06:58.242505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.511912Z","time spent":"730.525363ms","remote":"127.0.0.1:50276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T05:06:58.242657Z","caller":"traceutil/trace.go:171","msg":"trace[78316746] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"605.962347ms","start":"2026-03-11T05:06:57.636676Z","end":"2026-03-11T05:06:58.242638Z","steps":["trace[78316746] 'process raft request' (duration: 605.905686ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:58.242745Z","caller":"traceutil/trace.go:171","msg":"trace[1294035746] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"607.974525ms","start":"2026-03-11T05:06:57.634762Z","end":"2026-03-11T05:06:58.242736Z","steps":["trace[1294035746] 'process raft request' (duration: 607.482195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.242787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.636668Z","time spent":"606.07661ms","remote":"127.0.0.1:50646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T05:06:58.242807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.634748Z","time spent":"608.027016ms","remote":"127.0.0.1:50206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T05:06:58.242782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.991519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-11T05:06:58.242867Z","caller":"traceutil/trace.go:171","msg":"trace[46593386] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:14; }","duration":"691.171584ms","start":"2026-03-11T05:06:57.551675Z","end":"2026-03-11T05:06:58.242846Z","steps":["trace[46593386] 'agreement among raft nodes before linearized reading' (duration: 690.788466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.242916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.551637Z","time spent":"691.267535ms","remote":"127.0.0.1:50206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-11T05:06:58.24267Z","caller":"traceutil/trace.go:171","msg":"trace[685541765] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"607.836791ms","start":"2026-03-11T05:06:57.634812Z","end":"2026-03-11T05:06:58.242648Z","steps":["trace[685541765] 'process raft request' (duration: 607.481655ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:58.242909Z","caller":"traceutil/trace.go:171","msg":"trace[840554207] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"606.438397ms","start":"2026-03-11T05:06:57.636461Z","end":"2026-03-11T05:06:58.242899Z","steps":["trace[840554207] 'process raft request' (duration: 605.944617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.2431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.636439Z","time spent":"606.63473ms","remote":"127.0.0.1:50646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T05:06:58.243133Z","caller":"traceutil/trace.go:171","msg":"trace[605143058] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"606.120591ms","start":"2026-03-11T05:06:57.636998Z","end":"2026-03-11T05:06:58.243119Z","steps":["trace[605143058] 'process raft request' (duration: 605.615162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.243177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.634808Z","time spent":"608.25786ms","remote":"127.0.0.1:50206","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T05:06:58.24325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.636978Z","time spent":"606.237203ms","remote":"127.0.0.1:50646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T05:06:58.244555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.186834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T05:06:58.244619Z","caller":"traceutil/trace.go:171","msg":"trace[58546267] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:17; }","duration":"564.283356ms","start":"2026-03-11T05:06:57.680325Z","end":"2026-03-11T05:06:58.244608Z","steps":["trace[58546267] 'agreement among raft nodes before linearized reading' (duration: 564.147224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.244645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.680308Z","time spent":"564.330037ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-03-11T05:06:58.244761Z","caller":"traceutil/trace.go:171","msg":"trace[367947609] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"607.620737ms","start":"2026-03-11T05:06:57.637134Z","end":"2026-03-11T05:06:58.244755Z","steps":["trace[367947609] 'process raft request' (duration: 605.888507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.244819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.637123Z","time spent":"607.669237ms","remote":"127.0.0.1:50646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T05:06:58.244981Z","caller":"traceutil/trace.go:171","msg":"trace[1707310490] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"607.850781ms","start":"2026-03-11T05:06:57.637124Z","end":"2026-03-11T05:06:58.244975Z","steps":["trace[1707310490] 'process raft request' (duration: 607.288411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.245026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.637085Z","time spent":"607.918642ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T05:06:58.245166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.404872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T05:06:58.245196Z","caller":"traceutil/trace.go:171","msg":"trace[2004942804] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:15; }","duration":"726.275526ms","start":"2026-03-11T05:06:57.518912Z","end":"2026-03-11T05:06:58.245188Z","steps":["trace[2004942804] 'agreement among raft nodes before linearized reading' (duration: 723.766622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.245219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:06:57.5189Z","time spent":"726.312058ms","remote":"127.0.0.1:50538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-03-11T05:06:58.480688Z","caller":"traceutil/trace.go:171","msg":"trace[2063966006] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"194.302661ms","start":"2026-03-11T05:06:58.286338Z","end":"2026-03-11T05:06:58.480641Z","steps":["trace[2063966006] 'process raft request' (duration: 162.340687ms)","trace[2063966006] 'compare' (duration: 31.685929ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:06:58.480762Z","caller":"traceutil/trace.go:171","msg":"trace[1147864650] linearizableReadLoop","detail":"{readStateIndex:40; appliedIndex:34; }","duration":"192.018371ms","start":"2026-03-11T05:06:58.288719Z","end":"2026-03-11T05:06:58.480738Z","steps":["trace[1147864650] 'read index received' (duration: 28.932211ms)","trace[1147864650] 'applied index is now lower than readState.Index' (duration: 163.084869ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:06:58.480792Z","caller":"traceutil/trace.go:171","msg":"trace[1731013235] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"193.769662ms","start":"2026-03-11T05:06:58.286999Z","end":"2026-03-11T05:06:58.480769Z","steps":["trace[1731013235] 'process raft request' (duration: 193.512038ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:58.480877Z","caller":"traceutil/trace.go:171","msg":"trace[973355531] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"193.839884ms","start":"2026-03-11T05:06:58.287002Z","end":"2026-03-11T05:06:58.480842Z","steps":["trace[973355531] 'process raft request' (duration: 193.572369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:06:58.481116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.331097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-11T05:06:58.481293Z","caller":"traceutil/trace.go:171","msg":"trace[1597138501] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:36; }","duration":"192.51091ms","start":"2026-03-11T05:06:58.288694Z","end":"2026-03-11T05:06:58.481205Z","steps":["trace[1597138501] 'agreement among raft nodes before linearized reading' (duration: 192.217085ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:58.481174Z","caller":"traceutil/trace.go:171","msg":"trace[1051943447] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"193.893645ms","start":"2026-03-11T05:06:58.287274Z","end":"2026-03-11T05:06:58.481167Z","steps":["trace[1051943447] 'process raft request' (duration: 193.410966ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:58.731881Z","caller":"traceutil/trace.go:171","msg":"trace[1289514140] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"161.227946ms","start":"2026-03-11T05:06:58.57063Z","end":"2026-03-11T05:06:58.731858Z","steps":["trace[1289514140] 'process raft request' (duration: 161.158385ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:58.731944Z","caller":"traceutil/trace.go:171","msg":"trace[1564573924] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"162.225884ms","start":"2026-03-11T05:06:58.5697Z","end":"2026-03-11T05:06:58.731926Z","steps":["trace[1564573924] 'process raft request' (duration: 162.029141ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:06:58.731974Z","caller":"traceutil/trace.go:171","msg":"trace[652393111] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"166.241295ms","start":"2026-03-11T05:06:58.565702Z","end":"2026-03-11T05:06:58.731943Z","steps":["trace[652393111] 'process raft request' (duration: 115.56423ms)","trace[652393111] 'compare' (duration: 50.282408ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:06:59.556768Z","caller":"traceutil/trace.go:171","msg":"trace[1785572067] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"119.972534ms","start":"2026-03-11T05:06:59.436767Z","end":"2026-03-11T05:06:59.556739Z","steps":["trace[1785572067] 'process raft request' (duration: 51.679455ms)","trace[1785572067] 'compare' (duration: 68.122366ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:06:59.820759Z","caller":"traceutil/trace.go:171","msg":"trace[547669608] linearizableReadLoop","detail":"{readStateIndex:137; appliedIndex:136; }","duration":"184.278422ms","start":"2026-03-11T05:06:59.636454Z","end":"2026-03-11T05:06:59.820732Z","steps":["trace[547669608] 'read index received' (duration: 122.989437ms)","trace[547669608] 'applied index is now lower than readState.Index' (duration: 61.288095ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:06:59.820894Z","caller":"traceutil/trace.go:171","msg":"trace[467548729] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"257.973725ms","start":"2026-03-11T05:06:59.562895Z","end":"2026-03-11T05:06:59.820868Z","steps":["trace[467548729] 'process raft request' (duration: 196.537386ms)","trace[467548729] 'compare' (duration: 61.133834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:06:59.820955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.478446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T05:06:59.821056Z","caller":"traceutil/trace.go:171","msg":"trace[333466016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:133; }","duration":"184.614058ms","start":"2026-03-11T05:06:59.636404Z","end":"2026-03-11T05:06:59.821018Z","steps":["trace[333466016] 'agreement among raft nodes before linearized reading' (duration: 184.425795ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:00.002646Z","caller":"traceutil/trace.go:171","msg":"trace[141212817] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"109.074134ms","start":"2026-03-11T05:06:59.893545Z","end":"2026-03-11T05:07:00.002619Z","steps":["trace[141212817] 'process raft request' (duration: 98.843534ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:00.002934Z","caller":"traceutil/trace.go:171","msg":"trace[317224751] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"108.688637ms","start":"2026-03-11T05:06:59.894213Z","end":"2026-03-11T05:07:00.002901Z","steps":["trace[317224751] 'process raft request' (duration: 108.340671ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:00.317284Z","caller":"traceutil/trace.go:171","msg":"trace[2100342078] linearizableReadLoop","detail":"{readStateIndex:147; appliedIndex:146; }","duration":"219.174296ms","start":"2026-03-11T05:07:00.098084Z","end":"2026-03-11T05:07:00.317258Z","steps":["trace[2100342078] 'read index received' (duration: 215.613044ms)","trace[2100342078] 'applied index is now lower than readState.Index' (duration: 3.560142ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:07:00.31741Z","caller":"traceutil/trace.go:171","msg":"trace[1756938268] transaction","detail":"{read_only:false; response_revision:143; number_of_response:1; }","duration":"243.017271ms","start":"2026-03-11T05:07:00.074364Z","end":"2026-03-11T05:07:00.317381Z","steps":["trace[1756938268] 'process raft request' (duration: 239.649743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:00.317534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.38588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T05:07:00.317731Z","caller":"traceutil/trace.go:171","msg":"trace[1488243768] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:0; response_revision:143; }","duration":"219.653734ms","start":"2026-03-11T05:07:00.09806Z","end":"2026-03-11T05:07:00.317714Z","steps":["trace[1488243768] 'agreement among raft nodes before linearized reading' (duration: 219.291368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:01.057233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.163915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-11T05:07:01.057427Z","caller":"traceutil/trace.go:171","msg":"trace[878898228] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"268.669693ms","start":"2026-03-11T05:07:00.788737Z","end":"2026-03-11T05:07:01.057407Z","steps":["trace[878898228] 'process raft request' (duration: 51.253944ms)","trace[878898228] 'compare' (duration: 216.999642ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:07:01.490029Z","caller":"traceutil/trace.go:171","msg":"trace[1959342113] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"101.116088ms","start":"2026-03-11T05:07:01.388881Z","end":"2026-03-11T05:07:01.489997Z","steps":["trace[1959342113] 'process raft request' (duration: 61.228478ms)","trace[1959342113] 'compare' (duration: 39.605515ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:07:02.588159Z","caller":"traceutil/trace.go:171","msg":"trace[321040599] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"445.239646ms","start":"2026-03-11T05:07:02.142898Z","end":"2026-03-11T05:07:02.588138Z","steps":["trace[321040599] 'process raft request' (duration: 445.085593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:02.58844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:02.14287Z","time spent":"445.354319ms","remote":"127.0.0.1:50276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T05:07:02.589064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:02.246727Z","time spent":"342.332799ms","remote":"127.0.0.1:50174","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-11T05:07:03.308877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.871562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T05:07:03.309004Z","caller":"traceutil/trace.go:171","msg":"trace[31257447] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:240; }","duration":"260.762529ms","start":"2026-03-11T05:07:03.048219Z","end":"2026-03-11T05:07:03.308981Z","steps":["trace[31257447] 'read index received' (duration: 54.461µs)","trace[31257447] 'applied index is now lower than readState.Index' (duration: 260.706668ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:07:03.309165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.160706ms","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-03-11T05:07:03.309225Z","caller":"traceutil/trace.go:171","msg":"trace[13713954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:235; }","duration":"220.258618ms","start":"2026-03-11T05:07:03.088948Z","end":"2026-03-11T05:07:03.309206Z","steps":["trace[13713954] 'agreement among raft nodes before linearized reading' (duration: 220.129906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:03.309189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.971763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-03-11T05:07:03.309369Z","caller":"traceutil/trace.go:171","msg":"trace[1500595731] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:235; }","duration":"261.189077ms","start":"2026-03-11T05:07:03.048162Z","end":"2026-03-11T05:07:03.309352Z","steps":["trace[1500595731] 'agreement among raft nodes before linearized reading' (duration: 260.917772ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:03.309484Z","caller":"traceutil/trace.go:171","msg":"trace[173592520] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"413.930405ms","start":"2026-03-11T05:07:02.895448Z","end":"2026-03-11T05:07:03.309378Z","steps":["trace[173592520] 'process raft request' (duration: 30.454733ms)","trace[173592520] 'compare' (duration: 382.74902ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:07:03.30957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.264269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-03-11T05:07:03.309698Z","caller":"traceutil/trace.go:171","msg":"trace[444718045] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:235; }","duration":"162.446702ms","start":"2026-03-11T05:07:03.147224Z","end":"2026-03-11T05:07:03.309671Z","steps":["trace[444718045] 'agreement among raft nodes before linearized reading' (duration: 162.193998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:03.309875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:02.895433Z","time spent":"414.123848ms","remote":"127.0.0.1:50174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T05:07:04.51756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346246591256876,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T05:07:05.018548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346246591256876,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T05:07:05.519713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346246591256876,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T05:07:06.020693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346246591256876,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T05:07:06.449577Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.131950439s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-11T05:07:07.519359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069378588s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T05:07:07.519478Z","caller":"traceutil/trace.go:171","msg":"trace[1346608478] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"4.202025798s","start":"2026-03-11T05:07:03.317433Z","end":"2026-03-11T05:07:07.519459Z","steps":["trace[1346608478] 'process raft request' (duration: 3.132466247s)","trace[1346608478] 'compare' (duration: 1.068574005s)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:07:07.519542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:03.317406Z","time spent":"4.20210749s","remote":"127.0.0.1:50276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T05:07:08.440895Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.991039617s","expected-duration":"1s"} {"level":"info","ts":"2026-03-11T05:07:08.473537Z","caller":"traceutil/trace.go:171","msg":"trace[737334501] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"5.155387021s","start":"2026-03-11T05:07:03.318112Z","end":"2026-03-11T05:07:08.473499Z","steps":["trace[737334501] 'process raft request' (duration: 5.123154908s)","trace[737334501] 'compare' (duration: 31.910527ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:07:08.473597Z","caller":"traceutil/trace.go:171","msg":"trace[1355956360] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"5.155255389s","start":"2026-03-11T05:07:03.318322Z","end":"2026-03-11T05:07:08.473578Z","steps":["trace[1355956360] 'process raft request' (duration: 5.155075537s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:08.473687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:03.318094Z","time spent":"5.155518893s","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T05:07:08.473691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:03.318316Z","time spent":"5.15532314s","remote":"127.0.0.1:50174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T05:07:08.473816Z","caller":"traceutil/trace.go:171","msg":"trace[105601165] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"5.145254298s","start":"2026-03-11T05:07:03.328541Z","end":"2026-03-11T05:07:08.473795Z","steps":["trace[105601165] 'process raft request' (duration: 5.144957873s)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:08.473893Z","caller":"traceutil/trace.go:171","msg":"trace[1598455280] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"2.417463304s","start":"2026-03-11T05:07:06.056407Z","end":"2026-03-11T05:07:08.47387Z","steps":["trace[1598455280] 'process raft request' (duration: 2.417164119s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:08.473947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:03.328514Z","time spent":"5.14535927s","remote":"127.0.0.1:50290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T05:07:08.473967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:06.056376Z","time spent":"2.417557006s","remote":"127.0.0.1:50290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T05:07:08.473948Z","caller":"traceutil/trace.go:171","msg":"trace[573983692] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"4.564056372s","start":"2026-03-11T05:07:03.909839Z","end":"2026-03-11T05:07:08.473895Z","steps":["trace[573983692] 'process raft request' (duration: 4.563703576s)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:08.474117Z","caller":"traceutil/trace.go:171","msg":"trace[225946810] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:241; }","duration":"4.457594596s","start":"2026-03-11T05:07:04.01651Z","end":"2026-03-11T05:07:08.474105Z","steps":["trace[225946810] 'read index received' (duration: 2.433372491s)","trace[225946810] 'applied index is now lower than readState.Index' (duration: 2.024220765s)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:07:08.474126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:03.90981Z","time spent":"4.564233894s","remote":"127.0.0.1:50290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T05:07:08.474229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.457722778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-11T05:07:08.47426Z","caller":"traceutil/trace.go:171","msg":"trace[948809111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:242; }","duration":"4.457785958s","start":"2026-03-11T05:07:04.016465Z","end":"2026-03-11T05:07:08.474251Z","steps":["trace[948809111] 'agreement among raft nodes before linearized reading' (duration: 4.457721307s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:08.474289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:04.016443Z","time spent":"4.45783961s","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-11T05:07:08.474308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.1987697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T05:07:08.474348Z","caller":"traceutil/trace.go:171","msg":"trace[1792099254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:242; }","duration":"4.198836261s","start":"2026-03-11T05:07:04.2755Z","end":"2026-03-11T05:07:08.474336Z","steps":["trace[1792099254] 'agreement among raft nodes before linearized reading' (duration: 4.19877876s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:08.474378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:04.275479Z","time spent":"4.198892382s","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-11T05:07:08.474502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.433050688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T05:07:08.474532Z","caller":"traceutil/trace.go:171","msg":"trace[1043905564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:242; }","duration":"3.433120959s","start":"2026-03-11T05:07:05.041402Z","end":"2026-03-11T05:07:08.474523Z","steps":["trace[1043905564] 'agreement among raft nodes before linearized reading' (duration: 3.433075428s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:08.474525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.079806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T05:07:08.474535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.481935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-rant00\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T05:07:08.474563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:05.041381Z","time spent":"3.43317585s","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-11T05:07:08.474579Z","caller":"traceutil/trace.go:171","msg":"trace[1137334791] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:242; }","duration":"980.183518ms","start":"2026-03-11T05:07:07.494379Z","end":"2026-03-11T05:07:08.474562Z","steps":["trace[1137334791] 'agreement among raft nodes before linearized reading' (duration: 980.066146ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:08.474584Z","caller":"traceutil/trace.go:171","msg":"trace[212130726] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-rant00; range_end:; response_count:0; response_revision:242; }","duration":"950.569786ms","start":"2026-03-11T05:07:07.523995Z","end":"2026-03-11T05:07:08.474565Z","steps":["trace[212130726] 'agreement among raft nodes before linearized reading' (duration: 950.488775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:08.47462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:07.494355Z","time spent":"980.255029ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-03-11T05:07:08.474625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:07.523977Z","time spent":"950.639657ms","remote":"127.0.0.1:50192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-rant00\" "} {"level":"info","ts":"2026-03-11T05:07:10.193243Z","caller":"traceutil/trace.go:171","msg":"trace[239008979] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"129.392218ms","start":"2026-03-11T05:07:10.063831Z","end":"2026-03-11T05:07:10.193224Z","steps":["trace[239008979] 'process raft request' (duration: 110.343689ms)","trace[239008979] 'compare' (duration: 18.815526ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:07:10.193434Z","caller":"traceutil/trace.go:171","msg":"trace[795750950] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"104.771448ms","start":"2026-03-11T05:07:10.088624Z","end":"2026-03-11T05:07:10.193396Z","steps":["trace[795750950] 'process raft request' (duration: 104.471322ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:10.364024Z","caller":"traceutil/trace.go:171","msg":"trace[1341431208] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"100.66395ms","start":"2026-03-11T05:07:10.263325Z","end":"2026-03-11T05:07:10.363989Z","steps":["trace[1341431208] 'process raft request' (duration: 68.898593ms)","trace[1341431208] 'compare' (duration: 31.480143ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:07:11.316909Z","caller":"traceutil/trace.go:171","msg":"trace[1590188787] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"128.39799ms","start":"2026-03-11T05:07:11.188487Z","end":"2026-03-11T05:07:11.316885Z","steps":["trace[1590188787] 'process raft request' (duration: 127.243861ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:32.756057Z","caller":"traceutil/trace.go:171","msg":"trace[917396180] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"120.352661ms","start":"2026-03-11T05:07:32.635685Z","end":"2026-03-11T05:07:32.756038Z","steps":["trace[917396180] 'process raft request' (duration: 120.212419ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:32.756852Z","caller":"traceutil/trace.go:171","msg":"trace[977764687] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"120.723507ms","start":"2026-03-11T05:07:32.636116Z","end":"2026-03-11T05:07:32.756839Z","steps":["trace[977764687] 'process raft request' (duration: 120.562924ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:33.682665Z","caller":"traceutil/trace.go:171","msg":"trace[336947117] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"220.74745ms","start":"2026-03-11T05:07:33.461895Z","end":"2026-03-11T05:07:33.682642Z","steps":["trace[336947117] 'read index received' (duration: 220.606937ms)","trace[336947117] 'applied index is now lower than readState.Index' (duration: 139.283µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:07:33.68279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.874082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T05:07:33.682821Z","caller":"traceutil/trace.go:171","msg":"trace[62487283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"220.950203ms","start":"2026-03-11T05:07:33.461862Z","end":"2026-03-11T05:07:33.682812Z","steps":["trace[62487283] 'agreement among raft nodes before linearized reading' (duration: 220.882242ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:33.683003Z","caller":"traceutil/trace.go:171","msg":"trace[793077026] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"322.893063ms","start":"2026-03-11T05:07:33.360079Z","end":"2026-03-11T05:07:33.682973Z","steps":["trace[793077026] 'process raft request' (duration: 322.405175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:33.683242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:33.360052Z","time spent":"323.075945ms","remote":"127.0.0.1:50276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4566,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T05:07:36.879873Z","caller":"traceutil/trace.go:171","msg":"trace[1210321250] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"100.428387ms","start":"2026-03-11T05:07:36.779419Z","end":"2026-03-11T05:07:36.879848Z","steps":["trace[1210321250] 'process raft request' (duration: 100.241264ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:39.479378Z","caller":"traceutil/trace.go:171","msg":"trace[2124237385] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"161.06125ms","start":"2026-03-11T05:07:39.318278Z","end":"2026-03-11T05:07:39.479339Z","steps":["trace[2124237385] 'process raft request' (duration: 160.912568ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:41.215582Z","caller":"traceutil/trace.go:171","msg":"trace[1257855043] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"315.455613ms","start":"2026-03-11T05:07:40.900108Z","end":"2026-03-11T05:07:41.215564Z","steps":["trace[1257855043] 'read index received' (duration: 315.31904ms)","trace[1257855043] 'applied index is now lower than readState.Index' (duration: 135.683µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:07:41.215682Z","caller":"traceutil/trace.go:171","msg":"trace[1076279074] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"316.708539ms","start":"2026-03-11T05:07:40.898967Z","end":"2026-03-11T05:07:41.215675Z","steps":["trace[1076279074] 'process raft request' (duration: 316.475825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:41.215776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:40.898954Z","time spent":"316.76257ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T05:07:41.216122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.561443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-69x42\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-11T05:07:41.216159Z","caller":"traceutil/trace.go:171","msg":"trace[1956746780] range","detail":"{range_begin:/registry/pods/kube-system/cilium-69x42; range_end:; response_count:1; response_revision:570; }","duration":"134.619925ms","start":"2026-03-11T05:07:41.081532Z","end":"2026-03-11T05:07:41.216152Z","steps":["trace[1956746780] 'agreement among raft nodes before linearized reading' (duration: 134.518953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:41.216287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.185112ms","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-03-11T05:07:41.216304Z","caller":"traceutil/trace.go:171","msg":"trace[467248716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"316.224903ms","start":"2026-03-11T05:07:40.900074Z","end":"2026-03-11T05:07:41.216299Z","steps":["trace[467248716] 'agreement among raft nodes before linearized reading' (duration: 316.174832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:41.216323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:40.900055Z","time spent":"316.262223ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-11T05:07:41.216554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.072396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-11T05:07:41.216572Z","caller":"traceutil/trace.go:171","msg":"trace[431724125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"312.103606ms","start":"2026-03-11T05:07:40.904463Z","end":"2026-03-11T05:07:41.216567Z","steps":["trace[431724125] 'agreement among raft nodes before linearized reading' (duration: 312.052626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:41.216618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:40.904458Z","time spent":"312.156437ms","remote":"127.0.0.1:50398","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-03-11T05:07:41.216935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.12136ms","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-03-11T05:07:41.21704Z","caller":"traceutil/trace.go:171","msg":"trace[1305313143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"313.252021ms","start":"2026-03-11T05:07:40.903764Z","end":"2026-03-11T05:07:41.217016Z","steps":["trace[1305313143] 'agreement among raft nodes before linearized reading' (duration: 312.119836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:41.217096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:40.903755Z","time spent":"313.327162ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-11T05:07:41.962044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346246591257552,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T05:07:42.346753Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.025763329s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-11T05:07:43.461696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000148325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-11T05:07:43.46177Z","caller":"traceutil/trace.go:171","msg":"trace[2076370803] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000240077s","start":"2026-03-11T05:07:41.461515Z","end":"2026-03-11T05:07:43.461755Z","steps":["trace[2076370803] 'agreement among raft nodes before linearized reading' (duration: 2.000143235s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:43.461809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:41.461458Z","time spent":"2.000343617s","remote":"127.0.0.1:50110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T05:07:43.740718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.270466995s","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-03-11T05:07:43.740814Z","caller":"traceutil/trace.go:171","msg":"trace[1425281114] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:571; }","duration":"2.270568317s","start":"2026-03-11T05:07:41.470228Z","end":"2026-03-11T05:07:43.740796Z","steps":["trace[1425281114] 'range keys from in-memory index tree' (duration: 2.270454775s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:43.741019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394018062s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T05:07:43.741126Z","caller":"traceutil/trace.go:171","msg":"trace[1476405729] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"2.520445369s","start":"2026-03-11T05:07:41.220669Z","end":"2026-03-11T05:07:43.741114Z","steps":["trace[1476405729] 'process raft request' (duration: 1.126240635s)","trace[1476405729] 'compare' (duration: 1.39391661s)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:07:43.741174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:41.22066Z","time spent":"2.520493029s","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T05:07:43.741407Z","caller":"traceutil/trace.go:171","msg":"trace[485164400] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"2.520075502s","start":"2026-03-11T05:07:41.221322Z","end":"2026-03-11T05:07:43.741397Z","steps":["trace[485164400] 'process raft request' (duration: 2.519761418s)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:43.741445Z","caller":"traceutil/trace.go:171","msg":"trace[1473288889] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:593; }","duration":"2.279908685s","start":"2026-03-11T05:07:41.461521Z","end":"2026-03-11T05:07:43.74143Z","steps":["trace[1473288889] 'read index received' (duration: 885.416557ms)","trace[1473288889] 'applied index is now lower than readState.Index' (duration: 1.394491028s)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:07:43.741529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:41.221302Z","time spent":"2.520157033s","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T05:07:43.741607Z","caller":"traceutil/trace.go:171","msg":"trace[777614667] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"2.512548969s","start":"2026-03-11T05:07:41.22905Z","end":"2026-03-11T05:07:43.741599Z","steps":["trace[777614667] 'process raft request' (duration: 2.512230405s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:43.741654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:41.229033Z","time spent":"2.51259894s","remote":"127.0.0.1:50290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T05:07:43.741871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.100302079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-11T05:07:43.741909Z","caller":"traceutil/trace.go:171","msg":"trace[1531844446] range","detail":"{range_begin:/registry/masterleases/199.204.45.67; range_end:; response_count:1; response_revision:574; }","duration":"2.1003765s","start":"2026-03-11T05:07:41.641525Z","end":"2026-03-11T05:07:43.741901Z","steps":["trace[1531844446] 'agreement among raft nodes before linearized reading' (duration: 2.100276079s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:43.741933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:41.641505Z","time spent":"2.10042334s","remote":"127.0.0.1:50138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.67\" "} {"level":"warn","ts":"2026-03-11T05:07:43.742026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.500604ms","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-03-11T05:07:43.742072Z","caller":"traceutil/trace.go:171","msg":"trace[1785378717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"421.588395ms","start":"2026-03-11T05:07:43.320475Z","end":"2026-03-11T05:07:43.742063Z","steps":["trace[1785378717] 'agreement among raft nodes before linearized reading' (duration: 421.402993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:43.742109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:43.320449Z","time spent":"421.653286ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-11T05:07:43.742187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.976601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T05:07:43.742464Z","caller":"traceutil/trace.go:171","msg":"trace[1679923170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"280.251075ms","start":"2026-03-11T05:07:43.462205Z","end":"2026-03-11T05:07:43.742457Z","steps":["trace[1679923170] 'agreement among raft nodes before linearized reading' (duration: 279.963051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:43.74205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.647845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-11T05:07:43.742645Z","caller":"traceutil/trace.go:171","msg":"trace[323467293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"523.269244ms","start":"2026-03-11T05:07:43.219368Z","end":"2026-03-11T05:07:43.742638Z","steps":["trace[323467293] 'agreement among raft nodes before linearized reading' (duration: 522.643655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:43.742704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:07:43.219352Z","time spent":"523.327694ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-11T05:07:43.951371Z","caller":"traceutil/trace.go:171","msg":"trace[115668738] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"205.673658ms","start":"2026-03-11T05:07:43.745671Z","end":"2026-03-11T05:07:43.951345Z","steps":["trace[115668738] 'process raft request' (duration: 205.493205ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:43.951637Z","caller":"traceutil/trace.go:171","msg":"trace[2029178908] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"205.751808ms","start":"2026-03-11T05:07:43.745872Z","end":"2026-03-11T05:07:43.951624Z","steps":["trace[2029178908] 'process raft request' (duration: 205.418344ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:43.952096Z","caller":"traceutil/trace.go:171","msg":"trace[821470694] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:596; }","duration":"201.698353ms","start":"2026-03-11T05:07:43.750372Z","end":"2026-03-11T05:07:43.95207Z","steps":["trace[821470694] 'read index received' (duration: 179.136225ms)","trace[821470694] 'applied index is now lower than readState.Index' (duration: 22.560648ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T05:07:43.952236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.847285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T05:07:43.95231Z","caller":"traceutil/trace.go:171","msg":"trace[2141526834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"201.922127ms","start":"2026-03-11T05:07:43.750348Z","end":"2026-03-11T05:07:43.95227Z","steps":["trace[2141526834] 'agreement among raft nodes before linearized reading' (duration: 201.824446ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:07:43.952478Z","caller":"traceutil/trace.go:171","msg":"trace[171807143] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"204.238658ms","start":"2026-03-11T05:07:43.748188Z","end":"2026-03-11T05:07:43.952426Z","steps":["trace[171807143] 'process raft request' (duration: 203.390006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:07:45.740636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.473661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T05:07:45.740696Z","caller":"traceutil/trace.go:171","msg":"trace[647114953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"279.542552ms","start":"2026-03-11T05:07:45.461139Z","end":"2026-03-11T05:07:45.740681Z","steps":["trace[647114953] 'range keys from in-memory index tree' (duration: 279.35431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:09:06.749062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.841369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T05:09:06.749196Z","caller":"traceutil/trace.go:171","msg":"trace[1889800393] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:988; }","duration":"231.990771ms","start":"2026-03-11T05:09:06.517185Z","end":"2026-03-11T05:09:06.749175Z","steps":["trace[1889800393] 'count revisions from in-memory index tree' (duration: 231.695967ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T05:09:14.537535Z","caller":"traceutil/trace.go:171","msg":"trace[263672006] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"347.184303ms","start":"2026-03-11T05:09:14.190325Z","end":"2026-03-11T05:09:14.53751Z","steps":["trace[263672006] 'process raft request' (duration: 346.980031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:09:14.537711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:09:14.190292Z","time spent":"347.329745ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T05:09:14.543396Z","caller":"traceutil/trace.go:171","msg":"trace[547566340] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1057; }","duration":"279.656042ms","start":"2026-03-11T05:09:14.263714Z","end":"2026-03-11T05:09:14.54337Z","steps":["trace[547566340] 'read index received' (duration: 273.626367ms)","trace[547566340] 'applied index is now lower than readState.Index' (duration: 6.028665ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T05:09:14.543527Z","caller":"traceutil/trace.go:171","msg":"trace[1564321095] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"352.789413ms","start":"2026-03-11T05:09:14.190723Z","end":"2026-03-11T05:09:14.543512Z","steps":["trace[1564321095] 'process raft request' (duration: 352.527441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:09:14.543639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.563807ms","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-03-11T05:09:14.543643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T05:09:14.190709Z","time spent":"352.853994ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T05:09:14.543734Z","caller":"traceutil/trace.go:171","msg":"trace[1720888969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"159.649498ms","start":"2026-03-11T05:09:14.38402Z","end":"2026-03-11T05:09:14.54367Z","steps":["trace[1720888969] 'agreement among raft nodes before linearized reading' (duration: 159.529007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:09:14.543847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.134397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-11T05:09:14.54387Z","caller":"traceutil/trace.go:171","msg":"trace[1833733919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"280.202037ms","start":"2026-03-11T05:09:14.263662Z","end":"2026-03-11T05:09:14.543864Z","steps":["trace[1833733919] 'agreement among raft nodes before linearized reading' (duration: 280.138586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T05:09:14.544369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.802775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-11T05:09:14.54442Z","caller":"traceutil/trace.go:171","msg":"trace[775558460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"153.887836ms","start":"2026-03-11T05:09:14.390518Z","end":"2026-03-11T05:09:14.544406Z","steps":["trace[775558460] 'agreement among raft nodes before linearized reading' (duration: 153.742824ms)"],"step_count":1}