{"level":"warn","ts":"2026-02-03T22:09:45.559914Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T22:09:45.559991Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-02-03T22:09:45.560045Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T22:09:45.560057Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-03T22:09:45.560072Z","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-02-03T22:09:45.561348Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-03T22:09:45.561429Z","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":2,"max-cpu-available":2,"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://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-02-03T22:09:45.56587Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.183474ms"} {"level":"info","ts":"2026-02-03T22:09:45.582391Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-03T22:09:45.582481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T22:09:45.582499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-03T22:09:45.582506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T22:09:45.582512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-03T22:09:45.582541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-03T22:09:45.61051Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T22:09:45.62202Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T22:09:45.628581Z","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-02-03T22:09:45.634221Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T22:09:45.638495Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T22:09:45.640003Z","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-02-03T22:09:45.64005Z","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-02-03T22:09:45.640058Z","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-02-03T22:09:45.640913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-03T22:09:45.641127Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-03T22:09:45.647255Z","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-02-03T22:09:45.64742Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-03T22:09:45.647836Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-03T22:09:45.655092Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T22:09:45.655145Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T22:09:46.186486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T22:09:46.186582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T22:09:46.186622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-03T22:09:46.186666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-03T22:09:46.186686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-03T22:09:46.186704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-03T22:09:46.18672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-03T22:09:46.188715Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:09:46.191813Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T22:09:46.191843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T22:09:46.192277Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T22:09:46.193507Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:09:46.19361Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:09:46.193632Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:09:46.193943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T22:09:46.207658Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T22:09:46.207677Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T22:09:46.219699Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-02-03T22:10:03.112691Z","caller":"traceutil/trace.go:171","msg":"trace[919244461] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"155.76799ms","start":"2026-02-03T22:10:02.956704Z","end":"2026-02-03T22:10:03.112472Z","steps":["trace[919244461] 'process raft request' (duration: 155.601827ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:10:16.387177Z","caller":"traceutil/trace.go:171","msg":"trace[426429070] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"261.934304ms","start":"2026-02-03T22:10:16.125225Z","end":"2026-02-03T22:10:16.387159Z","steps":["trace[426429070] 'process raft request' (duration: 260.497591ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:10:19.574402Z","caller":"traceutil/trace.go:171","msg":"trace[561991701] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"108.618535ms","start":"2026-02-03T22:10:19.46577Z","end":"2026-02-03T22:10:19.574388Z","steps":["trace[561991701] 'read index received' (duration: 108.489782ms)","trace[561991701] 'applied index is now lower than readState.Index' (duration: 127.993µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:10:19.574495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.715146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:10:19.574527Z","caller":"traceutil/trace.go:171","msg":"trace[1994300376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"108.776268ms","start":"2026-02-03T22:10:19.465745Z","end":"2026-02-03T22:10:19.574521Z","steps":["trace[1994300376] 'agreement among raft nodes before linearized reading' (duration: 108.709786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:10:19.72226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.464247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:10:19.722352Z","caller":"traceutil/trace.go:171","msg":"trace[1382733319] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"146.883685ms","start":"2026-02-03T22:10:19.575459Z","end":"2026-02-03T22:10:19.722342Z","steps":["trace[1382733319] 'read index received' (duration: 39.828215ms)","trace[1382733319] 'applied index is now lower than readState.Index' (duration: 107.05477ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:10:19.72238Z","caller":"traceutil/trace.go:171","msg":"trace[739274990] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"147.09738ms","start":"2026-02-03T22:10:19.575259Z","end":"2026-02-03T22:10:19.722357Z","steps":["trace[739274990] 'process raft request' (duration: 40.04736ms)","trace[739274990] 'compare' (duration: 106.371955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:10:19.72241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.948897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:10:19.72243Z","caller":"traceutil/trace.go:171","msg":"trace[407661710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"146.984327ms","start":"2026-02-03T22:10:19.575441Z","end":"2026-02-03T22:10:19.722426Z","steps":["trace[407661710] 'agreement among raft nodes before linearized reading' (duration: 146.936696ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:10:22.504659Z","caller":"traceutil/trace.go:171","msg":"trace[1196371156] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"377.620515ms","start":"2026-02-03T22:10:22.127021Z","end":"2026-02-03T22:10:22.504642Z","steps":["trace[1196371156] 'process raft request' (duration: 377.504822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:10:22.50479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:10:22.127003Z","time spent":"377.710857ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:10:22.506043Z","caller":"traceutil/trace.go:171","msg":"trace[577029013] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:503; }","duration":"306.548255ms","start":"2026-02-03T22:10:22.199473Z","end":"2026-02-03T22:10:22.506022Z","steps":["trace[577029013] 'read index received' (duration: 306.539415ms)","trace[577029013] 'applied index is now lower than readState.Index' (duration: 7.32µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:10:22.506186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.694698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T22:10:22.506218Z","caller":"traceutil/trace.go:171","msg":"trace[1422387897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"306.777701ms","start":"2026-02-03T22:10:22.199429Z","end":"2026-02-03T22:10:22.506207Z","steps":["trace[1422387897] 'agreement among raft nodes before linearized reading' (duration: 306.691049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:10:22.506251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:10:22.199417Z","time spent":"306.823023ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T22:10:22.605244Z","caller":"traceutil/trace.go:171","msg":"trace[1523261200] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"261.35644ms","start":"2026-02-03T22:10:22.343874Z","end":"2026-02-03T22:10:22.60523Z","steps":["trace[1523261200] 'process raft request' (duration: 261.285209ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:10:22.606286Z","caller":"traceutil/trace.go:171","msg":"trace[1471109186] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:504; }","duration":"100.180698ms","start":"2026-02-03T22:10:22.506096Z","end":"2026-02-03T22:10:22.606277Z","steps":["trace[1471109186] 'read index received' (duration: 100.177128ms)","trace[1471109186] 'applied index is now lower than readState.Index' (duration: 3µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:10:22.60644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.424456ms","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-02-03T22:10:22.606498Z","caller":"traceutil/trace.go:171","msg":"trace[1257657747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:486; }","duration":"200.521209ms","start":"2026-02-03T22:10:22.405969Z","end":"2026-02-03T22:10:22.60649Z","steps":["trace[1257657747] 'agreement among raft nodes before linearized reading' (duration: 200.404256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:10:22.606663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.992539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g8xcn\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-03T22:10:22.606715Z","caller":"traceutil/trace.go:171","msg":"trace[938795853] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g8xcn; range_end:; response_count:1; response_revision:486; }","duration":"116.044481ms","start":"2026-02-03T22:10:22.490664Z","end":"2026-02-03T22:10:22.606708Z","steps":["trace[938795853] 'agreement among raft nodes before linearized reading' (duration: 115.970779ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:10:23.624596Z","caller":"traceutil/trace.go:171","msg":"trace[1528887834] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"109.041885ms","start":"2026-02-03T22:10:23.515531Z","end":"2026-02-03T22:10:23.624573Z","steps":["trace[1528887834] 'process raft request' (duration: 108.959493ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:10:37.600119Z","caller":"traceutil/trace.go:171","msg":"trace[883532521] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"133.881797ms","start":"2026-02-03T22:10:37.466218Z","end":"2026-02-03T22:10:37.6001Z","steps":["trace[883532521] 'read index received' (duration: 133.695083ms)","trace[883532521] 'applied index is now lower than readState.Index' (duration: 185.954µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:10:37.600274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.03575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:10:37.600298Z","caller":"traceutil/trace.go:171","msg":"trace[1249756310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"134.112642ms","start":"2026-02-03T22:10:37.466179Z","end":"2026-02-03T22:10:37.600291Z","steps":["trace[1249756310] 'agreement among raft nodes before linearized reading' (duration: 134.0155ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:10:37.600567Z","caller":"traceutil/trace.go:171","msg":"trace[955403532] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"349.91531ms","start":"2026-02-03T22:10:37.250644Z","end":"2026-02-03T22:10:37.60056Z","steps":["trace[955403532] 'process raft request' (duration: 349.334227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:10:37.600642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:10:37.250634Z","time spent":"349.951401ms","remote":"127.0.0.1:46740","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-02-03T22:10:37.76427Z","caller":"traceutil/trace.go:171","msg":"trace[1698360771] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"158.388832ms","start":"2026-02-03T22:10:37.605862Z","end":"2026-02-03T22:10:37.76425Z","steps":["trace[1698360771] 'read index received' (duration: 120.652053ms)","trace[1698360771] 'applied index is now lower than readState.Index' (duration: 37.736139ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:10:37.764367Z","caller":"traceutil/trace.go:171","msg":"trace[1202966794] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"162.445332ms","start":"2026-02-03T22:10:37.601886Z","end":"2026-02-03T22:10:37.764331Z","steps":["trace[1202966794] 'process raft request' (duration: 124.664851ms)","trace[1202966794] 'compare' (duration: 37.600007ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:10:37.764444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.581726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-drchq\" ","response":"range_response_count:1 size:4821"} {"level":"info","ts":"2026-02-03T22:10:37.764469Z","caller":"traceutil/trace.go:171","msg":"trace[139372338] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-drchq; range_end:; response_count:1; response_revision:661; }","duration":"158.652648ms","start":"2026-02-03T22:10:37.60581Z","end":"2026-02-03T22:10:37.764463Z","steps":["trace[139372338] 'agreement among raft nodes before linearized reading' (duration: 158.505175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:10:42.941583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.376725ms","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-02-03T22:10:42.941661Z","caller":"traceutil/trace.go:171","msg":"trace[993094316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"218.482058ms","start":"2026-02-03T22:10:42.723164Z","end":"2026-02-03T22:10:42.941646Z","steps":["trace[993094316] 'range keys from in-memory index tree' (duration: 218.284993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:10:42.941854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.58701ms","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-02-03T22:10:42.94188Z","caller":"traceutil/trace.go:171","msg":"trace[1281317914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"231.64195ms","start":"2026-02-03T22:10:42.710231Z","end":"2026-02-03T22:10:42.941873Z","steps":["trace[1281317914] 'range keys from in-memory index tree' (duration: 231.502277ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:10:42.964389Z","caller":"traceutil/trace.go:171","msg":"trace[2067430291] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"187.737284ms","start":"2026-02-03T22:10:42.776631Z","end":"2026-02-03T22:10:42.964368Z","steps":["trace[2067430291] 'process raft request' (duration: 187.53097ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:11:00.555203Z","caller":"traceutil/trace.go:171","msg":"trace[1237298897] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"118.77322ms","start":"2026-02-03T22:11:00.436409Z","end":"2026-02-03T22:11:00.555182Z","steps":["trace[1237298897] 'process raft request' (duration: 118.495484ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:11:03.284398Z","caller":"traceutil/trace.go:171","msg":"trace[874594747] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"148.301746ms","start":"2026-02-03T22:11:03.13608Z","end":"2026-02-03T22:11:03.284382Z","steps":["trace[874594747] 'process raft request' (duration: 148.041661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:03.657733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.953757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:11:03.658664Z","caller":"traceutil/trace.go:171","msg":"trace[521119497] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"187.933719ms","start":"2026-02-03T22:11:03.470713Z","end":"2026-02-03T22:11:03.658647Z","steps":["trace[521119497] 'process raft request' (duration: 187.111321ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:11:03.659933Z","caller":"traceutil/trace.go:171","msg":"trace[403144376] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"371.297285ms","start":"2026-02-03T22:11:03.288616Z","end":"2026-02-03T22:11:03.659914Z","steps":["trace[403144376] 'process raft request' (duration: 191.091438ms)","trace[403144376] 'compare' (duration: 177.872025ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:11:03.660081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:11:03.288592Z","time spent":"371.383248ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:11:03.663649Z","caller":"traceutil/trace.go:171","msg":"trace[998089353] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"196.253954ms","start":"2026-02-03T22:11:03.467376Z","end":"2026-02-03T22:11:03.66363Z","steps":["trace[998089353] 'read index received' (duration: 12.336794ms)","trace[998089353] 'applied index is now lower than readState.Index' (duration: 183.91465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:11:03.663954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.57483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:11:03.664206Z","caller":"traceutil/trace.go:171","msg":"trace[1087687418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"196.722164ms","start":"2026-02-03T22:11:03.467326Z","end":"2026-02-03T22:11:03.664048Z","steps":["trace[1087687418] 'agreement among raft nodes before linearized reading' (duration: 196.365706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:04.190063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.59748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:11:04.190506Z","caller":"traceutil/trace.go:171","msg":"trace[1528277304] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"499.974596ms","start":"2026-02-03T22:11:03.690505Z","end":"2026-02-03T22:11:04.190479Z","steps":["trace[1528277304] 'process raft request' (duration: 294.218621ms)","trace[1528277304] 'compare' (duration: 204.297432ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:11:04.191376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:11:03.690487Z","time spent":"500.844806ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:11:15.632571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.248967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:11:15.632648Z","caller":"traceutil/trace.go:171","msg":"trace[1462266599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"168.378969ms","start":"2026-02-03T22:11:15.464256Z","end":"2026-02-03T22:11:15.632635Z","steps":["trace[1462266599] 'range keys from in-memory index tree' (duration: 168.179704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:17.167968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.632425ms","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-02-03T22:11:17.168016Z","caller":"traceutil/trace.go:171","msg":"trace[1955621384] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:825; }","duration":"575.709239ms","start":"2026-02-03T22:11:16.592294Z","end":"2026-02-03T22:11:17.168003Z","steps":["trace[1955621384] 'count revisions from in-memory index tree' (duration: 575.584514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:17.168036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:11:16.592268Z","time spent":"575.763229ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:11:17.168177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.063055ms","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-02-03T22:11:17.168193Z","caller":"traceutil/trace.go:171","msg":"trace[1610534806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:825; }","duration":"489.096905ms","start":"2026-02-03T22:11:16.679088Z","end":"2026-02-03T22:11:17.168185Z","steps":["trace[1610534806] 'range keys from in-memory index tree' (duration: 488.975043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:17.168225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:11:16.679075Z","time spent":"489.145856ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T22:11:17.168311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.164698ms","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-02-03T22:11:17.168323Z","caller":"traceutil/trace.go:171","msg":"trace[13063974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:825; }","duration":"434.194658ms","start":"2026-02-03T22:11:16.734124Z","end":"2026-02-03T22:11:17.168319Z","steps":["trace[13063974] 'range keys from in-memory index tree' (duration: 434.106467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:17.168338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:11:16.73411Z","time spent":"434.22143ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T22:11:17.433965Z","caller":"traceutil/trace.go:171","msg":"trace[1960379983] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"261.167407ms","start":"2026-02-03T22:11:17.172785Z","end":"2026-02-03T22:11:17.433953Z","steps":["trace[1960379983] 'process raft request' (duration: 260.999644ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:11:24.114487Z","caller":"traceutil/trace.go:171","msg":"trace[734675676] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"370.732064ms","start":"2026-02-03T22:11:23.74374Z","end":"2026-02-03T22:11:24.114472Z","steps":["trace[734675676] 'process raft request' (duration: 370.639522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:24.114606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:11:23.743728Z","time spent":"370.826436ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:11:24.346874Z","caller":"traceutil/trace.go:171","msg":"trace[1791397242] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"115.081348ms","start":"2026-02-03T22:11:24.231781Z","end":"2026-02-03T22:11:24.346862Z","steps":["trace[1791397242] 'read index received' (duration: 111.820376ms)","trace[1791397242] 'applied index is now lower than readState.Index' (duration: 3.260422ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:11:24.346942Z","caller":"traceutil/trace.go:171","msg":"trace[1355756760] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"539.359678ms","start":"2026-02-03T22:11:23.807577Z","end":"2026-02-03T22:11:24.346936Z","steps":["trace[1355756760] 'process raft request' (duration: 536.065686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:24.346994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:11:23.807564Z","time spent":"539.39024ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:11:24.347101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.312343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:11:24.347142Z","caller":"traceutil/trace.go:171","msg":"trace[694459585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"115.398314ms","start":"2026-02-03T22:11:24.231732Z","end":"2026-02-03T22:11:24.34713Z","steps":["trace[694459585] 'agreement among raft nodes before linearized reading' (duration: 115.342923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:27.640952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.225904ms","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-02-03T22:11:27.641011Z","caller":"traceutil/trace.go:171","msg":"trace[1045695932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:854; }","duration":"162.311995ms","start":"2026-02-03T22:11:27.478688Z","end":"2026-02-03T22:11:27.641Z","steps":["trace[1045695932] 'range keys from in-memory index tree' (duration: 162.125922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:33.366359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.946091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:11:33.366415Z","caller":"traceutil/trace.go:171","msg":"trace[18710032] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:878; }","duration":"189.012323ms","start":"2026-02-03T22:11:33.177392Z","end":"2026-02-03T22:11:33.366405Z","steps":["trace[18710032] 'range keys from in-memory index tree' (duration: 188.8875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:33.3666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.477571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:11:33.366617Z","caller":"traceutil/trace.go:171","msg":"trace[385056846] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:878; }","duration":"112.520492ms","start":"2026-02-03T22:11:33.25409Z","end":"2026-02-03T22:11:33.366611Z","steps":["trace[385056846] 'count revisions from in-memory index tree' (duration: 112.41552ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:11:33.502069Z","caller":"traceutil/trace.go:171","msg":"trace[669298411] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"107.783777ms","start":"2026-02-03T22:11:33.394262Z","end":"2026-02-03T22:11:33.502046Z","steps":["trace[669298411] 'process raft request' (duration: 107.406439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:33.914445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.714287ms","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-02-03T22:11:33.914502Z","caller":"traceutil/trace.go:171","msg":"trace[1094152944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"240.809019ms","start":"2026-02-03T22:11:33.67368Z","end":"2026-02-03T22:11:33.914489Z","steps":["trace[1094152944] 'range keys from in-memory index tree' (duration: 240.588945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:33.914577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.428507ms","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-02-03T22:11:33.914662Z","caller":"traceutil/trace.go:171","msg":"trace[311416357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:879; }","duration":"177.547051ms","start":"2026-02-03T22:11:33.737103Z","end":"2026-02-03T22:11:33.91465Z","steps":["trace[311416357] 'range keys from in-memory index tree' (duration: 177.320695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:48.121411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.900494ms","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-02-03T22:11:48.121485Z","caller":"traceutil/trace.go:171","msg":"trace[1497576825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"149.983135ms","start":"2026-02-03T22:11:47.971485Z","end":"2026-02-03T22:11:48.121468Z","steps":["trace[1497576825] 'range keys from in-memory index tree' (duration: 149.755541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:48.121702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.700655ms","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-02-03T22:11:48.121754Z","caller":"traceutil/trace.go:171","msg":"trace[366066434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"137.779416ms","start":"2026-02-03T22:11:47.983966Z","end":"2026-02-03T22:11:48.121745Z","steps":["trace[366066434] 'range keys from in-memory index tree' (duration: 137.618963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:11:48.121885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.81389ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:11:48.13328Z","caller":"traceutil/trace.go:171","msg":"trace[671571080] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:975; }","duration":"120.205821ms","start":"2026-02-03T22:11:48.013061Z","end":"2026-02-03T22:11:48.133266Z","steps":["trace[671571080] 'range keys from in-memory index tree' (duration: 108.80699ms)"],"step_count":1}