{"level":"warn","ts":"2026-06-15T06:44:54.48762Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T06:44:54.487718Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.140: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.19.213.140:2380","--initial-cluster=instance=https://199.19.213.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.140: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-06-15T06:44:54.487792Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T06:44:54.487801Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-06-15T06:44:54.487823Z","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-06-15T06:44:54.488593Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"]} {"level":"info","ts":"2026-06-15T06:44:54.488681Z","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.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.140: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-06-15T06:44:54.492704Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.761704ms"} {"level":"info","ts":"2026-06-15T06:44:54.499336Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6366444c8cd268d","cluster-id":"b92fe020fa22927c"} {"level":"info","ts":"2026-06-15T06:44:54.499452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T06:44:54.499491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 0"} {"level":"info","ts":"2026-06-15T06:44:54.499514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6366444c8cd268d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T06:44:54.499536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 1"} {"level":"info","ts":"2026-06-15T06:44:54.499591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"warn","ts":"2026-06-15T06:44:54.507074Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T06:44:54.509673Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T06:44:54.511253Z","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-06-15T06:44:54.514355Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6366444c8cd268d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T06:44:54.514757Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6366444c8cd268d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T06:44:54.514959Z","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-06-15T06:44:54.515101Z","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-06-15T06:44:54.515113Z","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-06-15T06:44:54.516387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"info","ts":"2026-06-15T06:44:54.51659Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","added-peer-id":"b6366444c8cd268d","added-peer-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-06-15T06:44:54.523626Z","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-06-15T06:44:54.52377Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-06-15T06:44:54.523803Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-06-15T06:44:54.524059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6366444c8cd268d","initial-advertise-peer-urls":["https://199.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T06:44:54.524091Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T06:44:54.801977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T06:44:54.802072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T06:44:54.802116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgPreVoteResp from b6366444c8cd268d at term 1"} {"level":"info","ts":"2026-06-15T06:44:54.802137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became candidate at term 2"} {"level":"info","ts":"2026-06-15T06:44:54.802148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgVoteResp from b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-06-15T06:44:54.802184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became leader at term 2"} {"level":"info","ts":"2026-06-15T06:44:54.802198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6366444c8cd268d elected leader b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-06-15T06:44:54.803341Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T06:44:54.804468Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T06:44:54.804463Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6366444c8cd268d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.140:2379]}","request-path":"/0/members/b6366444c8cd268d/attributes","cluster-id":"b92fe020fa22927c","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T06:44:54.804517Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T06:44:54.806787Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T06:44:54.807307Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T06:44:54.807373Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T06:44:54.807855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T06:44:54.807981Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T06:44:54.808044Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T06:44:54.808201Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.140:2379"} {"level":"info","ts":"2026-06-15T06:45:13.136041Z","caller":"traceutil/trace.go:171","msg":"trace[709843066] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"157.958507ms","start":"2026-06-15T06:45:12.978054Z","end":"2026-06-15T06:45:13.136013Z","steps":["trace[709843066] 'process raft request' (duration: 152.815836ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:45:39.138437Z","caller":"traceutil/trace.go:171","msg":"trace[1954945696] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"311.799167ms","start":"2026-06-15T06:45:38.826569Z","end":"2026-06-15T06:45:39.138368Z","steps":["trace[1954945696] 'process raft request' (duration: 311.460639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:45:39.139571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:45:38.826536Z","time spent":"312.146945ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T06:45:39.263215Z","caller":"traceutil/trace.go:171","msg":"trace[1474861310] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"196.762407ms","start":"2026-06-15T06:45:39.066415Z","end":"2026-06-15T06:45:39.263178Z","steps":["trace[1474861310] 'read index received' (duration: 71.762624ms)","trace[1474861310] 'applied index is now lower than readState.Index' (duration: 124.998933ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T06:45:39.263242Z","caller":"traceutil/trace.go:171","msg":"trace[1457263213] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"379.560267ms","start":"2026-06-15T06:45:38.883669Z","end":"2026-06-15T06:45:39.263229Z","steps":["trace[1457263213] 'process raft request' (duration: 379.121036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:45:39.263323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.924221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-15T06:45:39.263382Z","caller":"traceutil/trace.go:171","msg":"trace[1892164312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"197.030614ms","start":"2026-06-15T06:45:39.066344Z","end":"2026-06-15T06:45:39.263374Z","steps":["trace[1892164312] 'agreement among raft nodes before linearized reading' (duration: 196.921692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:45:39.26335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:45:38.883648Z","time spent":"379.634729ms","remote":"127.0.0.1:44138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T06:45:39.88868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.45419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T06:45:39.888767Z","caller":"traceutil/trace.go:171","msg":"trace[10345677] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"566.611996ms","start":"2026-06-15T06:45:39.322139Z","end":"2026-06-15T06:45:39.888751Z","steps":["trace[10345677] 'process raft request' (duration: 323.88081ms)","trace[10345677] 'compare' (duration: 242.353788ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:45:39.888823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:45:39.32212Z","time spent":"566.680638ms","remote":"127.0.0.1:44318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T06:45:39.891726Z","caller":"traceutil/trace.go:171","msg":"trace[1947595345] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"567.884496ms","start":"2026-06-15T06:45:39.323819Z","end":"2026-06-15T06:45:39.891703Z","steps":["trace[1947595345] 'process raft request' (duration: 567.619519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:45:39.891846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:45:39.323799Z","time spent":"567.985199ms","remote":"127.0.0.1:44318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T06:45:39.891875Z","caller":"traceutil/trace.go:171","msg":"trace[1730327563] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"553.648122ms","start":"2026-06-15T06:45:39.338205Z","end":"2026-06-15T06:45:39.891853Z","steps":["trace[1730327563] 'process raft request' (duration: 553.390426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:45:39.891926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:45:39.338192Z","time spent":"553.711793ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T06:45:39.891881Z","caller":"traceutil/trace.go:171","msg":"trace[108464735] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:617; }","duration":"432.820957ms","start":"2026-06-15T06:45:39.45903Z","end":"2026-06-15T06:45:39.891851Z","steps":["trace[108464735] 'read index received' (duration: 187.002709ms)","trace[108464735] 'applied index is now lower than readState.Index' (duration: 245.800808ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:45:39.892409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.37004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T06:45:39.892536Z","caller":"traceutil/trace.go:171","msg":"trace[2090769814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"433.443301ms","start":"2026-06-15T06:45:39.459011Z","end":"2026-06-15T06:45:39.892454Z","steps":["trace[2090769814] 'agreement among raft nodes before linearized reading' (duration: 432.97965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:45:39.892598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:45:39.458961Z","time spent":"433.623725ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T06:45:41.045804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.24681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T06:45:41.045925Z","caller":"traceutil/trace.go:171","msg":"trace[1325647340] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"136.671368ms","start":"2026-06-15T06:45:40.909238Z","end":"2026-06-15T06:45:41.045909Z","steps":["trace[1325647340] 'compare' (duration: 134.078327ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:45:55.306252Z","caller":"traceutil/trace.go:171","msg":"trace[1485177478] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"393.997206ms","start":"2026-06-15T06:45:54.912217Z","end":"2026-06-15T06:45:55.306214Z","steps":["trace[1485177478] 'process raft request' (duration: 393.76242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:45:55.307499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:45:54.912202Z","time spent":"394.151829ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T06:46:30.435892Z","caller":"traceutil/trace.go:171","msg":"trace[1806793021] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"245.18584ms","start":"2026-06-15T06:46:30.190666Z","end":"2026-06-15T06:46:30.435852Z","steps":["trace[1806793021] 'process raft request' (duration: 191.351877ms)","trace[1806793021] 'compare' (duration: 53.662819ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T06:46:30.4361Z","caller":"traceutil/trace.go:171","msg":"trace[113036332] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"245.136198ms","start":"2026-06-15T06:46:30.190917Z","end":"2026-06-15T06:46:30.436053Z","steps":["trace[113036332] 'process raft request' (duration: 244.895403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:46:50.608231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.985314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T06:46:50.6083Z","caller":"traceutil/trace.go:171","msg":"trace[1519450946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"149.089286ms","start":"2026-06-15T06:46:50.459196Z","end":"2026-06-15T06:46:50.608285Z","steps":["trace[1519450946] 'range keys from in-memory index tree' (duration: 148.923412ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:46:52.899775Z","caller":"traceutil/trace.go:171","msg":"trace[1104226692] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"141.5828ms","start":"2026-06-15T06:46:52.758163Z","end":"2026-06-15T06:46:52.899745Z","steps":["trace[1104226692] 'process raft request' (duration: 53.32692ms)","trace[1104226692] 'compare' (duration: 88.133027ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:47:08.518385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:47:08.177637Z","time spent":"340.741077ms","remote":"127.0.0.1:44048","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-15T06:47:08.518372Z","caller":"traceutil/trace.go:171","msg":"trace[1991427429] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"309.845928ms","start":"2026-06-15T06:47:08.208499Z","end":"2026-06-15T06:47:08.518344Z","steps":["trace[1991427429] 'read index received' (duration: 309.650434ms)","trace[1991427429] 'applied index is now lower than readState.Index' (duration: 193.954µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:47:08.518508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.979022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T06:47:08.518573Z","caller":"traceutil/trace.go:171","msg":"trace[424219365] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1143; }","duration":"310.103105ms","start":"2026-06-15T06:47:08.208456Z","end":"2026-06-15T06:47:08.518559Z","steps":["trace[424219365] 'agreement among raft nodes before linearized reading' (duration: 309.978382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:47:08.518604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:47:08.208436Z","time spent":"310.160747ms","remote":"127.0.0.1:55792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T06:47:08.518605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.438596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T06:47:08.518665Z","caller":"traceutil/trace.go:171","msg":"trace[1630944458] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1143; }","duration":"254.57361ms","start":"2026-06-15T06:47:08.264075Z","end":"2026-06-15T06:47:08.518649Z","steps":["trace[1630944458] 'agreement among raft nodes before linearized reading' (duration: 254.459907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:47:08.51879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.82517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T06:47:08.518827Z","caller":"traceutil/trace.go:171","msg":"trace[1974495718] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1143; }","duration":"170.900402ms","start":"2026-06-15T06:47:08.347912Z","end":"2026-06-15T06:47:08.518813Z","steps":["trace[1974495718] 'agreement among raft nodes before linearized reading' (duration: 170.82653ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:08.733985Z","caller":"traceutil/trace.go:171","msg":"trace[1413954077] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"211.226668ms","start":"2026-06-15T06:47:08.522742Z","end":"2026-06-15T06:47:08.733969Z","steps":["trace[1413954077] 'process raft request' (duration: 211.190738ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:08.734087Z","caller":"traceutil/trace.go:171","msg":"trace[1462711250] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"214.206091ms","start":"2026-06-15T06:47:08.519851Z","end":"2026-06-15T06:47:08.734057Z","steps":["trace[1462711250] 'process raft request' (duration: 211.28387ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:21.919294Z","caller":"traceutil/trace.go:171","msg":"trace[282233610] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"123.639576ms","start":"2026-06-15T06:47:21.795632Z","end":"2026-06-15T06:47:21.919271Z","steps":["trace[282233610] 'read index received' (duration: 123.540404ms)","trace[282233610] 'applied index is now lower than readState.Index' (duration: 98.342µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:47:21.919528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.881422ms","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-06-15T06:47:21.919573Z","caller":"traceutil/trace.go:171","msg":"trace[991406024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"123.974304ms","start":"2026-06-15T06:47:21.795585Z","end":"2026-06-15T06:47:21.919559Z","steps":["trace[991406024] 'agreement among raft nodes before linearized reading' (duration: 123.80314ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:21.919655Z","caller":"traceutil/trace.go:171","msg":"trace[1533732939] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"142.546064ms","start":"2026-06-15T06:47:21.777061Z","end":"2026-06-15T06:47:21.919607Z","steps":["trace[1533732939] 'process raft request' (duration: 142.029812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:47:25.412026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.575536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T06:47:25.412128Z","caller":"traceutil/trace.go:171","msg":"trace[1897788143] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"188.234392ms","start":"2026-06-15T06:47:25.223879Z","end":"2026-06-15T06:47:25.412114Z","steps":["trace[1897788143] 'read index received' (duration: 14.450251ms)","trace[1897788143] 'applied index is now lower than readState.Index' (duration: 173.782961ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:47:25.412265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.383766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pjpsm\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-06-15T06:47:25.4123Z","caller":"traceutil/trace.go:171","msg":"trace[1754377314] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pjpsm; range_end:; response_count:1; response_revision:1217; }","duration":"188.419777ms","start":"2026-06-15T06:47:25.223872Z","end":"2026-06-15T06:47:25.412292Z","steps":["trace[1754377314] 'agreement among raft nodes before linearized reading' (duration: 188.282943ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:25.412469Z","caller":"traceutil/trace.go:171","msg":"trace[213676671] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"353.22387ms","start":"2026-06-15T06:47:25.059179Z","end":"2026-06-15T06:47:25.412403Z","steps":["trace[213676671] 'process raft request' (duration: 179.185843ms)","trace[213676671] 'compare' (duration: 173.393262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:47:25.412645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:47:25.059162Z","time spent":"353.383784ms","remote":"127.0.0.1:44318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T06:47:25.648556Z","caller":"traceutil/trace.go:171","msg":"trace[1294562104] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"224.135901ms","start":"2026-06-15T06:47:25.424397Z","end":"2026-06-15T06:47:25.648533Z","steps":["trace[1294562104] 'process raft request' (duration: 224.001828ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:26.893327Z","caller":"traceutil/trace.go:171","msg":"trace[1787573034] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"119.593618ms","start":"2026-06-15T06:47:26.773664Z","end":"2026-06-15T06:47:26.893258Z","steps":["trace[1787573034] 'process raft request' (duration: 119.168118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:47:27.188652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.872131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T06:47:27.188755Z","caller":"traceutil/trace.go:171","msg":"trace[2038643712] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"139.523711ms","start":"2026-06-15T06:47:27.049216Z","end":"2026-06-15T06:47:27.18874Z","steps":["trace[2038643712] 'read index received' (duration: 53.971µs)","trace[2038643712] 'applied index is now lower than readState.Index' (duration: 139.46793ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T06:47:27.188845Z","caller":"traceutil/trace.go:171","msg":"trace[744014652] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"291.540725ms","start":"2026-06-15T06:47:26.897292Z","end":"2026-06-15T06:47:27.188833Z","steps":["trace[744014652] 'process raft request' (duration: 101.403247ms)","trace[744014652] 'compare' (duration: 189.733678ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:47:27.189134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.927871ms","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-06-15T06:47:27.189169Z","caller":"traceutil/trace.go:171","msg":"trace[1549504857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1225; }","duration":"140.007863ms","start":"2026-06-15T06:47:27.049154Z","end":"2026-06-15T06:47:27.189162Z","steps":["trace[1549504857] 'agreement among raft nodes before linearized reading' (duration: 139.86963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:47:27.1892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.568333ms","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-06-15T06:47:27.18926Z","caller":"traceutil/trace.go:171","msg":"trace[931779578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1225; }","duration":"125.664455ms","start":"2026-06-15T06:47:27.063582Z","end":"2026-06-15T06:47:27.189246Z","steps":["trace[931779578] 'agreement among raft nodes before linearized reading' (duration: 125.516051ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:27.382849Z","caller":"traceutil/trace.go:171","msg":"trace[1128429906] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"188.699492ms","start":"2026-06-15T06:47:27.194128Z","end":"2026-06-15T06:47:27.382827Z","steps":["trace[1128429906] 'process raft request' (duration: 174.446967ms)","trace[1128429906] 'compare' (duration: 13.913277ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T06:47:38.445314Z","caller":"traceutil/trace.go:171","msg":"trace[97082713] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"415.744295ms","start":"2026-06-15T06:47:38.029531Z","end":"2026-06-15T06:47:38.445275Z","steps":["trace[97082713] 'process raft request' (duration: 415.505359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:47:38.445547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:47:38.02951Z","time spent":"415.920779ms","remote":"127.0.0.1:44318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T06:47:38.488477Z","caller":"traceutil/trace.go:171","msg":"trace[879343154] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"430.324099ms","start":"2026-06-15T06:47:38.058111Z","end":"2026-06-15T06:47:38.488435Z","steps":["trace[879343154] 'read index received' (duration: 387.04724ms)","trace[879343154] 'applied index is now lower than readState.Index' (duration: 43.275549ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T06:47:38.488664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.521813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T06:47:38.489085Z","caller":"traceutil/trace.go:171","msg":"trace[767814106] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1310; }","duration":"430.957704ms","start":"2026-06-15T06:47:38.058103Z","end":"2026-06-15T06:47:38.489061Z","steps":["trace[767814106] 'agreement among raft nodes before linearized reading' (duration: 430.463122ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:38.488668Z","caller":"traceutil/trace.go:171","msg":"trace[979371693] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"458.739487ms","start":"2026-06-15T06:47:38.029873Z","end":"2026-06-15T06:47:38.488612Z","steps":["trace[979371693] 'process raft request' (duration: 458.361278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:47:38.489365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:47:38.05807Z","time spent":"431.109298ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-15T06:47:38.48947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:47:38.029839Z","time spent":"459.434754ms","remote":"127.0.0.1:44318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T06:47:38.489769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.253163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-15T06:47:38.489807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.540454ms","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:530"} {"level":"info","ts":"2026-06-15T06:47:38.489891Z","caller":"traceutil/trace.go:171","msg":"trace[1514646782] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1310; }","duration":"253.656397ms","start":"2026-06-15T06:47:38.236214Z","end":"2026-06-15T06:47:38.48987Z","steps":["trace[1514646782] 'agreement among raft nodes before linearized reading' (duration: 253.048153ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:47:38.489913Z","caller":"traceutil/trace.go:171","msg":"trace[59600877] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:1; response_revision:1310; }","duration":"311.434847ms","start":"2026-06-15T06:47:38.17842Z","end":"2026-06-15T06:47:38.489855Z","steps":["trace[59600877] 'agreement among raft nodes before linearized reading' (duration: 310.605817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:47:38.490099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T06:47:38.178389Z","time spent":"311.695413ms","remote":"127.0.0.1:44048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.140\" "} {"level":"warn","ts":"2026-06-15T06:49:03.011914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.035296ms","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-06-15T06:49:03.012002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.391683ms","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-06-15T06:49:03.012083Z","caller":"traceutil/trace.go:171","msg":"trace[499984111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1697; }","duration":"127.509286ms","start":"2026-06-15T06:49:02.884556Z","end":"2026-06-15T06:49:03.012065Z","steps":["trace[499984111] 'range keys from in-memory index tree' (duration: 127.25643ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T06:49:03.0122Z","caller":"traceutil/trace.go:171","msg":"trace[1925978594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1697; }","duration":"201.168949ms","start":"2026-06-15T06:49:02.810822Z","end":"2026-06-15T06:49:03.011991Z","steps":["trace[1925978594] 'range keys from in-memory index tree' (duration: 200.851542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T06:49:03.012402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.840021ms","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:531"} {"level":"info","ts":"2026-06-15T06:49:03.012448Z","caller":"traceutil/trace.go:171","msg":"trace[657739761] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1697; }","duration":"131.923823ms","start":"2026-06-15T06:49:02.880513Z","end":"2026-06-15T06:49:03.012437Z","steps":["trace[657739761] 'range keys from in-memory index tree' (duration: 131.725408ms)"],"step_count":1}