{"level":"warn","ts":"2026-05-29T11:29:54.681702Z","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-29T11:29:54.681895Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.157: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.157:2380","--initial-cluster=instance=https://199.19.213.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.157: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-29T11:29:54.682029Z","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-29T11:29:54.682062Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-05-29T11:29:54.682096Z","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-29T11:29:54.687213Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"]} {"level":"info","ts":"2026-05-29T11:29:54.687443Z","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.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.157: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-29T11:29:54.690538Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.714848ms"} {"level":"info","ts":"2026-05-29T11:29:54.696021Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"10058a425931b8de","cluster-id":"2ff97ff1d0272754"} {"level":"info","ts":"2026-05-29T11:29:54.6962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T11:29:54.696235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 0"} {"level":"info","ts":"2026-05-29T11:29:54.696261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 10058a425931b8de [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T11:29:54.696274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 1"} {"level":"info","ts":"2026-05-29T11:29:54.696586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"warn","ts":"2026-05-29T11:29:54.701397Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T11:29:54.70383Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T11:29:54.705322Z","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-29T11:29:54.711504Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"10058a425931b8de","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T11:29:54.711799Z","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-29T11:29:54.711976Z","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-29T11:29:54.712022Z","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-29T11:29:54.712452Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"10058a425931b8de","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T11:29:54.712943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"info","ts":"2026-05-29T11:29:54.713165Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","added-peer-id":"10058a425931b8de","added-peer-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-05-29T11:29:54.719801Z","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-29T11:29:54.720047Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-05-29T11:29:54.720204Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-05-29T11:29:54.720346Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"10058a425931b8de","initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T11:29:54.720436Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T11:29:55.597144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T11:29:55.597252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T11:29:55.597314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgPreVoteResp from 10058a425931b8de at term 1"} {"level":"info","ts":"2026-05-29T11:29:55.597344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became candidate at term 2"} {"level":"info","ts":"2026-05-29T11:29:55.597356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgVoteResp from 10058a425931b8de at term 2"} {"level":"info","ts":"2026-05-29T11:29:55.597374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became leader at term 2"} {"level":"info","ts":"2026-05-29T11:29:55.597395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 10058a425931b8de elected leader 10058a425931b8de at term 2"} {"level":"info","ts":"2026-05-29T11:29:55.59875Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"10058a425931b8de","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.157:2379]}","request-path":"/0/members/10058a425931b8de/attributes","cluster-id":"2ff97ff1d0272754","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T11:29:55.598805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T11:29:55.598878Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:29:55.599063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T11:29:55.599699Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T11:29:55.599731Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T11:29:55.600414Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:29:55.600548Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:29:55.60058Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:29:55.602629Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T11:29:55.618495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.157:2379"} {"level":"info","ts":"2026-05-29T11:29:58.440995Z","caller":"traceutil/trace.go:171","msg":"trace[1383245583] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"183.619866ms","start":"2026-05-29T11:29:58.257339Z","end":"2026-05-29T11:29:58.440959Z","steps":["trace[1383245583] 'read index received' (duration: 154.192328ms)","trace[1383245583] 'applied index is now lower than readState.Index' (duration: 29.425188ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:29:58.441076Z","caller":"traceutil/trace.go:171","msg":"trace[1846305104] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"185.112302ms","start":"2026-05-29T11:29:58.25591Z","end":"2026-05-29T11:29:58.441022Z","steps":["trace[1846305104] 'process raft request' (duration: 155.698184ms)","trace[1846305104] 'compare' (duration: 29.165564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:29:58.441434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.013073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T11:29:58.441504Z","caller":"traceutil/trace.go:171","msg":"trace[693830498] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:73; }","duration":"184.185465ms","start":"2026-05-29T11:29:58.257308Z","end":"2026-05-29T11:29:58.441493Z","steps":["trace[693830498] 'agreement among raft nodes before linearized reading' (duration: 183.85637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:29:58.441704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.783629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T11:29:58.441823Z","caller":"traceutil/trace.go:171","msg":"trace[943709162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"183.960361ms","start":"2026-05-29T11:29:58.257831Z","end":"2026-05-29T11:29:58.441791Z","steps":["trace[943709162] 'agreement among raft nodes before linearized reading' (duration: 183.656556ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:35.424311Z","caller":"traceutil/trace.go:171","msg":"trace[2040028082] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"222.985428ms","start":"2026-05-29T11:30:35.201305Z","end":"2026-05-29T11:30:35.42429Z","steps":["trace[2040028082] 'process raft request' (duration: 222.773094ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:35.424157Z","caller":"traceutil/trace.go:171","msg":"trace[1328911176] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"224.335422ms","start":"2026-05-29T11:30:35.199753Z","end":"2026-05-29T11:30:35.424088Z","steps":["trace[1328911176] 'process raft request' (duration: 187.774978ms)","trace[1328911176] 'compare' (duration: 36.32677ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:30:35.430542Z","caller":"traceutil/trace.go:171","msg":"trace[773650404] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"227.199345ms","start":"2026-05-29T11:30:35.203314Z","end":"2026-05-29T11:30:35.430513Z","steps":["trace[773650404] 'process raft request' (duration: 225.987713ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:35.431769Z","caller":"traceutil/trace.go:171","msg":"trace[797071933] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"223.362065ms","start":"2026-05-29T11:30:35.208377Z","end":"2026-05-29T11:30:35.431739Z","steps":["trace[797071933] 'process raft request' (duration: 222.0578ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:35.432388Z","caller":"traceutil/trace.go:171","msg":"trace[1288301982] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:531; }","duration":"200.83242ms","start":"2026-05-29T11:30:35.231541Z","end":"2026-05-29T11:30:35.432374Z","steps":["trace[1288301982] 'read index received' (duration: 155.922052ms)","trace[1288301982] 'applied index is now lower than readState.Index' (duration: 44.909428ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:30:35.432557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.927511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-05-29T11:30:35.43259Z","caller":"traceutil/trace.go:171","msg":"trace[1984028483] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:521; }","duration":"201.069864ms","start":"2026-05-29T11:30:35.231511Z","end":"2026-05-29T11:30:35.432581Z","steps":["trace[1984028483] 'agreement among raft nodes before linearized reading' (duration: 200.909931ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:35.432691Z","caller":"traceutil/trace.go:171","msg":"trace[1591540125] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"203.746503ms","start":"2026-05-29T11:30:35.228928Z","end":"2026-05-29T11:30:35.432674Z","steps":["trace[1591540125] 'process raft request' (duration: 202.717394ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:35.432718Z","caller":"traceutil/trace.go:171","msg":"trace[1986548581] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"203.585221ms","start":"2026-05-29T11:30:35.229125Z","end":"2026-05-29T11:30:35.43271Z","steps":["trace[1986548581] 'process raft request' (duration: 203.157773ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:35.432915Z","caller":"traceutil/trace.go:171","msg":"trace[638746826] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"201.916899ms","start":"2026-05-29T11:30:35.230991Z","end":"2026-05-29T11:30:35.432908Z","steps":["trace[638746826] 'process raft request' (duration: 201.341869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:30:44.968008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.008819ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-29T11:30:44.968649Z","caller":"traceutil/trace.go:171","msg":"trace[1500143805] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"102.206862ms","start":"2026-05-29T11:30:44.866415Z","end":"2026-05-29T11:30:44.968622Z","steps":["trace[1500143805] 'process raft request' (duration: 101.943917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:30:45.517716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.375143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dmbdg\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-29T11:30:45.517779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.644275ms","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-05-29T11:30:45.518673Z","caller":"traceutil/trace.go:171","msg":"trace[1429845251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"322.626583ms","start":"2026-05-29T11:30:45.196016Z","end":"2026-05-29T11:30:45.518643Z","steps":["trace[1429845251] 'range keys from in-memory index tree' (duration: 321.33438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:30:45.518723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:30:45.19599Z","time spent":"322.719224ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T11:30:45.518355Z","caller":"traceutil/trace.go:171","msg":"trace[2117279743] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dmbdg; range_end:; response_count:1; response_revision:646; }","duration":"155.118227ms","start":"2026-05-29T11:30:45.363182Z","end":"2026-05-29T11:30:45.5183Z","steps":["trace[2117279743] 'range keys from in-memory index tree' (duration: 154.17059ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:45.574771Z","caller":"traceutil/trace.go:171","msg":"trace[1818131379] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"141.291612ms","start":"2026-05-29T11:30:45.433444Z","end":"2026-05-29T11:30:45.574736Z","steps":["trace[1818131379] 'process raft request' (duration: 140.335305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:01.804267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.563286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T11:31:01.80466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.544416ms","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-29T11:31:01.804755Z","caller":"traceutil/trace.go:171","msg":"trace[2012007232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"142.653938ms","start":"2026-05-29T11:31:01.662075Z","end":"2026-05-29T11:31:01.804729Z","steps":["trace[2012007232] 'range keys from in-memory index tree' (duration: 141.926165ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:01.804502Z","caller":"traceutil/trace.go:171","msg":"trace[189342687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"206.877661ms","start":"2026-05-29T11:31:01.597547Z","end":"2026-05-29T11:31:01.804425Z","steps":["trace[189342687] 'range keys from in-memory index tree' (duration: 206.358911ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:37.415983Z","caller":"traceutil/trace.go:171","msg":"trace[1912048349] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"116.614513ms","start":"2026-05-29T11:31:37.299344Z","end":"2026-05-29T11:31:37.415959Z","steps":["trace[1912048349] 'read index received' (duration: 116.498201ms)","trace[1912048349] 'applied index is now lower than readState.Index' (duration: 115.622µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:31:37.416004Z","caller":"traceutil/trace.go:171","msg":"trace[527851391] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"174.72099ms","start":"2026-05-29T11:31:37.241262Z","end":"2026-05-29T11:31:37.415983Z","steps":["trace[527851391] 'process raft request' (duration: 174.563517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:31:37.416136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.753656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T11:31:37.416174Z","caller":"traceutil/trace.go:171","msg":"trace[357379921] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1029; }","duration":"116.868438ms","start":"2026-05-29T11:31:37.299294Z","end":"2026-05-29T11:31:37.416163Z","steps":["trace[357379921] 'agreement among raft nodes before linearized reading' (duration: 116.756825ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:32:39.630993Z","caller":"traceutil/trace.go:171","msg":"trace[1299613367] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"159.765569ms","start":"2026-05-29T11:32:39.471192Z","end":"2026-05-29T11:32:39.630958Z","steps":["trace[1299613367] 'read index received' (duration: 159.522434ms)","trace[1299613367] 'applied index is now lower than readState.Index' (duration: 242.405µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:32:39.631172Z","caller":"traceutil/trace.go:171","msg":"trace[1685491277] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"299.777314ms","start":"2026-05-29T11:32:39.331337Z","end":"2026-05-29T11:32:39.631114Z","steps":["trace[1685491277] 'process raft request' (duration: 299.461818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:39.631283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.070324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T11:32:39.631399Z","caller":"traceutil/trace.go:171","msg":"trace[822371590] range","detail":"{range_begin:/registry/masterleases/199.19.213.157; range_end:; response_count:1; response_revision:1284; }","duration":"160.282668ms","start":"2026-05-29T11:32:39.471082Z","end":"2026-05-29T11:32:39.631364Z","steps":["trace[822371590] 'agreement among raft nodes before linearized reading' (duration: 159.992533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:32:39.632167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:32:39.331297Z","time spent":"300.032779ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:37:07.421824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.391311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-29T11:37:07.421989Z","caller":"traceutil/trace.go:171","msg":"trace[1602125506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2587; }","duration":"110.676887ms","start":"2026-05-29T11:37:07.311282Z","end":"2026-05-29T11:37:07.421959Z","steps":["trace[1602125506] 'range keys from in-memory index tree' (duration: 110.098206ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:08.258182Z","caller":"traceutil/trace.go:171","msg":"trace[1473929811] linearizableReadLoop","detail":"{readStateIndex:2693; appliedIndex:2692; }","duration":"154.876752ms","start":"2026-05-29T11:37:08.103267Z","end":"2026-05-29T11:37:08.258144Z","steps":["trace[1473929811] 'read index received' (duration: 154.628727ms)","trace[1473929811] 'applied index is now lower than readState.Index' (duration: 246.055µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:08.258337Z","caller":"traceutil/trace.go:171","msg":"trace[1475281575] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"254.329077ms","start":"2026-05-29T11:37:08.003964Z","end":"2026-05-29T11:37:08.258293Z","steps":["trace[1475281575] 'process raft request' (duration: 253.945749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:37:08.25863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.50174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T11:37:08.258776Z","caller":"traceutil/trace.go:171","msg":"trace[638647624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2589; }","duration":"149.657642ms","start":"2026-05-29T11:37:08.109064Z","end":"2026-05-29T11:37:08.258722Z","steps":["trace[638647624] 'agreement among raft nodes before linearized reading' (duration: 149.4972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:37:08.258867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.161323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T11:37:08.258871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.679394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T11:37:08.258526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.225148ms","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-29T11:37:08.258945Z","caller":"traceutil/trace.go:171","msg":"trace[1490816696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2589; }","duration":"155.717387ms","start":"2026-05-29T11:37:08.103217Z","end":"2026-05-29T11:37:08.258934Z","steps":["trace[1490816696] 'agreement among raft nodes before linearized reading' (duration: 155.131667ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:08.258942Z","caller":"traceutil/trace.go:171","msg":"trace[1974027145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2589; }","duration":"114.313886ms","start":"2026-05-29T11:37:08.144606Z","end":"2026-05-29T11:37:08.258919Z","steps":["trace[1974027145] 'agreement among raft nodes before linearized reading' (duration: 114.031341ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:08.258942Z","caller":"traceutil/trace.go:171","msg":"trace[154850641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2589; }","duration":"149.832546ms","start":"2026-05-29T11:37:08.109092Z","end":"2026-05-29T11:37:08.258925Z","steps":["trace[154850641] 'agreement among raft nodes before linearized reading' (duration: 149.669813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:37:08.537925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.693804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T11:37:08.538303Z","caller":"traceutil/trace.go:171","msg":"trace[850917864] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"273.475069ms","start":"2026-05-29T11:37:08.26477Z","end":"2026-05-29T11:37:08.538245Z","steps":["trace[850917864] 'process raft request' (duration: 273.431678ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:08.538593Z","caller":"traceutil/trace.go:171","msg":"trace[2032013730] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"274.006669ms","start":"2026-05-29T11:37:08.264545Z","end":"2026-05-29T11:37:08.538552Z","steps":["trace[2032013730] 'process raft request' (duration: 133.58754ms)","trace[2032013730] 'compare' (duration: 139.425949ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:08.538663Z","caller":"traceutil/trace.go:171","msg":"trace[272281521] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"274.023299ms","start":"2026-05-29T11:37:08.264585Z","end":"2026-05-29T11:37:08.538609Z","steps":["trace[272281521] 'process raft request' (duration: 273.508419ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:37:09.247069Z","caller":"traceutil/trace.go:171","msg":"trace[948930158] linearizableReadLoop","detail":"{readStateIndex:2698; appliedIndex:2697; }","duration":"439.987148ms","start":"2026-05-29T11:37:08.807047Z","end":"2026-05-29T11:37:09.247034Z","steps":["trace[948930158] 'read index received' (duration: 439.017789ms)","trace[948930158] 'applied index is now lower than readState.Index' (duration: 967.899µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:09.247224Z","caller":"traceutil/trace.go:171","msg":"trace[1929894214] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"585.481492ms","start":"2026-05-29T11:37:08.661729Z","end":"2026-05-29T11:37:09.24721Z","steps":["trace[1929894214] 'process raft request' (duration: 585.096815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:37:09.24738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:37:08.661687Z","time spent":"585.575804ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T11:37:09.247554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.601281ms","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:533"} {"level":"info","ts":"2026-05-29T11:37:09.24762Z","caller":"traceutil/trace.go:171","msg":"trace[674927456] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2594; }","duration":"237.724773ms","start":"2026-05-29T11:37:09.009879Z","end":"2026-05-29T11:37:09.247604Z","steps":["trace[674927456] 'agreement among raft nodes before linearized reading' (duration: 237.377257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:37:09.247537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.464237ms","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":"info","ts":"2026-05-29T11:37:09.247732Z","caller":"traceutil/trace.go:171","msg":"trace[769501700] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2594; }","duration":"440.713922ms","start":"2026-05-29T11:37:08.806975Z","end":"2026-05-29T11:37:09.247689Z","steps":["trace[769501700] 'agreement among raft nodes before linearized reading' (duration: 440.341535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:37:09.247798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:37:08.806945Z","time spent":"440.838265ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-29T11:37:09.427596Z","caller":"traceutil/trace.go:171","msg":"trace[636797902] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"175.373138ms","start":"2026-05-29T11:37:09.252175Z","end":"2026-05-29T11:37:09.427548Z","steps":["trace[636797902] 'process raft request' (duration: 115.068351ms)","trace[636797902] 'compare' (duration: 59.988831ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:37:09.42759Z","caller":"traceutil/trace.go:171","msg":"trace[733273015] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"174.395459ms","start":"2026-05-29T11:37:09.253159Z","end":"2026-05-29T11:37:09.427555Z","steps":["trace[733273015] 'process raft request' (duration: 174.318518ms)"],"step_count":1}