{"level":"warn","ts":"2026-02-09T10:50:03.209651Z","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-09T10:50:03.209894Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-09T10:50:03.210018Z","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-09T10:50:03.210083Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-09T10:50:03.210156Z","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-09T10:50:03.210945Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-02-09T10:50:03.211103Z","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.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-09T10:50:03.215471Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.084238ms"} {"level":"info","ts":"2026-02-09T10:50:03.249217Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-09T10:50:03.252005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T10:50:03.252233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-09T10:50:03.252387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T10:50:03.252582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-09T10:50:03.252828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-09T10:50:03.268888Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T10:50:03.281312Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T10:50:03.287113Z","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-09T10:50:03.301937Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T10:50:03.302399Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T10:50:03.302674Z","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-09T10:50:03.30275Z","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-09T10:50:03.302779Z","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-09T10:50:03.311724Z","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-09T10:50:03.313706Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T10:50:03.313791Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T10:50:03.313887Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-09T10:50:03.313963Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-09T10:50:03.314806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-09T10:50:03.31494Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-09T10:50:04.054265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T10:50:04.0543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T10:50:04.054322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-09T10:50:04.054332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-09T10:50:04.054337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-09T10:50:04.054345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-09T10:50:04.054351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-09T10:50:04.06598Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T10:50:04.066011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T10:50:04.066261Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T10:50:04.068775Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T10:50:04.06889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T10:50:04.068899Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T10:50:04.070265Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-09T10:50:04.07032Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T10:50:04.070357Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T10:50:04.070371Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T10:50:04.071889Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T10:50:32.24467Z","caller":"traceutil/trace.go:171","msg":"trace[1411317772] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"140.272157ms","start":"2026-02-09T10:50:32.104381Z","end":"2026-02-09T10:50:32.244653Z","steps":["trace[1411317772] 'process raft request' (duration: 140.195456ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:50:35.207454Z","caller":"traceutil/trace.go:171","msg":"trace[1921123192] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"297.796772ms","start":"2026-02-09T10:50:34.909636Z","end":"2026-02-09T10:50:35.207433Z","steps":["trace[1921123192] 'process raft request' (duration: 297.633191ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:50:38.320341Z","caller":"traceutil/trace.go:171","msg":"trace[9550774] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"271.491609ms","start":"2026-02-09T10:50:38.048792Z","end":"2026-02-09T10:50:38.320284Z","steps":["trace[9550774] 'read index received' (duration: 271.201787ms)","trace[9550774] 'applied index is now lower than readState.Index' (duration: 288.722µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:50:38.320512Z","caller":"traceutil/trace.go:171","msg":"trace[539572582] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"326.777505ms","start":"2026-02-09T10:50:37.993721Z","end":"2026-02-09T10:50:38.320498Z","steps":["trace[539572582] 'process raft request' (duration: 326.309392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:38.320706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.320198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T10:50:38.32077Z","caller":"traceutil/trace.go:171","msg":"trace[2097553342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"267.46667ms","start":"2026-02-09T10:50:38.053289Z","end":"2026-02-09T10:50:38.320756Z","steps":["trace[2097553342] 'agreement among raft nodes before linearized reading' (duration: 267.263459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:38.321028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.248604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-09T10:50:38.321057Z","caller":"traceutil/trace.go:171","msg":"trace[1965788412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"272.342654ms","start":"2026-02-09T10:50:38.048706Z","end":"2026-02-09T10:50:38.321049Z","steps":["trace[1965788412] 'agreement among raft nodes before linearized reading' (duration: 272.235184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:38.321304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:37.993707Z","time spent":"326.830567ms","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:50:42.294232Z","caller":"traceutil/trace.go:171","msg":"trace[1533411197] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"279.966324ms","start":"2026-02-09T10:50:42.014247Z","end":"2026-02-09T10:50:42.294213Z","steps":["trace[1533411197] 'process raft request' (duration: 279.829423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:42.854878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360160921450919,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T10:50:43.355632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360160921450919,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T10:50:43.42758Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.092621934s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T10:50:43.427893Z","caller":"traceutil/trace.go:171","msg":"trace[539488580] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.092950687s","start":"2026-02-09T10:50:42.334886Z","end":"2026-02-09T10:50:43.427837Z","steps":["trace[539488580] 'process raft request' (duration: 1.092858916s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:43.427983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:42.334875Z","time spent":"1.093043567s","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:50:43.535357Z","caller":"traceutil/trace.go:171","msg":"trace[226215140] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"1.180908544s","start":"2026-02-09T10:50:42.354425Z","end":"2026-02-09T10:50:43.535334Z","steps":["trace[226215140] 'read index received' (duration: 1.074342175s)","trace[226215140] 'applied index is now lower than readState.Index' (duration: 106.564959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:50:43.535629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181188836s","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-09T10:50:43.535665Z","caller":"traceutil/trace.go:171","msg":"trace[491932839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"1.181276546s","start":"2026-02-09T10:50:42.354376Z","end":"2026-02-09T10:50:43.535652Z","steps":["trace[491932839] 'agreement among raft nodes before linearized reading' (duration: 1.181084535s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:43.535699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:42.354364Z","time spent":"1.181326456s","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T10:50:43.536122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181032324s","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-09T10:50:43.536176Z","caller":"traceutil/trace.go:171","msg":"trace[1886217163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"1.181099065s","start":"2026-02-09T10:50:42.355066Z","end":"2026-02-09T10:50:43.536165Z","steps":["trace[1886217163] 'agreement among raft nodes before linearized reading' (duration: 1.180989244s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:43.536206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:42.35506Z","time spent":"1.181139556s","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T10:50:43.536445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15908458s","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-02-09T10:50:43.536574Z","caller":"traceutil/trace.go:171","msg":"trace[640640835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"1.159233351s","start":"2026-02-09T10:50:42.377328Z","end":"2026-02-09T10:50:43.536561Z","steps":["trace[640640835] 'agreement among raft nodes before linearized reading' (duration: 1.159056529s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:43.536613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:42.377315Z","time spent":"1.159284742s","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T10:50:45.382155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721115626s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:50:45.382342Z","caller":"traceutil/trace.go:171","msg":"trace[805679630] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:578; }","duration":"1.691433241s","start":"2026-02-09T10:50:43.690894Z","end":"2026-02-09T10:50:45.382327Z","steps":["trace[805679630] 'read index received' (duration: 22.14µs)","trace[805679630] 'applied index is now lower than readState.Index' (duration: 1.691409991s)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:50:45.382415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691518894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:50:45.382438Z","caller":"traceutil/trace.go:171","msg":"trace[563395464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"1.691566175s","start":"2026-02-09T10:50:43.690864Z","end":"2026-02-09T10:50:45.382431Z","steps":["trace[563395464] 'agreement among raft nodes before linearized reading' (duration: 1.691508993s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:45.382466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:43.690852Z","time spent":"1.691608237s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T10:50:45.38271Z","caller":"traceutil/trace.go:171","msg":"trace[477271065] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.842907808s","start":"2026-02-09T10:50:43.53979Z","end":"2026-02-09T10:50:45.382698Z","steps":["trace[477271065] 'process raft request' (duration: 121.01631ms)","trace[477271065] 'compare' (duration: 1.720741811s)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:50:45.382792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:43.53978Z","time spent":"1.84295631s","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:50:45.382907Z","caller":"traceutil/trace.go:171","msg":"trace[131376206] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.841521166s","start":"2026-02-09T10:50:43.541377Z","end":"2026-02-09T10:50:45.382898Z","steps":["trace[131376206] 'process raft request' (duration: 1.84089135s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:45.382957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:43.541367Z","time spent":"1.841560917s","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:50:53.751635Z","caller":"traceutil/trace.go:171","msg":"trace[1342346784] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"140.043116ms","start":"2026-02-09T10:50:53.611575Z","end":"2026-02-09T10:50:53.751618Z","steps":["trace[1342346784] 'process raft request' (duration: 128.711254ms)","trace[1342346784] 'compare' (duration: 11.02246ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:50:55.442865Z","caller":"traceutil/trace.go:171","msg":"trace[2089722835] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"132.059577ms","start":"2026-02-09T10:50:55.310787Z","end":"2026-02-09T10:50:55.442847Z","steps":["trace[2089722835] 'process raft request' (duration: 131.921992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.65477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.020998ms","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-09T10:50:55.654816Z","caller":"traceutil/trace.go:171","msg":"trace[812759042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"126.094651ms","start":"2026-02-09T10:50:55.528711Z","end":"2026-02-09T10:50:55.654805Z","steps":["trace[812759042] 'range keys from in-memory index tree' (duration: 125.912124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.954472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.074544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:50:55.954582Z","caller":"traceutil/trace.go:171","msg":"trace[1611222309] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"337.922326ms","start":"2026-02-09T10:50:55.616645Z","end":"2026-02-09T10:50:55.954567Z","steps":["trace[1611222309] 'process raft request' (duration: 152.704197ms)","trace[1611222309] 'compare' (duration: 183.773177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:50:55.954622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:55.616627Z","time spent":"337.979609ms","remote":"127.0.0.1:33562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T10:50:55.975886Z","caller":"traceutil/trace.go:171","msg":"trace[1978784956] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:686; }","duration":"301.802874ms","start":"2026-02-09T10:50:55.674069Z","end":"2026-02-09T10:50:55.975872Z","steps":["trace[1978784956] 'read index received' (duration: 95.220239ms)","trace[1978784956] 'applied index is now lower than readState.Index' (duration: 206.582115ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:50:55.976091Z","caller":"traceutil/trace.go:171","msg":"trace[245844304] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"310.243491ms","start":"2026-02-09T10:50:55.665842Z","end":"2026-02-09T10:50:55.976085Z","steps":["trace[245844304] 'process raft request' (duration: 309.819896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.976176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:55.665831Z","time spent":"310.297933ms","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:50:55.9763Z","caller":"traceutil/trace.go:171","msg":"trace[1604510499] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"310.402587ms","start":"2026-02-09T10:50:55.665892Z","end":"2026-02-09T10:50:55.976294Z","steps":["trace[1604510499] 'process raft request' (duration: 309.838656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.976345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:55.665888Z","time spent":"310.441468ms","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:50:55.976521Z","caller":"traceutil/trace.go:171","msg":"trace[1056947456] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"223.641155ms","start":"2026-02-09T10:50:55.752874Z","end":"2026-02-09T10:50:55.976516Z","steps":["trace[1056947456] 'process raft request' (duration: 222.918678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.976716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.643215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-09T10:50:55.976811Z","caller":"traceutil/trace.go:171","msg":"trace[127562124] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:671; }","duration":"302.756309ms","start":"2026-02-09T10:50:55.674048Z","end":"2026-02-09T10:50:55.976804Z","steps":["trace[127562124] 'agreement among raft nodes before linearized reading' (duration: 302.610824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.976847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:55.674038Z","time spent":"302.803071ms","remote":"127.0.0.1:33562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-09T10:50:55.977158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.326586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:50:55.977956Z","caller":"traceutil/trace.go:171","msg":"trace[763418489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"286.136186ms","start":"2026-02-09T10:50:55.691813Z","end":"2026-02-09T10:50:55.977949Z","steps":["trace[763418489] 'agreement among raft nodes before linearized reading' (duration: 285.330667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.978319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.195245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-09T10:50:55.979936Z","caller":"traceutil/trace.go:171","msg":"trace[1817047334] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:671; }","duration":"105.831925ms","start":"2026-02-09T10:50:55.874095Z","end":"2026-02-09T10:50:55.979927Z","steps":["trace[1817047334] 'agreement among raft nodes before linearized reading' (duration: 104.183495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.978662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.859648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:50:55.980185Z","caller":"traceutil/trace.go:171","msg":"trace[1760398073] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:671; }","duration":"180.409045ms","start":"2026-02-09T10:50:55.799761Z","end":"2026-02-09T10:50:55.98017Z","steps":["trace[1760398073] 'agreement among raft nodes before linearized reading' (duration: 178.827057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.978707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.947041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:50:55.980388Z","caller":"traceutil/trace.go:171","msg":"trace[517302018] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:671; }","duration":"180.637202ms","start":"2026-02-09T10:50:55.799744Z","end":"2026-02-09T10:50:55.980381Z","steps":["trace[517302018] 'agreement among raft nodes before linearized reading' (duration: 178.949521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.978737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.003023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:50:55.98055Z","caller":"traceutil/trace.go:171","msg":"trace[1807529043] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:671; }","duration":"180.822519ms","start":"2026-02-09T10:50:55.799722Z","end":"2026-02-09T10:50:55.980544Z","steps":["trace[1807529043] 'agreement among raft nodes before linearized reading' (duration: 178.994793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.978821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.928497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-09T10:50:55.980747Z","caller":"traceutil/trace.go:171","msg":"trace[428065798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:671; }","duration":"181.879818ms","start":"2026-02-09T10:50:55.798861Z","end":"2026-02-09T10:50:55.980741Z","steps":["trace[428065798] 'agreement among raft nodes before linearized reading' (duration: 179.883756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:55.97886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.747327ms","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-02-09T10:50:55.984895Z","caller":"traceutil/trace.go:171","msg":"trace[42202541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:671; }","duration":"227.789576ms","start":"2026-02-09T10:50:55.757098Z","end":"2026-02-09T10:50:55.984888Z","steps":["trace[42202541] 'agreement among raft nodes before linearized reading' (duration: 221.731226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:56.382368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.817493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:50:56.382711Z","caller":"traceutil/trace.go:171","msg":"trace[6823626] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"266.088757ms","start":"2026-02-09T10:50:56.116606Z","end":"2026-02-09T10:50:56.382695Z","steps":["trace[6823626] 'read index received' (duration: 48.198962ms)","trace[6823626] 'applied index is now lower than readState.Index' (duration: 217.888855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:50:56.383526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.908576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-09T10:50:56.383612Z","caller":"traceutil/trace.go:171","msg":"trace[488646048] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:673; }","duration":"267.036281ms","start":"2026-02-09T10:50:56.116541Z","end":"2026-02-09T10:50:56.383578Z","steps":["trace[488646048] 'agreement among raft nodes before linearized reading' (duration: 266.336225ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:50:56.384316Z","caller":"traceutil/trace.go:171","msg":"trace[1071106024] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"322.418799ms","start":"2026-02-09T10:50:56.061876Z","end":"2026-02-09T10:50:56.384295Z","steps":["trace[1071106024] 'process raft request' (duration: 320.738529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:50:56.384423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:56.061859Z","time spent":"322.501453ms","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:50:56.384538Z","caller":"traceutil/trace.go:171","msg":"trace[1413525058] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"337.874495ms","start":"2026-02-09T10:50:56.046655Z","end":"2026-02-09T10:50:56.38453Z","steps":["trace[1413525058] 'process raft request' (duration: 19.830682ms)","trace[1413525058] 'compare' (duration: 315.206871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:50:56.384564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:50:56.04664Z","time spent":"337.910407ms","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:50:56.423538Z","caller":"traceutil/trace.go:171","msg":"trace[950148166] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"249.75148ms","start":"2026-02-09T10:50:56.173772Z","end":"2026-02-09T10:50:56.423523Z","steps":["trace[950148166] 'process raft request' (duration: 249.49811ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:50:56.657117Z","caller":"traceutil/trace.go:171","msg":"trace[970245268] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"124.692329ms","start":"2026-02-09T10:50:56.532276Z","end":"2026-02-09T10:50:56.656968Z","steps":["trace[970245268] 'process raft request' (duration: 123.352391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:52:12.722975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.66973ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T10:52:12.72428Z","caller":"traceutil/trace.go:171","msg":"trace[1533348703] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"270.40063ms","start":"2026-02-09T10:52:12.453858Z","end":"2026-02-09T10:52:12.724258Z","steps":["trace[1533348703] 'read index received' (duration: 5.388396ms)","trace[1533348703] 'applied index is now lower than readState.Index' (duration: 265.010114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:52:12.724616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.69555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-09T10:52:12.724628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.740786ms","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-09T10:52:12.724765Z","caller":"traceutil/trace.go:171","msg":"trace[1572398048] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:989; }","duration":"268.790872ms","start":"2026-02-09T10:52:12.45589Z","end":"2026-02-09T10:52:12.72468Z","steps":["trace[1572398048] 'agreement among raft nodes before linearized reading' (duration: 268.651949ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:52:12.72483Z","caller":"traceutil/trace.go:171","msg":"trace[169307256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"270.893529ms","start":"2026-02-09T10:52:12.453839Z","end":"2026-02-09T10:52:12.724732Z","steps":["trace[169307256] 'agreement among raft nodes before linearized reading' (duration: 270.613804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:52:12.726698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.443273ms","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-09T10:52:12.726726Z","caller":"traceutil/trace.go:171","msg":"trace[1951234297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"228.517203ms","start":"2026-02-09T10:52:12.498202Z","end":"2026-02-09T10:52:12.726719Z","steps":["trace[1951234297] 'agreement among raft nodes before linearized reading' (duration: 228.444312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:52:13.128427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.977066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-09T10:52:13.128523Z","caller":"traceutil/trace.go:171","msg":"trace[278196726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"303.118019ms","start":"2026-02-09T10:52:12.825384Z","end":"2026-02-09T10:52:13.128502Z","steps":["trace[278196726] 'range keys from in-memory index tree' (duration: 302.795452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:52:13.128603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:52:12.825369Z","time spent":"303.22176ms","remote":"127.0.0.1:33758","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\" "}