{"level":"warn","ts":"2026-01-29T18:43:13.723154Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T18:43:13.723427Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-01-29T18:43:13.723609Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T18:43:13.723677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-01-29T18:43:13.723748Z","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-01-29T18:43:13.724565Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-01-29T18:43:13.724797Z","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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-01-29T18:43:13.729253Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.072866ms"} {"level":"info","ts":"2026-01-29T18:43:13.745178Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-01-29T18:43:13.745408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T18:43:13.745493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-01-29T18:43:13.745568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T18:43:13.745632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-01-29T18:43:13.745745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-01-29T18:43:13.751136Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T18:43:13.752159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T18:43:13.753883Z","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-01-29T18:43:13.756961Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T18:43:13.758985Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T18:43:13.759183Z","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-01-29T18:43:13.761179Z","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-01-29T18:43:13.761256Z","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-01-29T18:43:13.762021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-01-29T18:43:13.762333Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-01-29T18:43:13.766969Z","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-01-29T18:43:13.767374Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T18:43:13.767211Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-01-29T18:43:13.769775Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T18:43:13.769973Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-01-29T18:43:14.046781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T18:43:14.047071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T18:43:14.047172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-01-29T18:43:14.047326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-01-29T18:43:14.047431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-01-29T18:43:14.047578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-01-29T18:43:14.047652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-01-29T18:43:14.05016Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T18:43:14.050247Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:43:14.050537Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:43:14.050751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:43:14.050941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T18:43:14.050998Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T18:43:14.057204Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T18:43:14.060765Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:43:14.060892Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:43:14.060952Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:43:14.07423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"warn","ts":"2026-01-29T18:43:20.050306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.239268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:43:20.050759Z","caller":"traceutil/trace.go:171","msg":"trace[694405546] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"326.759018ms","start":"2026-01-29T18:43:19.723918Z","end":"2026-01-29T18:43:20.050677Z","steps":["trace[694405546] 'process raft request' (duration: 206.317222ms)","trace[694405546] 'compare' (duration: 119.107807ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:20.051171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:19.723906Z","time spent":"326.934458ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2629,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:43:20.064608Z","caller":"traceutil/trace.go:171","msg":"trace[1593646937] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"186.237795ms","start":"2026-01-29T18:43:19.878351Z","end":"2026-01-29T18:43:20.064589Z","steps":["trace[1593646937] 'process raft request' (duration: 186.205624ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:20.06508Z","caller":"traceutil/trace.go:171","msg":"trace[363139737] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"332.344681ms","start":"2026-01-29T18:43:19.732724Z","end":"2026-01-29T18:43:20.065069Z","steps":["trace[363139737] 'process raft request' (duration: 331.608096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.066033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:19.732715Z","time spent":"333.237457ms","remote":"127.0.0.1:43268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:43:20.066348Z","caller":"traceutil/trace.go:171","msg":"trace[367468668] transaction","detail":"{read_only:false; number_of_response:0; response_revision:256; }","duration":"188.648719ms","start":"2026-01-29T18:43:19.877688Z","end":"2026-01-29T18:43:20.066336Z","steps":["trace[367468668] 'process raft request' (duration: 186.726237ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:20.066659Z","caller":"traceutil/trace.go:171","msg":"trace[391714946] transaction","detail":"{read_only:false; number_of_response:0; response_revision:256; }","duration":"188.88376ms","start":"2026-01-29T18:43:19.877764Z","end":"2026-01-29T18:43:20.066648Z","steps":["trace[391714946] 'process raft request' (duration: 186.693287ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:20.067063Z","caller":"traceutil/trace.go:171","msg":"trace[200260282] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"189.147772ms","start":"2026-01-29T18:43:19.877902Z","end":"2026-01-29T18:43:20.06705Z","steps":["trace[200260282] 'process raft request' (duration: 186.574347ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:20.067453Z","caller":"traceutil/trace.go:171","msg":"trace[1185199709] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"189.313614ms","start":"2026-01-29T18:43:19.878125Z","end":"2026-01-29T18:43:20.067439Z","steps":["trace[1185199709] 'process raft request' (duration: 186.387646ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:20.254383Z","caller":"traceutil/trace.go:171","msg":"trace[128330058] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:269; }","duration":"181.163398ms","start":"2026-01-29T18:43:20.073203Z","end":"2026-01-29T18:43:20.254366Z","steps":["trace[128330058] 'read index received' (duration: 178.077918ms)","trace[128330058] 'applied index is now lower than readState.Index' (duration: 3.08484ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:20.254595Z","caller":"traceutil/trace.go:171","msg":"trace[473182431] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"199.804753ms","start":"2026-01-29T18:43:20.054783Z","end":"2026-01-29T18:43:20.254588Z","steps":["trace[473182431] 'process raft request' (duration: 196.541483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.254902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.623211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-01-29T18:43:20.255008Z","caller":"traceutil/trace.go:171","msg":"trace[614976073] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:260; }","duration":"181.711272ms","start":"2026-01-29T18:43:20.073226Z","end":"2026-01-29T18:43:20.254938Z","steps":["trace[614976073] 'agreement among raft nodes before linearized reading' (duration: 181.46679ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.255196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.924971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-29T18:43:20.255312Z","caller":"traceutil/trace.go:171","msg":"trace[1470474427] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:260; }","duration":"180.039332ms","start":"2026-01-29T18:43:20.075261Z","end":"2026-01-29T18:43:20.2553Z","steps":["trace[1470474427] 'agreement among raft nodes before linearized reading' (duration: 179.819371ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.255055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.846502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5870"} {"level":"info","ts":"2026-01-29T18:43:20.256407Z","caller":"traceutil/trace.go:171","msg":"trace[1958656192] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:260; }","duration":"183.214512ms","start":"2026-01-29T18:43:20.073179Z","end":"2026-01-29T18:43:20.256393Z","steps":["trace[1958656192] 'agreement among raft nodes before linearized reading' (duration: 181.730353ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:34.177471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.83835ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-01-29T18:43:47.306901Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674398235120908,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T18:43:47.553101Z","caller":"traceutil/trace.go:171","msg":"trace[1818035046] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"891.779689ms","start":"2026-01-29T18:43:46.661283Z","end":"2026-01-29T18:43:47.553062Z","steps":["trace[1818035046] 'process raft request' (duration: 891.658448ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:47.553461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:46.661263Z","time spent":"891.91949ms","remote":"127.0.0.1:43334","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-01-29T18:43:47.560293Z","caller":"traceutil/trace.go:171","msg":"trace[1399524731] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:487; }","duration":"754.299184ms","start":"2026-01-29T18:43:46.805982Z","end":"2026-01-29T18:43:47.560281Z","steps":["trace[1399524731] 'read index received' (duration: 754.295874ms)","trace[1399524731] 'applied index is now lower than readState.Index' (duration: 2.69µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:47.560747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.775436ms","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-01-29T18:43:47.560833Z","caller":"traceutil/trace.go:171","msg":"trace[403890813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"754.893867ms","start":"2026-01-29T18:43:46.805929Z","end":"2026-01-29T18:43:47.560823Z","steps":["trace[403890813] 'agreement among raft nodes before linearized reading' (duration: 754.746375ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:47.560938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:46.805913Z","time spent":"755.015688ms","remote":"127.0.0.1:43334","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-01-29T18:43:47.565266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.842195ms","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-01-29T18:43:47.565445Z","caller":"traceutil/trace.go:171","msg":"trace[379963524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"723.993015ms","start":"2026-01-29T18:43:46.841316Z","end":"2026-01-29T18:43:47.565309Z","steps":["trace[379963524] 'agreement among raft nodes before linearized reading' (duration: 723.823625ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:47.56548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:46.841264Z","time spent":"724.208727ms","remote":"127.0.0.1:43334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T18:43:47.565645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.289879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T18:43:47.565667Z","caller":"traceutil/trace.go:171","msg":"trace[1703499712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"528.309769ms","start":"2026-01-29T18:43:47.03735Z","end":"2026-01-29T18:43:47.56566Z","steps":["trace[1703499712] 'agreement among raft nodes before linearized reading' (duration: 528.259978ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:47.56568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:47.037266Z","time spent":"528.410601ms","remote":"127.0.0.1:43334","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-01-29T18:43:47.565752Z","caller":"traceutil/trace.go:171","msg":"trace[1734694008] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"211.087728ms","start":"2026-01-29T18:43:47.354537Z","end":"2026-01-29T18:43:47.565625Z","steps":["trace[1734694008] 'process raft request' (duration: 210.507895ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:55.585647Z","caller":"traceutil/trace.go:171","msg":"trace[1340710401] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"853.59054ms","start":"2026-01-29T18:43:54.732038Z","end":"2026-01-29T18:43:55.585628Z","steps":["trace[1340710401] 'process raft request' (duration: 853.452072ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:55.585784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:54.732022Z","time spent":"853.691826ms","remote":"127.0.0.1:43246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:56.111357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674398235121006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:43:56.611477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674398235121006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:43:57.112459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674398235121006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:43:57.249179Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.641812888s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-29T18:43:57.645033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000356535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T18:43:57.645127Z","caller":"traceutil/trace.go:171","msg":"trace[330896972] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000503612s","start":"2026-01-29T18:43:55.644606Z","end":"2026-01-29T18:43:57.64511Z","steps":["trace[330896972] 'agreement among raft nodes before linearized reading' (duration: 2.000375656s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.645192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.644597Z","time spent":"2.000586697s","remote":"127.0.0.1:43104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:43:58.832593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583191884s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:43:58.832662Z","caller":"traceutil/trace.go:171","msg":"trace[454099354] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"3.221806655s","start":"2026-01-29T18:43:55.610844Z","end":"2026-01-29T18:43:58.832651Z","steps":["trace[454099354] 'read index received' (duration: 1.638542106s)","trace[454099354] 'applied index is now lower than readState.Index' (duration: 1.583263789s)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:58.832707Z","caller":"traceutil/trace.go:171","msg":"trace[1421168784] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"3.225394054s","start":"2026-01-29T18:43:55.607306Z","end":"2026-01-29T18:43:58.8327Z","steps":["trace[1421168784] 'process raft request' (duration: 1.6420403s)","trace[1421168784] 'compare' (duration: 1.582509628s)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:58.832742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.607291Z","time spent":"3.225432536s","remote":"127.0.0.1:43334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:58.832854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.221989334s","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-01-29T18:43:58.83292Z","caller":"traceutil/trace.go:171","msg":"trace[1999945599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"3.22208672s","start":"2026-01-29T18:43:55.610817Z","end":"2026-01-29T18:43:58.832903Z","steps":["trace[1999945599] 'agreement among raft nodes before linearized reading' (duration: 3.221923332s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.832959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.610805Z","time spent":"3.222142332s","remote":"127.0.0.1:43334","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-01-29T18:43:58.834727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.186357687s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T18:43:58.83509Z","caller":"traceutil/trace.go:171","msg":"trace[814296341] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.186782029s","start":"2026-01-29T18:43:57.648297Z","end":"2026-01-29T18:43:58.835079Z","steps":["trace[814296341] 'process raft request' (duration: 1.186697075s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.835155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:57.64828Z","time spent":"1.186847293s","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T18:43:58.842813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.192988878s","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-01-29T18:43:58.843451Z","caller":"traceutil/trace.go:171","msg":"trace[1596132008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"3.193639964s","start":"2026-01-29T18:43:55.649788Z","end":"2026-01-29T18:43:58.843428Z","steps":["trace[1596132008] 'agreement among raft nodes before linearized reading' (duration: 3.192473041s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.843513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.649782Z","time spent":"3.193719707s","remote":"127.0.0.1:43334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T18:43:58.844409Z","caller":"traceutil/trace.go:171","msg":"trace[974019369] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.160497763s","start":"2026-01-29T18:43:57.683899Z","end":"2026-01-29T18:43:58.844397Z","steps":["trace[974019369] 'process raft request' (duration: 1.157798899s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.844499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:57.68388Z","time spent":"1.160552806s","remote":"127.0.0.1:43334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:58.844886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.735748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T18:43:58.844909Z","caller":"traceutil/trace.go:171","msg":"trace[1090753890] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:519; }","duration":"964.794041ms","start":"2026-01-29T18:43:57.880109Z","end":"2026-01-29T18:43:58.844903Z","steps":["trace[1090753890] 'agreement among raft nodes before linearized reading' (duration: 964.631423ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.844929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:57.880065Z","time spent":"964.859514ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"warn","ts":"2026-01-29T18:43:58.845249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19523513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:58.845275Z","caller":"traceutil/trace.go:171","msg":"trace[51506459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"1.195277922s","start":"2026-01-29T18:43:57.649988Z","end":"2026-01-29T18:43:58.845265Z","steps":["trace[51506459] 'agreement among raft nodes before linearized reading' (duration: 1.195189026s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.845291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:57.649979Z","time spent":"1.195308584s","remote":"127.0.0.1:43098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:43:58.846092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.19240874s","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-01-29T18:43:58.846119Z","caller":"traceutil/trace.go:171","msg":"trace[656541025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"3.192441311s","start":"2026-01-29T18:43:55.653667Z","end":"2026-01-29T18:43:58.846109Z","steps":["trace[656541025] 'agreement among raft nodes before linearized reading' (duration: 3.192261201s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.846134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.653663Z","time spent":"3.192467853s","remote":"127.0.0.1:43334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-29T18:44:01.146495Z","caller":"traceutil/trace.go:171","msg":"trace[193536286] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"221.707684ms","start":"2026-01-29T18:44:00.924765Z","end":"2026-01-29T18:44:01.146473Z","steps":["trace[193536286] 'process raft request' (duration: 221.548976ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:01.151236Z","caller":"traceutil/trace.go:171","msg":"trace[870956096] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"188.23033ms","start":"2026-01-29T18:44:00.962988Z","end":"2026-01-29T18:44:01.151218Z","steps":["trace[870956096] 'read index received' (duration: 183.332468ms)","trace[870956096] 'applied index is now lower than readState.Index' (duration: 4.897162ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:01.151448Z","caller":"traceutil/trace.go:171","msg":"trace[403662038] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"226.533623ms","start":"2026-01-29T18:44:00.924906Z","end":"2026-01-29T18:44:01.15144Z","steps":["trace[403662038] 'process raft request' (duration: 226.138692ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:01.151615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.61217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:01.151647Z","caller":"traceutil/trace.go:171","msg":"trace[1263794565] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"188.697274ms","start":"2026-01-29T18:44:00.96294Z","end":"2026-01-29T18:44:01.151638Z","steps":["trace[1263794565] 'agreement among raft nodes before linearized reading' (duration: 188.638831ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:01.154505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.077369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:01.15462Z","caller":"traceutil/trace.go:171","msg":"trace[1188209984] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:545; }","duration":"158.219106ms","start":"2026-01-29T18:44:00.996386Z","end":"2026-01-29T18:44:01.154605Z","steps":["trace[1188209984] 'agreement among raft nodes before linearized reading' (duration: 158.041738ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:01.155049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.997354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:01.155261Z","caller":"traceutil/trace.go:171","msg":"trace[1809579356] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:545; }","duration":"192.224816ms","start":"2026-01-29T18:44:00.963025Z","end":"2026-01-29T18:44:01.15525Z","steps":["trace[1809579356] 'agreement among raft nodes before linearized reading' (duration: 191.363872ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:01.155366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.665835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-29T18:44:01.15629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.815186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:01.156382Z","caller":"traceutil/trace.go:171","msg":"trace[424199461] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:545; }","duration":"163.920573ms","start":"2026-01-29T18:44:00.992451Z","end":"2026-01-29T18:44:01.156371Z","steps":["trace[424199461] 'agreement among raft nodes before linearized reading' (duration: 162.90889ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:01.156229Z","caller":"traceutil/trace.go:171","msg":"trace[1530564162] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:545; }","duration":"145.568853ms","start":"2026-01-29T18:44:01.010648Z","end":"2026-01-29T18:44:01.156216Z","steps":["trace[1530564162] 'agreement among raft nodes before linearized reading' (duration: 144.612884ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:01.158885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.702909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:01.158937Z","caller":"traceutil/trace.go:171","msg":"trace[2102310444] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:545; }","duration":"168.792573ms","start":"2026-01-29T18:44:00.990134Z","end":"2026-01-29T18:44:01.158927Z","steps":["trace[2102310444] 'agreement among raft nodes before linearized reading' (duration: 168.707829ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:01.159234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.402548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:01.159257Z","caller":"traceutil/trace.go:171","msg":"trace[696280430] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:545; }","duration":"175.485382ms","start":"2026-01-29T18:44:00.983766Z","end":"2026-01-29T18:44:01.159251Z","steps":["trace[696280430] 'agreement among raft nodes before linearized reading' (duration: 175.420088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:01.159335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.991845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:01.159414Z","caller":"traceutil/trace.go:171","msg":"trace[762623037] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:545; }","duration":"185.030678ms","start":"2026-01-29T18:44:00.974315Z","end":"2026-01-29T18:44:01.159346Z","steps":["trace[762623037] 'agreement among raft nodes before linearized reading' (duration: 185.004897ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:12.043232Z","caller":"traceutil/trace.go:171","msg":"trace[1300811221] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"101.794953ms","start":"2026-01-29T18:44:11.941391Z","end":"2026-01-29T18:44:12.043186Z","steps":["trace[1300811221] 'process raft request' (duration: 39.030078ms)","trace[1300811221] 'compare' (duration: 62.337096ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:12.053961Z","caller":"traceutil/trace.go:171","msg":"trace[2057276484] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"107.652034ms","start":"2026-01-29T18:44:11.94629Z","end":"2026-01-29T18:44:12.053943Z","steps":["trace[2057276484] 'process raft request' (duration: 107.611622ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:12.054166Z","caller":"traceutil/trace.go:171","msg":"trace[668319454] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"110.143257ms","start":"2026-01-29T18:44:11.944012Z","end":"2026-01-29T18:44:12.054155Z","steps":["trace[668319454] 'process raft request' (duration: 109.825263ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:12.222876Z","caller":"traceutil/trace.go:171","msg":"trace[1710299070] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"153.11164ms","start":"2026-01-29T18:44:12.06972Z","end":"2026-01-29T18:44:12.222831Z","steps":["trace[1710299070] 'process raft request' (duration: 96.414638ms)","trace[1710299070] 'compare' (duration: 55.615494ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:12.298766Z","caller":"traceutil/trace.go:171","msg":"trace[1164798269] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"148.639689ms","start":"2026-01-29T18:44:12.150104Z","end":"2026-01-29T18:44:12.298744Z","steps":["trace[1164798269] 'process raft request' (duration: 148.571076ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:12.300817Z","caller":"traceutil/trace.go:171","msg":"trace[852735159] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"152.487743ms","start":"2026-01-29T18:44:12.148206Z","end":"2026-01-29T18:44:12.300694Z","steps":["trace[852735159] 'process raft request' (duration: 150.244471ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:12.301071Z","caller":"traceutil/trace.go:171","msg":"trace[1084012993] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"152.829937ms","start":"2026-01-29T18:44:12.148217Z","end":"2026-01-29T18:44:12.301047Z","steps":["trace[1084012993] 'process raft request' (duration: 150.387207ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:12.329035Z","caller":"traceutil/trace.go:171","msg":"trace[387181650] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"162.932412ms","start":"2026-01-29T18:44:12.166089Z","end":"2026-01-29T18:44:12.329022Z","steps":["trace[387181650] 'process raft request' (duration: 157.969659ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:13.109972Z","caller":"traceutil/trace.go:171","msg":"trace[1900410706] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"177.211859ms","start":"2026-01-29T18:44:12.932745Z","end":"2026-01-29T18:44:13.109956Z","steps":["trace[1900410706] 'process raft request' (duration: 176.956098ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:58.004373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.157193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T18:44:58.004455Z","caller":"traceutil/trace.go:171","msg":"trace[1779086064] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:834; }","duration":"120.301637ms","start":"2026-01-29T18:44:57.884137Z","end":"2026-01-29T18:44:58.004439Z","steps":["trace[1779086064] 'range keys from in-memory index tree' (duration: 119.989337ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:59.552761Z","caller":"traceutil/trace.go:171","msg":"trace[281293688] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:868; }","duration":"169.812947ms","start":"2026-01-29T18:44:59.382932Z","end":"2026-01-29T18:44:59.552745Z","steps":["trace[281293688] 'read index received' (duration: 167.570806ms)","trace[281293688] 'applied index is now lower than readState.Index' (duration: 2.241631ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:59.552903Z","caller":"traceutil/trace.go:171","msg":"trace[1659482545] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"199.137587ms","start":"2026-01-29T18:44:59.35376Z","end":"2026-01-29T18:44:59.552897Z","steps":["trace[1659482545] 'process raft request' (duration: 196.735751ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:59.553226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.214507ms","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-01-29T18:44:59.553289Z","caller":"traceutil/trace.go:171","msg":"trace[1705550176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:837; }","duration":"170.371343ms","start":"2026-01-29T18:44:59.382902Z","end":"2026-01-29T18:44:59.553274Z","steps":["trace[1705550176] 'agreement among raft nodes before linearized reading' (duration: 170.131345ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:59.557216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.703402ms","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-01-29T18:44:59.557269Z","caller":"traceutil/trace.go:171","msg":"trace[646417744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:838; }","duration":"162.795195ms","start":"2026-01-29T18:44:59.394462Z","end":"2026-01-29T18:44:59.557258Z","steps":["trace[646417744] 'agreement among raft nodes before linearized reading' (duration: 162.60272ms)"],"step_count":1}