{"level":"warn","ts":"2026-10-16T00:03:15.302659Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:03:15.302733Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.159: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.159:2380","--initial-cluster=instance=https://162.253.55.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.159: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-10-16T00:03:15.302789Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:03:15.3028Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-10-16T00:03:15.302816Z","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-10-16T00:03:15.303626Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"]} {"level":"info","ts":"2026-10-16T00:03:15.304402Z","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.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.159: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-10-16T00:03:15.312813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.156149ms"} {"level":"info","ts":"2026-10-16T00:03:15.326631Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"94bfbb0e034a3ac2","cluster-id":"9676abcf1742949"} {"level":"info","ts":"2026-10-16T00:03:15.326708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:03:15.32673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 0"} {"level":"info","ts":"2026-10-16T00:03:15.326739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 94bfbb0e034a3ac2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:03:15.326746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 1"} {"level":"info","ts":"2026-10-16T00:03:15.32679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"warn","ts":"2026-10-16T00:03:15.334418Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:03:15.335865Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:03:15.33852Z","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-10-16T00:03:15.341221Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"94bfbb0e034a3ac2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:03:15.34313Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"94bfbb0e034a3ac2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:03:15.345033Z","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-10-16T00:03:15.345142Z","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-10-16T00:03:15.345156Z","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-10-16T00:03:15.345469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"info","ts":"2026-10-16T00:03:15.346217Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","added-peer-id":"94bfbb0e034a3ac2","added-peer-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-10-16T00:03:15.347251Z","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-10-16T00:03:15.347299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-10-16T00:03:15.347353Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-10-16T00:03:15.347631Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"94bfbb0e034a3ac2","initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:03:15.347673Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:03:15.727365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:03:15.727427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:03:15.727452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgPreVoteResp from 94bfbb0e034a3ac2 at term 1"} {"level":"info","ts":"2026-10-16T00:03:15.727474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:03:15.727489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgVoteResp from 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-10-16T00:03:15.727507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became leader at term 2"} {"level":"info","ts":"2026-10-16T00:03:15.727525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 94bfbb0e034a3ac2 elected leader 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-10-16T00:03:15.730159Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"94bfbb0e034a3ac2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.159:2379]}","request-path":"/0/members/94bfbb0e034a3ac2/attributes","cluster-id":"9676abcf1742949","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:03:15.730339Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:03:15.730402Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:03:15.730791Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:03:15.730813Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:03:15.731434Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:03:15.733469Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.159:2379"} {"level":"info","ts":"2026-10-16T00:03:15.733825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:03:15.736257Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:03:15.736585Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:03:15.736623Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:03:21.999066Z","caller":"traceutil/trace.go:171","msg":"trace[841971706] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:283; }","duration":"148.531583ms","start":"2026-10-16T00:03:21.850511Z","end":"2026-10-16T00:03:21.999042Z","steps":["trace[841971706] 'read index received' (duration: 148.332714ms)","trace[841971706] 'applied index is now lower than readState.Index' (duration: 197.679µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:03:21.999213Z","caller":"traceutil/trace.go:171","msg":"trace[2137208133] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"209.076024ms","start":"2026-10-16T00:03:21.790061Z","end":"2026-10-16T00:03:21.999137Z","steps":["trace[2137208133] 'process raft request' (duration: 208.843063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:03:21.999307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.743483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:03:21.999393Z","caller":"traceutil/trace.go:171","msg":"trace[1127664818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:276; }","duration":"148.883639ms","start":"2026-10-16T00:03:21.850496Z","end":"2026-10-16T00:03:21.999379Z","steps":["trace[1127664818] 'agreement among raft nodes before linearized reading' (duration: 148.67959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:03:22.332028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.142381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:03:22.332238Z","caller":"traceutil/trace.go:171","msg":"trace[988392561] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"163.634551ms","start":"2026-10-16T00:03:22.168584Z","end":"2026-10-16T00:03:22.332219Z","steps":["trace[988392561] 'read index received' (duration: 42.807867ms)","trace[988392561] 'applied index is now lower than readState.Index' (duration: 120.824843ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:03:22.332329Z","caller":"traceutil/trace.go:171","msg":"trace[162889101] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"320.832511ms","start":"2026-10-16T00:03:22.011437Z","end":"2026-10-16T00:03:22.33227Z","steps":["trace[162889101] 'process raft request' (duration: 199.935054ms)","trace[162889101] 'compare' (duration: 119.980524ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:03:22.332435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.813469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-10-16T00:03:22.332476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:03:22.011413Z","time spent":"320.998878ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:03:22.332496Z","caller":"traceutil/trace.go:171","msg":"trace[166395850] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:277; }","duration":"163.963735ms","start":"2026-10-16T00:03:22.168513Z","end":"2026-10-16T00:03:22.332476Z","steps":["trace[166395850] 'agreement among raft nodes before linearized reading' (duration: 163.793578ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:03:22.436658Z","caller":"traceutil/trace.go:171","msg":"trace[1740738215] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:285; }","duration":"100.119339ms","start":"2026-10-16T00:03:22.336514Z","end":"2026-10-16T00:03:22.436633Z","steps":["trace[1740738215] 'read index received' (duration: 99.784463ms)","trace[1740738215] 'applied index is now lower than readState.Index' (duration: 333.336µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:03:22.436789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.241994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:03:22.436746Z","caller":"traceutil/trace.go:171","msg":"trace[1247398307] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"266.566389ms","start":"2026-10-16T00:03:22.170118Z","end":"2026-10-16T00:03:22.436685Z","steps":["trace[1247398307] 'process raft request' (duration: 266.444003ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:03:22.436849Z","caller":"traceutil/trace.go:171","msg":"trace[1104802649] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:277; }","duration":"100.356209ms","start":"2026-10-16T00:03:22.336478Z","end":"2026-10-16T00:03:22.436835Z","steps":["trace[1104802649] 'agreement among raft nodes before linearized reading' (duration: 100.248514ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:03:22.436886Z","caller":"traceutil/trace.go:171","msg":"trace[56654558] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"267.056862ms","start":"2026-10-16T00:03:22.169647Z","end":"2026-10-16T00:03:22.436704Z","steps":["trace[56654558] 'process raft request' (duration: 266.619602ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:03:22.436982Z","caller":"traceutil/trace.go:171","msg":"trace[1852271334] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"266.208223ms","start":"2026-10-16T00:03:22.170752Z","end":"2026-10-16T00:03:22.43696Z","steps":["trace[1852271334] 'process raft request' (duration: 265.834046ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:03:22.437001Z","caller":"traceutil/trace.go:171","msg":"trace[1922057629] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"264.644709ms","start":"2026-10-16T00:03:22.172346Z","end":"2026-10-16T00:03:22.43699Z","steps":["trace[1922057629] 'process raft request' (duration: 264.259551ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:04:01.966858Z","caller":"traceutil/trace.go:171","msg":"trace[1910906775] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"104.252119ms","start":"2026-10-16T00:04:01.862567Z","end":"2026-10-16T00:04:01.966819Z","steps":["trace[1910906775] 'process raft request' (duration: 104.115752ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:04:02.803613Z","caller":"traceutil/trace.go:171","msg":"trace[1145077979] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"113.296005ms","start":"2026-10-16T00:04:02.690293Z","end":"2026-10-16T00:04:02.803589Z","steps":["trace[1145077979] 'process raft request' (duration: 105.434216ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:04:02.803798Z","caller":"traceutil/trace.go:171","msg":"trace[877266375] transaction","detail":"{read_only:false; number_of_response:1; response_revision:533; }","duration":"109.317598ms","start":"2026-10-16T00:04:02.69447Z","end":"2026-10-16T00:04:02.803788Z","steps":["trace[877266375] 'process raft request' (duration: 108.316472ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:04:02.803888Z","caller":"traceutil/trace.go:171","msg":"trace[372044768] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"104.27156ms","start":"2026-10-16T00:04:02.699606Z","end":"2026-10-16T00:04:02.803878Z","steps":["trace[372044768] 'read index received' (duration: 96.201059ms)","trace[372044768] 'applied index is now lower than readState.Index' (duration: 8.069631ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:04:02.804011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.383576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-10-16T00:04:02.804046Z","caller":"traceutil/trace.go:171","msg":"trace[888225053] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:537; }","duration":"104.44985ms","start":"2026-10-16T00:04:02.699585Z","end":"2026-10-16T00:04:02.804035Z","steps":["trace[888225053] 'agreement among raft nodes before linearized reading' (duration: 104.326763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:04:09.14306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.985304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.159\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-16T00:04:09.143152Z","caller":"traceutil/trace.go:171","msg":"trace[869189499] range","detail":"{range_begin:/registry/masterleases/162.253.55.159; range_end:; response_count:1; response_revision:616; }","duration":"147.140573ms","start":"2026-10-16T00:04:08.99599Z","end":"2026-10-16T00:04:09.14313Z","steps":["trace[869189499] 'range keys from in-memory index tree' (duration: 146.868387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:04:09.143175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.397193ms","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-10-16T00:04:09.143297Z","caller":"traceutil/trace.go:171","msg":"trace[587606750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"113.555342ms","start":"2026-10-16T00:04:09.029692Z","end":"2026-10-16T00:04:09.143247Z","steps":["trace[587606750] 'range keys from in-memory index tree' (duration: 113.079204ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:04:09.386285Z","caller":"traceutil/trace.go:171","msg":"trace[621809817] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"230.355894ms","start":"2026-10-16T00:04:09.155901Z","end":"2026-10-16T00:04:09.386257Z","steps":["trace[621809817] 'process raft request' (duration: 152.03405ms)","trace[621809817] 'compare' (duration: 78.112012ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:04:09.414103Z","caller":"traceutil/trace.go:171","msg":"trace[755324771] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"125.68397ms","start":"2026-10-16T00:04:09.288391Z","end":"2026-10-16T00:04:09.414075Z","steps":["trace[755324771] 'read index received' (duration: 19.644337ms)","trace[755324771] 'applied index is now lower than readState.Index' (duration: 106.038213ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:04:09.414218Z","caller":"traceutil/trace.go:171","msg":"trace[1210847265] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"180.587578ms","start":"2026-10-16T00:04:09.23357Z","end":"2026-10-16T00:04:09.414158Z","steps":["trace[1210847265] 'process raft request' (duration: 179.93149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:04:09.414422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.01125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p2kdq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-16T00:04:09.414492Z","caller":"traceutil/trace.go:171","msg":"trace[1843066952] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p2kdq; range_end:; response_count:1; response_revision:619; }","duration":"126.200941ms","start":"2026-10-16T00:04:09.288277Z","end":"2026-10-16T00:04:09.414478Z","steps":["trace[1843066952] 'agreement among raft nodes before linearized reading' (duration: 125.929615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:04:09.777601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.479789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:04:09.777725Z","caller":"traceutil/trace.go:171","msg":"trace[524392948] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"341.911031ms","start":"2026-10-16T00:04:09.435793Z","end":"2026-10-16T00:04:09.777705Z","steps":["trace[524392948] 'process raft request' (duration: 83.110274ms)","trace[524392948] 'compare' (duration: 258.387623ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:04:09.777806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:04:09.435765Z","time spent":"341.992936ms","remote":"127.0.0.1:51420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:04:09.904004Z","caller":"traceutil/trace.go:171","msg":"trace[1880438595] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"404.945763ms","start":"2026-10-16T00:04:09.498965Z","end":"2026-10-16T00:04:09.903911Z","steps":["trace[1880438595] 'process raft request' (duration: 394.303851ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:04:09.904092Z","caller":"traceutil/trace.go:171","msg":"trace[1739117099] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"358.290808ms","start":"2026-10-16T00:04:09.545528Z","end":"2026-10-16T00:04:09.903819Z","steps":["trace[1739117099] 'read index received' (duration: 347.666647ms)","trace[1739117099] 'applied index is now lower than readState.Index' (duration: 10.623281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:04:09.904219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:04:09.498938Z","time spent":"405.183496ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T00:04:09.904781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.254113ms","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-10-16T00:04:09.904846Z","caller":"traceutil/trace.go:171","msg":"trace[128019247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"359.350918ms","start":"2026-10-16T00:04:09.545479Z","end":"2026-10-16T00:04:09.90483Z","steps":["trace[128019247] 'agreement among raft nodes before linearized reading' (duration: 358.706531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:04:09.904941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:04:09.545468Z","time spent":"359.459565ms","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-16T00:04:09.905301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.102885ms","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-10-16T00:04:09.905358Z","caller":"traceutil/trace.go:171","msg":"trace[681366825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"208.19617ms","start":"2026-10-16T00:04:09.697147Z","end":"2026-10-16T00:04:09.905343Z","steps":["trace[681366825] 'agreement among raft nodes before linearized reading' (duration: 208.046671ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:26.796017Z","caller":"traceutil/trace.go:171","msg":"trace[628263244] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"126.037499ms","start":"2026-10-16T00:06:26.669959Z","end":"2026-10-16T00:06:26.795997Z","steps":["trace[628263244] 'read index received' (duration: 125.847352ms)","trace[628263244] 'applied index is now lower than readState.Index' (duration: 188.897µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:06:26.796077Z","caller":"traceutil/trace.go:171","msg":"trace[1830449872] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"128.867764ms","start":"2026-10-16T00:06:26.667197Z","end":"2026-10-16T00:06:26.796065Z","steps":["trace[1830449872] 'process raft request' (duration: 128.687707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:26.796186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.212826ms","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-10-16T00:06:26.796214Z","caller":"traceutil/trace.go:171","msg":"trace[1950418991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1350; }","duration":"126.263076ms","start":"2026-10-16T00:06:26.669943Z","end":"2026-10-16T00:06:26.796206Z","steps":["trace[1950418991] 'agreement among raft nodes before linearized reading' (duration: 126.162367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:26.796426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.492835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-16T00:06:26.79648Z","caller":"traceutil/trace.go:171","msg":"trace[619607419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"117.578214ms","start":"2026-10-16T00:06:26.678888Z","end":"2026-10-16T00:06:26.796466Z","steps":["trace[619607419] 'agreement among raft nodes before linearized reading' (duration: 117.327918ms)"],"step_count":1}