{"level":"warn","ts":"2026-09-22T01:50:32.112501Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:50:32.112609Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.165: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.165:2380","--initial-cluster=instance=https://162.253.55.165:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.165:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.165: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-09-22T01:50:32.112701Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:50:32.112717Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.165:2380"]} {"level":"info","ts":"2026-09-22T01:50:32.112742Z","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-09-22T01:50:32.113498Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"]} {"level":"info","ts":"2026-09-22T01:50:32.113595Z","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://162.253.55.165:2380"],"listen-peer-urls":["https://162.253.55.165:2380"],"advertise-client-urls":["https://162.253.55.165:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.165: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-09-22T01:50:32.117514Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.648487ms"} {"level":"info","ts":"2026-09-22T01:50:32.127735Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9d471e5f5fb9faf","cluster-id":"fb4c33c289aeb291"} {"level":"info","ts":"2026-09-22T01:50:32.12782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:50:32.127845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became follower at term 0"} {"level":"info","ts":"2026-09-22T01:50:32.127869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9d471e5f5fb9faf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:50:32.127878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became follower at term 1"} {"level":"info","ts":"2026-09-22T01:50:32.127929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=(16849217338388225967)"} {"level":"warn","ts":"2026-09-22T01:50:32.133626Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:50:32.136534Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:50:32.138115Z","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-09-22T01:50:32.140441Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9d471e5f5fb9faf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:50:32.142056Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9d471e5f5fb9faf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:50:32.142183Z","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-09-22T01:50:32.142227Z","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-09-22T01:50:32.142234Z","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-09-22T01:50:32.143239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=(16849217338388225967)"} {"level":"info","ts":"2026-09-22T01:50:32.143585Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb4c33c289aeb291","local-member-id":"e9d471e5f5fb9faf","added-peer-id":"e9d471e5f5fb9faf","added-peer-peer-urls":["https://162.253.55.165:2380"]} {"level":"info","ts":"2026-09-22T01:50:32.144704Z","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-09-22T01:50:32.144818Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.165:2380"} {"level":"info","ts":"2026-09-22T01:50:32.144841Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.165:2380"} {"level":"info","ts":"2026-09-22T01:50:32.145063Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9d471e5f5fb9faf","initial-advertise-peer-urls":["https://162.253.55.165:2380"],"listen-peer-urls":["https://162.253.55.165:2380"],"advertise-client-urls":["https://162.253.55.165:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:50:32.145116Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:50:32.428579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:50:32.428642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:50:32.428666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf received MsgPreVoteResp from e9d471e5f5fb9faf at term 1"} {"level":"info","ts":"2026-09-22T01:50:32.428685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:50:32.428696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf received MsgVoteResp from e9d471e5f5fb9faf at term 2"} {"level":"info","ts":"2026-09-22T01:50:32.428712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became leader at term 2"} {"level":"info","ts":"2026-09-22T01:50:32.428731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9d471e5f5fb9faf elected leader e9d471e5f5fb9faf at term 2"} {"level":"info","ts":"2026-09-22T01:50:32.431445Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:50:32.433051Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb4c33c289aeb291","local-member-id":"e9d471e5f5fb9faf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:50:32.433171Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:50:32.433247Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:50:32.433338Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9d471e5f5fb9faf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.165:2379]}","request-path":"/0/members/e9d471e5f5fb9faf/attributes","cluster-id":"fb4c33c289aeb291","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:50:32.435141Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:50:32.435168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:50:32.437237Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.165:2379"} {"level":"info","ts":"2026-09-22T01:50:32.438278Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:50:32.438372Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:50:32.438577Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-22T01:50:38.392092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.85789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:50:38.392201Z","caller":"traceutil/trace.go:171","msg":"trace[549157982] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"214.972922ms","start":"2026-09-22T01:50:38.17721Z","end":"2026-09-22T01:50:38.392183Z","steps":["trace[549157982] 'read index received' (duration: 46.59873ms)","trace[549157982] 'applied index is now lower than readState.Index' (duration: 168.372732ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:50:38.392243Z","caller":"traceutil/trace.go:171","msg":"trace[1097352272] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"307.361934ms","start":"2026-09-22T01:50:38.084849Z","end":"2026-09-22T01:50:38.392211Z","steps":["trace[1097352272] 'process raft request' (duration: 138.91168ms)","trace[1097352272] 'compare' (duration: 167.483381ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:50:38.392369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.156397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:50:38.392408Z","caller":"traceutil/trace.go:171","msg":"trace[436773463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:286; }","duration":"215.24398ms","start":"2026-09-22T01:50:38.177154Z","end":"2026-09-22T01:50:38.392398Z","steps":["trace[436773463] 'agreement among raft nodes before linearized reading' (duration: 215.149347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:38.392427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:50:38.084833Z","time spent":"307.498697ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:50:38.39246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.354489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:50:38.392569Z","caller":"traceutil/trace.go:171","msg":"trace[1666411131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:286; }","duration":"123.504523ms","start":"2026-09-22T01:50:38.26903Z","end":"2026-09-22T01:50:38.392535Z","steps":["trace[1666411131] 'agreement among raft nodes before linearized reading' (duration: 123.315838ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:38.586154Z","caller":"traceutil/trace.go:171","msg":"trace[888290285] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"188.604914ms","start":"2026-09-22T01:50:38.397516Z","end":"2026-09-22T01:50:38.586121Z","steps":["trace[888290285] 'process raft request' (duration: 153.63233ms)","trace[888290285] 'compare' (duration: 34.618026ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:50:38.906595Z","caller":"traceutil/trace.go:171","msg":"trace[1392420688] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:295; }","duration":"431.997285ms","start":"2026-09-22T01:50:38.474544Z","end":"2026-09-22T01:50:38.906541Z","steps":["trace[1392420688] 'read index received' (duration: 76.707308ms)","trace[1392420688] 'applied index is now lower than readState.Index' (duration: 355.288357ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:50:38.906647Z","caller":"traceutil/trace.go:171","msg":"trace[1666471125] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"506.92513ms","start":"2026-09-22T01:50:38.399659Z","end":"2026-09-22T01:50:38.906584Z","steps":["trace[1666471125] 'process raft request' (duration: 501.299076ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:38.906663Z","caller":"traceutil/trace.go:171","msg":"trace[1470175211] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"500.20931ms","start":"2026-09-22T01:50:38.406396Z","end":"2026-09-22T01:50:38.906605Z","steps":["trace[1470175211] 'process raft request' (duration: 500.073297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:38.906805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:50:38.397885Z","time spent":"508.841166ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:50:38.906829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.25702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T01:50:38.90688Z","caller":"traceutil/trace.go:171","msg":"trace[1440732694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:289; }","duration":"432.381683ms","start":"2026-09-22T01:50:38.474486Z","end":"2026-09-22T01:50:38.906868Z","steps":["trace[1440732694] 'agreement among raft nodes before linearized reading' (duration: 432.23538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:38.906913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:50:38.474466Z","time spent":"432.440215ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T01:50:38.907077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:50:38.406365Z","time spent":"500.387994ms","remote":"127.0.0.1:51826","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-09-22T01:50:38.907458Z","caller":"traceutil/trace.go:171","msg":"trace[29449478] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"316.458581ms","start":"2026-09-22T01:50:38.590957Z","end":"2026-09-22T01:50:38.907416Z","steps":["trace[29449478] 'process raft request' (duration: 316.091163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:38.907618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:50:38.590927Z","time spent":"316.611395ms","remote":"127.0.0.1:51658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:50:38.907623Z","caller":"traceutil/trace.go:171","msg":"trace[946471163] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"105.437763ms","start":"2026-09-22T01:50:38.802174Z","end":"2026-09-22T01:50:38.907612Z","steps":["trace[946471163] 'process raft request' (duration: 105.199277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:38.90765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.625379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:50:38.907627Z","caller":"traceutil/trace.go:171","msg":"trace[1119389333] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"104.640353ms","start":"2026-09-22T01:50:38.802942Z","end":"2026-09-22T01:50:38.907583Z","steps":["trace[1119389333] 'process raft request' (duration: 104.49601ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:38.907694Z","caller":"traceutil/trace.go:171","msg":"trace[192234040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:290; }","duration":"317.709111ms","start":"2026-09-22T01:50:38.589971Z","end":"2026-09-22T01:50:38.90768Z","steps":["trace[192234040] 'agreement among raft nodes before linearized reading' (duration: 317.607998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:38.907786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:50:38.589947Z","time spent":"317.830274ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T01:50:38.907872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.151174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-09-22T01:50:38.907916Z","caller":"traceutil/trace.go:171","msg":"trace[2083364914] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:290; }","duration":"107.238946ms","start":"2026-09-22T01:50:38.800666Z","end":"2026-09-22T01:50:38.907905Z","steps":["trace[2083364914] 'agreement among raft nodes before linearized reading' (duration: 107.131924ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:38.907968Z","caller":"traceutil/trace.go:171","msg":"trace[904140676] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"104.48217ms","start":"2026-09-22T01:50:38.803478Z","end":"2026-09-22T01:50:38.90796Z","steps":["trace[904140676] 'process raft request' (duration: 104.066691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:38.907967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.341112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-22T01:50:38.908012Z","caller":"traceutil/trace.go:171","msg":"trace[1630651757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:290; }","duration":"317.425234ms","start":"2026-09-22T01:50:38.590575Z","end":"2026-09-22T01:50:38.908Z","steps":["trace[1630651757] 'agreement among raft nodes before linearized reading' (duration: 317.335402ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:38.908048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:50:38.590558Z","time spent":"317.483765ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-22T01:51:18.631504Z","caller":"traceutil/trace.go:171","msg":"trace[960209678] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"129.455ms","start":"2026-09-22T01:51:18.502Z","end":"2026-09-22T01:51:18.631455Z","steps":["trace[960209678] 'process raft request' (duration: 129.226784ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:18.890788Z","caller":"traceutil/trace.go:171","msg":"trace[353386085] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"387.60878ms","start":"2026-09-22T01:51:18.503132Z","end":"2026-09-22T01:51:18.89074Z","steps":["trace[353386085] 'process raft request' (duration: 387.140989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:18.890971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.503079Z","time spent":"387.813275ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:18.890922Z","caller":"traceutil/trace.go:171","msg":"trace[1315712171] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"387.457636ms","start":"2026-09-22T01:51:18.503422Z","end":"2026-09-22T01:51:18.89088Z","steps":["trace[1315712171] 'process raft request' (duration: 387.20711ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:18.891159Z","caller":"traceutil/trace.go:171","msg":"trace[557387996] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"373.963318ms","start":"2026-09-22T01:51:18.517102Z","end":"2026-09-22T01:51:18.891066Z","steps":["trace[557387996] 'process raft request' (duration: 373.62233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:18.891429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.517082Z","time spent":"374.236595ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:18.891444Z","caller":"traceutil/trace.go:171","msg":"trace[1307954035] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"363.552622ms","start":"2026-09-22T01:51:18.527769Z","end":"2026-09-22T01:51:18.891322Z","steps":["trace[1307954035] 'process raft request' (duration: 363.110151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:18.891664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.527749Z","time spent":"363.808598ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:18.891552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.503413Z","time spent":"388.03014ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:18.892039Z","caller":"traceutil/trace.go:171","msg":"trace[1555975158] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"360.309775ms","start":"2026-09-22T01:51:18.531712Z","end":"2026-09-22T01:51:18.892022Z","steps":["trace[1555975158] 'process raft request' (duration: 359.422794ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:18.892117Z","caller":"traceutil/trace.go:171","msg":"trace[1330966153] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"349.703404ms","start":"2026-09-22T01:51:18.542394Z","end":"2026-09-22T01:51:18.892098Z","steps":["trace[1330966153] 'process raft request' (duration: 349.445358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:18.892138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.531691Z","time spent":"360.399067ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:18.892198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.542378Z","time spent":"349.768086ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:18.892377Z","caller":"traceutil/trace.go:171","msg":"trace[1905441403] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"349.057799ms","start":"2026-09-22T01:51:18.543307Z","end":"2026-09-22T01:51:18.892365Z","steps":["trace[1905441403] 'process raft request' (duration: 348.716101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:18.892451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.543298Z","time spent":"349.10674ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:51:18.895188Z","caller":"traceutil/trace.go:171","msg":"trace[2135124876] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"298.614888ms","start":"2026-09-22T01:51:18.596549Z","end":"2026-09-22T01:51:18.895164Z","steps":["trace[2135124876] 'process raft request' (duration: 298.342651ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:18.895791Z","caller":"traceutil/trace.go:171","msg":"trace[1916653645] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"342.348351ms","start":"2026-09-22T01:51:18.553424Z","end":"2026-09-22T01:51:18.895772Z","steps":["trace[1916653645] 'process raft request' (duration: 339.642087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:18.896055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.553412Z","time spent":"342.480594ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:18.896106Z","caller":"traceutil/trace.go:171","msg":"trace[1086630718] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"323.595878ms","start":"2026-09-22T01:51:18.572462Z","end":"2026-09-22T01:51:18.896058Z","steps":["trace[1086630718] 'process raft request' (duration: 321.949799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:18.896266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.572454Z","time spent":"323.734091ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:18.896447Z","caller":"traceutil/trace.go:171","msg":"trace[541974087] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"331.512685ms","start":"2026-09-22T01:51:18.564924Z","end":"2026-09-22T01:51:18.896437Z","steps":["trace[541974087] 'process raft request' (duration: 328.742319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:18.896488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.564915Z","time spent":"331.552045ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:19.04207Z","caller":"traceutil/trace.go:171","msg":"trace[202046145] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"402.339959ms","start":"2026-09-22T01:51:18.639327Z","end":"2026-09-22T01:51:19.041667Z","steps":["trace[202046145] 'process raft request' (duration: 395.396475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:19.042458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.639256Z","time spent":"402.896612ms","remote":"127.0.0.1:52066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:19.043034Z","caller":"traceutil/trace.go:171","msg":"trace[1616025682] transaction","detail":"{read_only:false; number_of_response:1; response_revision:555; }","duration":"401.476508ms","start":"2026-09-22T01:51:18.641528Z","end":"2026-09-22T01:51:19.043004Z","steps":["trace[1616025682] 'process raft request' (duration: 400.123436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:19.043308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.641513Z","time spent":"401.572611ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:51:19.046319Z","caller":"traceutil/trace.go:171","msg":"trace[83296090] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"405.33464ms","start":"2026-09-22T01:51:18.640928Z","end":"2026-09-22T01:51:19.046263Z","steps":["trace[83296090] 'process raft request' (duration: 400.663039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:19.046617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.640912Z","time spent":"405.591675ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:51:19.046895Z","caller":"traceutil/trace.go:171","msg":"trace[315487108] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"312.345172ms","start":"2026-09-22T01:51:18.734529Z","end":"2026-09-22T01:51:19.046875Z","steps":["trace[315487108] 'process raft request' (duration: 312.020274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:19.047007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:18.734481Z","time spent":"312.471065ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:19.050212Z","caller":"traceutil/trace.go:171","msg":"trace[1102118939] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"122.943705ms","start":"2026-09-22T01:51:18.927248Z","end":"2026-09-22T01:51:19.050191Z","steps":["trace[1102118939] 'process raft request' (duration: 122.898924ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:19.050257Z","caller":"traceutil/trace.go:171","msg":"trace[227509504] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"144.27982ms","start":"2026-09-22T01:51:18.905942Z","end":"2026-09-22T01:51:19.050222Z","steps":["trace[227509504] 'process raft request' (duration: 143.971543ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:19.050443Z","caller":"traceutil/trace.go:171","msg":"trace[1291117406] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"143.065271ms","start":"2026-09-22T01:51:18.907369Z","end":"2026-09-22T01:51:19.050435Z","steps":["trace[1291117406] 'process raft request' (duration: 142.58446ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:19.050539Z","caller":"traceutil/trace.go:171","msg":"trace[412124052] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"131.470537ms","start":"2026-09-22T01:51:18.91906Z","end":"2026-09-22T01:51:19.050531Z","steps":["trace[412124052] 'process raft request' (duration: 131.046497ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:19.050545Z","caller":"traceutil/trace.go:171","msg":"trace[1958563498] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"134.871407ms","start":"2026-09-22T01:51:18.91561Z","end":"2026-09-22T01:51:19.050482Z","steps":["trace[1958563498] 'process raft request' (duration: 134.397806ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:19.050697Z","caller":"traceutil/trace.go:171","msg":"trace[1055297096] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"141.463513ms","start":"2026-09-22T01:51:18.909223Z","end":"2026-09-22T01:51:19.050686Z","steps":["trace[1055297096] 'process raft request' (duration: 140.755356ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:19.05082Z","caller":"traceutil/trace.go:171","msg":"trace[1862289252] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"149.063433ms","start":"2026-09-22T01:51:18.901748Z","end":"2026-09-22T01:51:19.050812Z","steps":["trace[1862289252] 'process raft request' (duration: 148.017448ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:19.050934Z","caller":"traceutil/trace.go:171","msg":"trace[111667929] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"149.072933ms","start":"2026-09-22T01:51:18.901809Z","end":"2026-09-22T01:51:19.050882Z","steps":["trace[111667929] 'process raft request' (duration: 148.067069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:26.525417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.555737ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-09-22T01:51:26.526325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:25.935823Z","time spent":"590.493405ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-22T01:51:26.526909Z","caller":"traceutil/trace.go:171","msg":"trace[1494769661] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"582.067396ms","start":"2026-09-22T01:51:25.944805Z","end":"2026-09-22T01:51:26.526872Z","steps":["trace[1494769661] 'process raft request' (duration: 581.527593ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:26.52698Z","caller":"traceutil/trace.go:171","msg":"trace[444049212] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:678; }","duration":"376.305893ms","start":"2026-09-22T01:51:26.150638Z","end":"2026-09-22T01:51:26.526944Z","steps":["trace[444049212] 'read index received' (duration: 368.775706ms)","trace[444049212] 'applied index is now lower than readState.Index' (duration: 7.528607ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:26.527141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:25.944783Z","time spent":"582.205579ms","remote":"127.0.0.1:51752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:51:26.527187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.518938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:51:26.52723Z","caller":"traceutil/trace.go:171","msg":"trace[1523395813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"376.630631ms","start":"2026-09-22T01:51:26.150585Z","end":"2026-09-22T01:51:26.527216Z","steps":["trace[1523395813] 'agreement among raft nodes before linearized reading' (duration: 376.482637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:26.527294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:26.150561Z","time spent":"376.704882ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T01:51:26.527523Z","caller":"traceutil/trace.go:171","msg":"trace[581213227] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"534.188765ms","start":"2026-09-22T01:51:25.993322Z","end":"2026-09-22T01:51:26.527511Z","steps":["trace[581213227] 'process raft request' (duration: 533.298354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:26.528459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:25.993268Z","time spent":"534.294257ms","remote":"127.0.0.1:52058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:52:26.255905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11506592348687223162,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T01:52:26.27559Z","caller":"traceutil/trace.go:171","msg":"trace[1222330505] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"533.695528ms","start":"2026-09-22T01:52:25.741861Z","end":"2026-09-22T01:52:26.275556Z","steps":["trace[1222330505] 'process raft request' (duration: 533.490173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:26.275808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:25.741846Z","time spent":"533.845502ms","remote":"127.0.0.1:51826","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-09-22T01:52:26.276152Z","caller":"traceutil/trace.go:171","msg":"trace[1935156449] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1152; }","duration":"521.12363ms","start":"2026-09-22T01:52:25.755005Z","end":"2026-09-22T01:52:26.276128Z","steps":["trace[1935156449] 'read index received' (duration: 520.326691ms)","trace[1935156449] 'applied index is now lower than readState.Index' (duration: 795.739µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:52:26.276211Z","caller":"traceutil/trace.go:171","msg":"trace[202507984] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"534.197681ms","start":"2026-09-22T01:52:25.741966Z","end":"2026-09-22T01:52:26.276164Z","steps":["trace[202507984] 'process raft request' (duration: 534.038407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:26.276376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.351255ms","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-09-22T01:52:26.276381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.072671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:52:26.276406Z","caller":"traceutil/trace.go:171","msg":"trace[1681997323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1110; }","duration":"521.460348ms","start":"2026-09-22T01:52:25.754935Z","end":"2026-09-22T01:52:26.276395Z","steps":["trace[1681997323] 'agreement among raft nodes before linearized reading' (duration: 521.274894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:26.276435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:25.754905Z","time spent":"521.523349ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T01:52:26.276421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:25.741943Z","time spent":"534.374904ms","remote":"127.0.0.1:51826","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-09-22T01:52:26.276452Z","caller":"traceutil/trace.go:171","msg":"trace[669097660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1110; }","duration":"128.171863ms","start":"2026-09-22T01:52:26.148241Z","end":"2026-09-22T01:52:26.276413Z","steps":["trace[669097660] 'agreement among raft nodes before linearized reading' (duration: 128.093051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:26.276494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.968115ms","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-09-22T01:52:26.276573Z","caller":"traceutil/trace.go:171","msg":"trace[810726336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1110; }","duration":"518.088258ms","start":"2026-09-22T01:52:25.758464Z","end":"2026-09-22T01:52:26.276553Z","steps":["trace[810726336] 'agreement among raft nodes before linearized reading' (duration: 517.849492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:26.276638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:25.758455Z","time spent":"518.17443ms","remote":"127.0.0.1:51826","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":"warn","ts":"2026-09-22T01:52:26.276696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.485447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.165\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-22T01:52:26.276732Z","caller":"traceutil/trace.go:171","msg":"trace[659005365] range","detail":"{range_begin:/registry/masterleases/162.253.55.165; range_end:; response_count:1; response_revision:1110; }","duration":"344.569939ms","start":"2026-09-22T01:52:25.932149Z","end":"2026-09-22T01:52:26.276719Z","steps":["trace[659005365] 'agreement among raft nodes before linearized reading' (duration: 344.441506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:26.276758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:25.932126Z","time spent":"344.625981ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.165\" "} {"level":"warn","ts":"2026-09-22T01:52:43.090311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.197321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:52:43.090404Z","caller":"traceutil/trace.go:171","msg":"trace[878646313] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1180; }","duration":"106.364125ms","start":"2026-09-22T01:52:42.98402Z","end":"2026-09-22T01:52:43.090385Z","steps":["trace[878646313] 'count revisions from in-memory index tree' (duration: 106.104689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:43.09076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.161235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-09-22T01:52:43.090842Z","caller":"traceutil/trace.go:171","msg":"trace[662873744] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1180; }","duration":"110.286928ms","start":"2026-09-22T01:52:42.980535Z","end":"2026-09-22T01:52:43.090822Z","steps":["trace[662873744] 'range keys from in-memory index tree' (duration: 109.965601ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:43.270331Z","caller":"traceutil/trace.go:171","msg":"trace[974369845] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"112.792678ms","start":"2026-09-22T01:52:43.157515Z","end":"2026-09-22T01:52:43.270308Z","steps":["trace[974369845] 'read index received' (duration: 112.47163ms)","trace[974369845] 'applied index is now lower than readState.Index' (duration: 320.108µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:52:43.27045Z","caller":"traceutil/trace.go:171","msg":"trace[921933576] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"119.726342ms","start":"2026-09-22T01:52:43.150709Z","end":"2026-09-22T01:52:43.270436Z","steps":["trace[921933576] 'process raft request' (duration: 119.456835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:43.270887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.358371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:52:43.270951Z","caller":"traceutil/trace.go:171","msg":"trace[1771971411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"115.235136ms","start":"2026-09-22T01:52:43.155702Z","end":"2026-09-22T01:52:43.270937Z","steps":["trace[1771971411] 'agreement among raft nodes before linearized reading' (duration: 115.095282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:43.271324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.582557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-22T01:52:43.271382Z","caller":"traceutil/trace.go:171","msg":"trace[169661018] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1182; }","duration":"113.660998ms","start":"2026-09-22T01:52:43.157708Z","end":"2026-09-22T01:52:43.271369Z","steps":["trace[169661018] 'agreement among raft nodes before linearized reading' (duration: 113.538765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:43.536918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.313127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-22T01:52:43.537248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.107122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cbztt\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-09-22T01:52:43.537334Z","caller":"traceutil/trace.go:171","msg":"trace[545296764] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cbztt; range_end:; response_count:1; response_revision:1183; }","duration":"123.261356ms","start":"2026-09-22T01:52:43.414055Z","end":"2026-09-22T01:52:43.537317Z","steps":["trace[545296764] 'range keys from in-memory index tree' (duration: 122.916458ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:43.537292Z","caller":"traceutil/trace.go:171","msg":"trace[1069290064] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1183; }","duration":"198.730107ms","start":"2026-09-22T01:52:43.338525Z","end":"2026-09-22T01:52:43.537255Z","steps":["trace[1069290064] 'count revisions from in-memory index tree' (duration: 198.247986ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:43.664738Z","caller":"traceutil/trace.go:171","msg":"trace[763881085] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"113.662818ms","start":"2026-09-22T01:52:43.551049Z","end":"2026-09-22T01:52:43.664712Z","steps":["trace[763881085] 'process raft request' (duration: 113.506814ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:57.311698Z","caller":"traceutil/trace.go:171","msg":"trace[736706211] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"402.33748ms","start":"2026-09-22T01:52:56.909327Z","end":"2026-09-22T01:52:57.311665Z","steps":["trace[736706211] 'process raft request' (duration: 331.744684ms)","trace[736706211] 'compare' (duration: 70.35343ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:52:57.311879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:56.909252Z","time spent":"402.531595ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}