{"level":"warn","ts":"2026-05-05T06:51:19.316191Z","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-05T06:51:19.316317Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-05T06:51:19.316432Z","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-05T06:51:19.316453Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-05T06:51:19.316491Z","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-05T06:51:19.317339Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-05T06:51:19.317483Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-05T06:51:19.324423Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.939446ms"} {"level":"info","ts":"2026-05-05T06:51:19.333472Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-05T06:51:19.333537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:51:19.333652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-05T06:51:19.333671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:51:19.333685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-05T06:51:19.333801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-05T06:51:19.339712Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:51:19.341723Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:51:19.346251Z","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-05T06:51:19.348869Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:51:19.349203Z","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-05T06:51:19.349248Z","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-05T06:51:19.349258Z","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-05T06:51:19.349374Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:51:19.352426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-05T06:51:19.35401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-05T06:51:19.35885Z","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-05T06:51:19.359049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-05T06:51:19.359108Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-05T06:51:19.359326Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:51:19.359424Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:51:20.03436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:51:20.034425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:51:20.034441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-05T06:51:20.034453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:51:20.034458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-05T06:51:20.034467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-05T06:51:20.034473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-05T06:51:20.035329Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:51:20.035355Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:51:20.035519Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:51:20.0356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:51:20.03564Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:51:20.035662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:51:20.037199Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:51:20.039979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-05T06:51:20.046902Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:51:20.047232Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:51:20.048007Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T06:51:22.646758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.359534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T06:51:22.646878Z","caller":"traceutil/trace.go:171","msg":"trace[1557589010] linearizableReadLoop","detail":"{readStateIndex:90; appliedIndex:89; }","duration":"323.464235ms","start":"2026-05-05T06:51:22.323397Z","end":"2026-05-05T06:51:22.646862Z","steps":["trace[1557589010] 'read index received' (duration: 126.498444ms)","trace[1557589010] 'applied index is now lower than readState.Index' (duration: 196.964451ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:22.646964Z","caller":"traceutil/trace.go:171","msg":"trace[131839481] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"338.602362ms","start":"2026-05-05T06:51:22.308302Z","end":"2026-05-05T06:51:22.646904Z","steps":["trace[131839481] 'process raft request' (duration: 141.489328ms)","trace[131839481] 'compare' (duration: 196.207149ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:22.647036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.647509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T06:51:22.647207Z","caller":"traceutil/trace.go:171","msg":"trace[1925980403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:86; }","duration":"323.866936ms","start":"2026-05-05T06:51:22.323329Z","end":"2026-05-05T06:51:22.647196Z","steps":["trace[1925980403] 'agreement among raft nodes before linearized reading' (duration: 323.584628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:22.647254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:22.323303Z","time spent":"323.933377ms","remote":"127.0.0.1:51060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:51:22.647226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:22.308289Z","time spent":"338.808667ms","remote":"127.0.0.1:51402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1473,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T06:51:22.882843Z","caller":"traceutil/trace.go:171","msg":"trace[1404410858] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"230.981466ms","start":"2026-05-05T06:51:22.651832Z","end":"2026-05-05T06:51:22.882813Z","steps":["trace[1404410858] 'process raft request' (duration: 144.062907ms)","trace[1404410858] 'compare' (duration: 86.790076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:52.253643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.437162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:51:52.253718Z","caller":"traceutil/trace.go:171","msg":"trace[1019111099] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"233.382161ms","start":"2026-05-05T06:51:52.020326Z","end":"2026-05-05T06:51:52.253708Z","steps":["trace[1019111099] 'process raft request' (duration: 117.756774ms)","trace[1019111099] 'compare' (duration: 115.37295ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:51:52.264578Z","caller":"traceutil/trace.go:171","msg":"trace[718364691] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"163.280914ms","start":"2026-05-05T06:51:52.101269Z","end":"2026-05-05T06:51:52.264549Z","steps":["trace[718364691] 'process raft request' (duration: 162.75411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:55.472082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.615232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:51:55.473612Z","caller":"traceutil/trace.go:171","msg":"trace[369623432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"412.075445ms","start":"2026-05-05T06:51:55.060235Z","end":"2026-05-05T06:51:55.47231Z","steps":["trace[369623432] 'range keys from in-memory index tree' (duration: 411.52542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:55.473763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:55.060212Z","time spent":"413.513643ms","remote":"127.0.0.1:51062","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-05T06:51:55.47831Z","caller":"traceutil/trace.go:171","msg":"trace[531378449] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"336.333083ms","start":"2026-05-05T06:51:55.141924Z","end":"2026-05-05T06:51:55.478257Z","steps":["trace[531378449] 'read index received' (duration: 326.498075ms)","trace[531378449] 'applied index is now lower than readState.Index' (duration: 9.834018ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:51:55.478477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.526659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bngsw\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T06:51:55.478512Z","caller":"traceutil/trace.go:171","msg":"trace[544543191] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bngsw; range_end:; response_count:1; response_revision:619; }","duration":"336.631001ms","start":"2026-05-05T06:51:55.14187Z","end":"2026-05-05T06:51:55.478501Z","steps":["trace[544543191] 'agreement among raft nodes before linearized reading' (duration: 336.504478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:55.478544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:55.141848Z","time spent":"336.688743ms","remote":"127.0.0.1:51240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-bngsw\" "} {"level":"info","ts":"2026-05-05T06:51:55.478569Z","caller":"traceutil/trace.go:171","msg":"trace[399927002] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"372.622901ms","start":"2026-05-05T06:51:55.105902Z","end":"2026-05-05T06:51:55.478525Z","steps":["trace[399927002] 'process raft request' (duration: 363.246416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:51:55.478736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:51:55.105871Z","time spent":"372.783635ms","remote":"127.0.0.1:51162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:52:00.442088Z","caller":"traceutil/trace.go:171","msg":"trace[653277540] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"125.567486ms","start":"2026-05-05T06:52:00.316501Z","end":"2026-05-05T06:52:00.442068Z","steps":["trace[653277540] 'read index received' (duration: 125.36389ms)","trace[653277540] 'applied index is now lower than readState.Index' (duration: 202.696µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:52:00.442214Z","caller":"traceutil/trace.go:171","msg":"trace[320766004] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"134.850139ms","start":"2026-05-05T06:52:00.307296Z","end":"2026-05-05T06:52:00.442146Z","steps":["trace[320766004] 'process raft request' (duration: 134.604442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:00.442268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.748571ms","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-05T06:52:00.442341Z","caller":"traceutil/trace.go:171","msg":"trace[943649719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"125.877134ms","start":"2026-05-05T06:52:00.316455Z","end":"2026-05-05T06:52:00.442332Z","steps":["trace[943649719] 'agreement among raft nodes before linearized reading' (duration: 125.690419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:15.187377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.915907ms","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-05T06:52:15.187475Z","caller":"traceutil/trace.go:171","msg":"trace[243492834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"126.063511ms","start":"2026-05-05T06:52:15.06139Z","end":"2026-05-05T06:52:15.187453Z","steps":["trace[243492834] 'range keys from in-memory index tree' (duration: 125.748742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:52:15.187625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.460842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:52:15.187714Z","caller":"traceutil/trace.go:171","msg":"trace[1926299725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"126.580595ms","start":"2026-05-05T06:52:15.061113Z","end":"2026-05-05T06:52:15.187693Z","steps":["trace[1926299725] 'range keys from in-memory index tree' (duration: 126.41387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:12.964717Z","caller":"traceutil/trace.go:171","msg":"trace[2048526932] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"138.861554ms","start":"2026-05-05T06:53:12.825823Z","end":"2026-05-05T06:53:12.964685Z","steps":["trace[2048526932] 'process raft request' (duration: 138.677269ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:13.613815Z","caller":"traceutil/trace.go:171","msg":"trace[465043848] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"116.73802ms","start":"2026-05-05T06:53:13.497024Z","end":"2026-05-05T06:53:13.613762Z","steps":["trace[465043848] 'process raft request' (duration: 116.488593ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:38.712521Z","caller":"traceutil/trace.go:171","msg":"trace[1662582027] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"189.661062ms","start":"2026-05-05T06:53:38.522836Z","end":"2026-05-05T06:53:38.712497Z","steps":["trace[1662582027] 'process raft request' (duration: 189.504777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:53:43.802621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.920506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:53:43.802789Z","caller":"traceutil/trace.go:171","msg":"trace[888438392] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"190.906276ms","start":"2026-05-05T06:53:43.611841Z","end":"2026-05-05T06:53:43.802748Z","steps":["trace[888438392] 'process raft request' (duration: 40.762324ms)","trace[888438392] 'compare' (duration: 148.935369ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:53:44.582592Z","caller":"traceutil/trace.go:171","msg":"trace[808783399] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"166.426497ms","start":"2026-05-05T06:53:44.416136Z","end":"2026-05-05T06:53:44.582562Z","steps":["trace[808783399] 'process raft request' (duration: 72.836219ms)","trace[808783399] 'compare' (duration: 93.413173ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:53:44.582718Z","caller":"traceutil/trace.go:171","msg":"trace[635030066] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"144.393535ms","start":"2026-05-05T06:53:44.438273Z","end":"2026-05-05T06:53:44.582667Z","steps":["trace[635030066] 'read index received' (duration: 50.676705ms)","trace[635030066] 'applied index is now lower than readState.Index' (duration: 93.71121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:53:44.582882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.594991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:53:44.58291Z","caller":"traceutil/trace.go:171","msg":"trace[587060405] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1277; }","duration":"144.668043ms","start":"2026-05-05T06:53:44.43823Z","end":"2026-05-05T06:53:44.582899Z","steps":["trace[587060405] 'agreement among raft nodes before linearized reading' (duration: 144.591911ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:55.465607Z","caller":"traceutil/trace.go:171","msg":"trace[1179238283] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"128.55001ms","start":"2026-05-05T06:53:55.337024Z","end":"2026-05-05T06:53:55.465574Z","steps":["trace[1179238283] 'process raft request' (duration: 49.656548ms)","trace[1179238283] 'compare' (duration: 78.753398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:54:46.600057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899029318677408,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:54:46.946271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920105062s","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-05-05T06:54:46.946373Z","caller":"traceutil/trace.go:171","msg":"trace[1449145058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1576; }","duration":"1.920259237s","start":"2026-05-05T06:54:45.026091Z","end":"2026-05-05T06:54:46.94635Z","steps":["trace[1449145058] 'range keys from in-memory index tree' (duration: 1.919820445s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.946432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:45.02607Z","time spent":"1.920341129s","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T06:54:46.946665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.130438ms","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-05T06:54:46.946732Z","caller":"traceutil/trace.go:171","msg":"trace[1732829074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1576; }","duration":"848.223371ms","start":"2026-05-05T06:54:46.098492Z","end":"2026-05-05T06:54:46.946716Z","steps":["trace[1732829074] 'range keys from in-memory index tree' (duration: 848.016516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.946762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.098487Z","time spent":"848.267452ms","remote":"127.0.0.1:51332","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-05T06:54:46.947155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.91965ms","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-05T06:54:46.947185Z","caller":"traceutil/trace.go:171","msg":"trace[701401242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1576; }","duration":"848.973441ms","start":"2026-05-05T06:54:46.098205Z","end":"2026-05-05T06:54:46.947179Z","steps":["trace[701401242] 'range keys from in-memory index tree' (duration: 848.674623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.947227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.098191Z","time spent":"849.030743ms","remote":"127.0.0.1:51332","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-05T06:54:46.946695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.926163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:54:46.947353Z","caller":"traceutil/trace.go:171","msg":"trace[1850400309] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1576; }","duration":"848.973861ms","start":"2026-05-05T06:54:46.09835Z","end":"2026-05-05T06:54:46.947324Z","steps":["trace[1850400309] 'count revisions from in-memory index tree' (duration: 847.856431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.947571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.098329Z","time spent":"849.215778ms","remote":"127.0.0.1:51236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:54:46.948263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.756413ms","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-05T06:54:46.948356Z","caller":"traceutil/trace.go:171","msg":"trace[1346267365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1576; }","duration":"849.858765ms","start":"2026-05-05T06:54:46.098478Z","end":"2026-05-05T06:54:46.948337Z","steps":["trace[1346267365] 'range keys from in-memory index tree' (duration: 847.613244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.948401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.098471Z","time spent":"849.918057ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T06:54:46.948601Z","caller":"traceutil/trace.go:171","msg":"trace[860421924] linearizableReadLoop","detail":"{readStateIndex:1636; appliedIndex:1635; }","duration":"848.644942ms","start":"2026-05-05T06:54:46.099939Z","end":"2026-05-05T06:54:46.948584Z","steps":["trace[860421924] 'read index received' (duration: 846.445182ms)","trace[860421924] 'applied index is now lower than readState.Index' (duration: 2.19868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:54:46.948877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.544329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:54:46.948943Z","caller":"traceutil/trace.go:171","msg":"trace[1137289060] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1577; }","duration":"644.642982ms","start":"2026-05-05T06:54:46.304282Z","end":"2026-05-05T06:54:46.948925Z","steps":["trace[1137289060] 'agreement among raft nodes before linearized reading' (duration: 644.5423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.948997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.304264Z","time spent":"644.718284ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:54:46.949084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.567596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-05T06:54:46.949181Z","caller":"traceutil/trace.go:171","msg":"trace[1589863712] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1577; }","duration":"168.68817ms","start":"2026-05-05T06:54:46.780479Z","end":"2026-05-05T06:54:46.949167Z","steps":["trace[1589863712] 'agreement among raft nodes before linearized reading' (duration: 168.452133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.949725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.888571ms","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-05T06:54:46.94977Z","caller":"traceutil/trace.go:171","msg":"trace[998574935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1577; }","duration":"110.962393ms","start":"2026-05-05T06:54:46.838797Z","end":"2026-05-05T06:54:46.949759Z","steps":["trace[998574935] 'agreement among raft nodes before linearized reading' (duration: 110.84188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.949789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.058481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-05-05T06:54:46.949373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.933824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:54:46.949861Z","caller":"traceutil/trace.go:171","msg":"trace[552731626] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1577; }","duration":"205.462489ms","start":"2026-05-05T06:54:46.74439Z","end":"2026-05-05T06:54:46.949852Z","steps":["trace[552731626] 'agreement among raft nodes before linearized reading' (duration: 204.868622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.949545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.657741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-05T06:54:46.949977Z","caller":"traceutil/trace.go:171","msg":"trace[2048421772] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1577; }","duration":"583.129183ms","start":"2026-05-05T06:54:46.366689Z","end":"2026-05-05T06:54:46.949818Z","steps":["trace[2048421772] 'agreement among raft nodes before linearized reading' (duration: 582.874666ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:54:46.950004Z","caller":"traceutil/trace.go:171","msg":"trace[1943496310] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1577; }","duration":"400.157404ms","start":"2026-05-05T06:54:46.549837Z","end":"2026-05-05T06:54:46.949994Z","steps":["trace[1943496310] 'agreement among raft nodes before linearized reading' (duration: 399.605259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.950041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.549812Z","time spent":"400.218946ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T06:54:46.950017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.366671Z","time spent":"583.335208ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T06:54:46.949615Z","caller":"traceutil/trace.go:171","msg":"trace[627128260] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"851.062378ms","start":"2026-05-05T06:54:46.098538Z","end":"2026-05-05T06:54:46.9496Z","steps":["trace[627128260] 'process raft request' (duration: 847.910082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.949618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.667141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:54:46.950985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.098528Z","time spent":"852.348123ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:54:46.950973Z","caller":"traceutil/trace.go:171","msg":"trace[1290068161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1577; }","duration":"851.038168ms","start":"2026-05-05T06:54:46.099922Z","end":"2026-05-05T06:54:46.95096Z","steps":["trace[1290068161] 'agreement among raft nodes before linearized reading' (duration: 849.64683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:54:46.951231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:54:46.099915Z","time spent":"851.1462ms","remote":"127.0.0.1:51060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}