{"level":"warn","ts":"2026-03-04T03:53:54.782642Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T03:53:54.782746Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206: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-03-04T03:53:54.782811Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T03:53:54.78282Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-03-04T03:53:54.782837Z","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-03-04T03:53:54.783444Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-03-04T03:53:54.783518Z","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.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206: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-03-04T03:53:54.788055Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.293359ms"} {"level":"info","ts":"2026-03-04T03:53:54.798076Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-03-04T03:53:54.798147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T03:53:54.798166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-03-04T03:53:54.798176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T03:53:54.798192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-03-04T03:53:54.79828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-03-04T03:53:54.804423Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T03:53:54.805803Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T03:53:54.806811Z","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-03-04T03:53:54.81121Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T03:53:54.811733Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T03:53:54.811884Z","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-03-04T03:53:54.812044Z","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-03-04T03:53:54.81206Z","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-03-04T03:53:54.815617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-03-04T03:53:54.815809Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-03-04T03:53:54.818429Z","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-03-04T03:53:54.818597Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-03-04T03:53:54.818636Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-03-04T03:53:54.819008Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T03:53:54.819049Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T03:53:55.398941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T03:53:55.39916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T03:53:55.399174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-03-04T03:53:55.399186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-03-04T03:53:55.399204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-03-04T03:53:55.399214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-03-04T03:53:55.399222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-03-04T03:53:55.407838Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T03:53:55.407799Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T03:53:55.40805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T03:53:55.408203Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T03:53:55.408886Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T03:53:55.408947Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T03:53:55.412019Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T03:53:55.412185Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T03:53:55.41223Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T03:53:55.412398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T03:53:55.422159Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-03-04T03:53:57.048977Z","caller":"traceutil/trace.go:171","msg":"trace[1816555590] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"370.069848ms","start":"2026-03-04T03:53:56.678885Z","end":"2026-03-04T03:53:57.048954Z","steps":["trace[1816555590] 'read index received' (duration: 370.015393ms)","trace[1816555590] 'applied index is now lower than readState.Index' (duration: 53.735µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T03:53:57.049178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.239543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:57.04908Z","caller":"traceutil/trace.go:171","msg":"trace[1449818249] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"370.339652ms","start":"2026-03-04T03:53:56.678694Z","end":"2026-03-04T03:53:57.049034Z","steps":["trace[1449818249] 'process raft request' (duration: 370.095121ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:53:57.049253Z","caller":"traceutil/trace.go:171","msg":"trace[1010387236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2; }","duration":"370.393716ms","start":"2026-03-04T03:53:56.678848Z","end":"2026-03-04T03:53:57.049241Z","steps":["trace[1010387236] 'agreement among raft nodes before linearized reading' (duration: 370.191449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.049285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.678844Z","time spent":"370.43361ms","remote":"127.0.0.1:36464","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-03-04T03:53:57.049958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.678684Z","time spent":"370.755496ms","remote":"127.0.0.1:36524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T03:53:57.389904Z","caller":"traceutil/trace.go:171","msg":"trace[344611299] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"340.842746ms","start":"2026-03-04T03:53:57.049038Z","end":"2026-03-04T03:53:57.389881Z","steps":["trace[344611299] 'read index received' (duration: 340.408328ms)","trace[344611299] 'applied index is now lower than readState.Index' (duration: 433.578µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T03:53:57.390061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.547089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:57.390176Z","caller":"traceutil/trace.go:171","msg":"trace[1073515222] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"708.703493ms","start":"2026-03-04T03:53:56.68146Z","end":"2026-03-04T03:53:57.390163Z","steps":["trace[1073515222] 'agreement among raft nodes before linearized reading' (duration: 708.511576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.390214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.681436Z","time spent":"708.767168ms","remote":"127.0.0.1:36514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-03-04T03:53:57.390196Z","caller":"traceutil/trace.go:171","msg":"trace[1358815732] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"620.108615ms","start":"2026-03-04T03:53:56.770024Z","end":"2026-03-04T03:53:57.390132Z","steps":["trace[1358815732] 'process raft request' (duration: 619.795768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.390325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.769983Z","time spent":"620.280819ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T03:53:57.390723Z","caller":"traceutil/trace.go:171","msg":"trace[204997791] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"655.642846ms","start":"2026-03-04T03:53:56.735068Z","end":"2026-03-04T03:53:57.390711Z","steps":["trace[204997791] 'process raft request' (duration: 654.452233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.390779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.735026Z","time spent":"655.722673ms","remote":"127.0.0.1:36952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T03:53:57.390832Z","caller":"traceutil/trace.go:171","msg":"trace[83020187] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"615.829545ms","start":"2026-03-04T03:53:56.774988Z","end":"2026-03-04T03:53:57.390818Z","steps":["trace[83020187] 'process raft request' (duration: 614.862061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.390953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.774979Z","time spent":"615.919112ms","remote":"127.0.0.1:36752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T03:53:57.391089Z","caller":"traceutil/trace.go:171","msg":"trace[479091310] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"654.201071ms","start":"2026-03-04T03:53:56.736806Z","end":"2026-03-04T03:53:57.391007Z","steps":["trace[479091310] 'process raft request' (duration: 652.979146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.39115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.895237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-04T03:53:57.39125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.736794Z","time spent":"654.389867ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T03:53:57.391185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.254004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:57.39136Z","caller":"traceutil/trace.go:171","msg":"trace[511945991] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"656.061872ms","start":"2026-03-04T03:53:56.735198Z","end":"2026-03-04T03:53:57.39126Z","steps":["trace[511945991] 'agreement among raft nodes before linearized reading' (duration: 655.871635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.391434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.915929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-04T03:53:57.391469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.827025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:57.391485Z","caller":"traceutil/trace.go:171","msg":"trace[1318977581] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"700.989486ms","start":"2026-03-04T03:53:56.690485Z","end":"2026-03-04T03:53:57.391474Z","steps":["trace[1318977581] 'agreement among raft nodes before linearized reading' (duration: 700.910279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.391518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.690475Z","time spent":"701.03614ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-03-04T03:53:57.39153Z","caller":"traceutil/trace.go:171","msg":"trace[1581243645] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"657.903411ms","start":"2026-03-04T03:53:56.733615Z","end":"2026-03-04T03:53:57.391518Z","steps":["trace[1581243645] 'agreement among raft nodes before linearized reading' (duration: 657.822064ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:53:57.391565Z","caller":"traceutil/trace.go:171","msg":"trace[1101205129] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"654.945006ms","start":"2026-03-04T03:53:56.736612Z","end":"2026-03-04T03:53:57.391557Z","steps":["trace[1101205129] 'process raft request' (duration: 653.066993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.391571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.733607Z","time spent":"657.954546ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-04T03:53:57.391631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.736603Z","time spent":"654.997291ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T03:53:57.391705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.410185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:57.391769Z","caller":"traceutil/trace.go:171","msg":"trace[1151534131] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"655.149203ms","start":"2026-03-04T03:53:56.73661Z","end":"2026-03-04T03:53:57.391759Z","steps":["trace[1151534131] 'process raft request' (duration: 653.132059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.391765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.232458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:57.39182Z","caller":"traceutil/trace.go:171","msg":"trace[2102895422] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"667.323585ms","start":"2026-03-04T03:53:56.724479Z","end":"2026-03-04T03:53:57.391803Z","steps":["trace[2102895422] 'agreement among raft nodes before linearized reading' (duration: 667.234227ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:53:57.391865Z","caller":"traceutil/trace.go:171","msg":"trace[1719621857] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"655.118201ms","start":"2026-03-04T03:53:56.73674Z","end":"2026-03-04T03:53:57.391858Z","steps":["trace[1719621857] 'process raft request' (duration: 653.02261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.391908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.736733Z","time spent":"655.158975ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T03:53:57.391526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.735189Z","time spent":"656.26243ms","remote":"127.0.0.1:36538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-03-04T03:53:57.391365Z","caller":"traceutil/trace.go:171","msg":"trace[2141603909] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"660.500016ms","start":"2026-03-04T03:53:56.73084Z","end":"2026-03-04T03:53:57.39134Z","steps":["trace[2141603909] 'agreement among raft nodes before linearized reading' (duration: 660.208761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.392218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.730829Z","time spent":"661.378572ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-04T03:53:57.391531Z","caller":"traceutil/trace.go:171","msg":"trace[839408668] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"655.092979ms","start":"2026-03-04T03:53:56.736427Z","end":"2026-03-04T03:53:57.39152Z","steps":["trace[839408668] 'process raft request' (duration: 653.220357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.392706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.736413Z","time spent":"656.269651ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T03:53:57.391833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.736587Z","time spent":"655.212959ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T03:53:57.391872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.724458Z","time spent":"667.406073ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-04T03:53:57.391792Z","caller":"traceutil/trace.go:171","msg":"trace[1810044069] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"605.495562ms","start":"2026-03-04T03:53:56.786282Z","end":"2026-03-04T03:53:57.391778Z","steps":["trace[1810044069] 'agreement among raft nodes before linearized reading' (duration: 605.35638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.396136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.526501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:57.396186Z","caller":"traceutil/trace.go:171","msg":"trace[1837487936] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"671.613808ms","start":"2026-03-04T03:53:56.724559Z","end":"2026-03-04T03:53:57.396173Z","steps":["trace[1837487936] 'agreement among raft nodes before linearized reading' (duration: 671.534251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.39622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.724539Z","time spent":"671.674084ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-04T03:53:57.396234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.786266Z","time spent":"609.880502ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-04T03:53:57.395723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.133935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:57.396494Z","caller":"traceutil/trace.go:171","msg":"trace[1668741243] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:0; response_revision:10; }","duration":"663.830185ms","start":"2026-03-04T03:53:56.732548Z","end":"2026-03-04T03:53:57.396379Z","steps":["trace[1668741243] 'agreement among raft nodes before linearized reading' (duration: 659.340296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:57.396679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:56.732541Z","time spent":"664.102777ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.206\" "} {"level":"warn","ts":"2026-03-04T03:53:58.144513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.443752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-04T03:53:58.144595Z","caller":"traceutil/trace.go:171","msg":"trace[1344742772] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:13; }","duration":"748.550378ms","start":"2026-03-04T03:53:57.396032Z","end":"2026-03-04T03:53:58.144582Z","steps":["trace[1344742772] 'read index received' (duration: 305.894557ms)","trace[1344742772] 'applied index is now lower than readState.Index' (duration: 442.654971ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T03:53:58.14465Z","caller":"traceutil/trace.go:171","msg":"trace[265043658] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"750.450883ms","start":"2026-03-04T03:53:57.394189Z","end":"2026-03-04T03:53:58.14464Z","steps":["trace[265043658] 'process raft request' (duration: 307.701544ms)","trace[265043658] 'compare' (duration: 442.329393ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T03:53:58.144691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.394173Z","time spent":"750.497378ms","remote":"127.0.0.1:36514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T03:53:58.144884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.823192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-03-04T03:53:58.145107Z","caller":"traceutil/trace.go:171","msg":"trace[2064867396] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"749.080115ms","start":"2026-03-04T03:53:57.396001Z","end":"2026-03-04T03:53:58.145081Z","steps":["trace[2064867396] 'agreement among raft nodes before linearized reading' (duration: 748.748626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.145309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.395985Z","time spent":"749.238078ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3195,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-04T03:53:58.145999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.78304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-l5nd5\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-03-04T03:53:58.145899Z","caller":"traceutil/trace.go:171","msg":"trace[1915699585] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"749.826559ms","start":"2026-03-04T03:53:57.396042Z","end":"2026-03-04T03:53:58.145868Z","steps":["trace[1915699585] 'process raft request' (duration: 749.403773ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:53:58.146053Z","caller":"traceutil/trace.go:171","msg":"trace[800103500] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-l5nd5; range_end:; response_count:1; response_revision:19; }","duration":"748.862376ms","start":"2026-03-04T03:53:57.397178Z","end":"2026-03-04T03:53:58.146041Z","steps":["trace[800103500] 'agreement among raft nodes before linearized reading' (duration: 748.760118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.146127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.397165Z","time spent":"748.946934ms","remote":"127.0.0.1:36752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":881,"request content":"key:\"/registry/certificatesigningrequests/csr-l5nd5\" "} {"level":"warn","ts":"2026-03-04T03:53:58.146118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.396012Z","time spent":"750.044779ms","remote":"127.0.0.1:36952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T03:53:58.146222Z","caller":"traceutil/trace.go:171","msg":"trace[1485493183] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"745.89849ms","start":"2026-03-04T03:53:57.400275Z","end":"2026-03-04T03:53:58.146173Z","steps":["trace[1485493183] 'process raft request' (duration: 745.569971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.146407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.40027Z","time spent":"746.083927ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T03:53:58.146654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.280153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:58.146705Z","caller":"traceutil/trace.go:171","msg":"trace[42831140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"281.391192ms","start":"2026-03-04T03:53:57.865303Z","end":"2026-03-04T03:53:58.146694Z","steps":["trace[42831140] 'agreement among raft nodes before linearized reading' (duration: 281.318316ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:53:58.146761Z","caller":"traceutil/trace.go:171","msg":"trace[749777026] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"750.51146ms","start":"2026-03-04T03:53:57.39614Z","end":"2026-03-04T03:53:58.146651Z","steps":["trace[749777026] 'process raft request' (duration: 749.37173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.146899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.3961Z","time spent":"750.747359ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T03:53:58.147092Z","caller":"traceutil/trace.go:171","msg":"trace[1995759246] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"747.878811ms","start":"2026-03-04T03:53:57.399197Z","end":"2026-03-04T03:53:58.147076Z","steps":["trace[1995759246] 'process raft request' (duration: 746.531234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.147207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.39919Z","time spent":"747.970268ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T03:53:58.14739Z","caller":"traceutil/trace.go:171","msg":"trace[2088809223] transaction","detail":"{read_only:false; number_of_response:0; response_revision:16; }","duration":"747.97605ms","start":"2026-03-04T03:53:57.399404Z","end":"2026-03-04T03:53:58.14738Z","steps":["trace[2088809223] 'process raft request' (duration: 746.378062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.147563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.895272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-04T03:53:58.147633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.399393Z","time spent":"748.197159ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T03:53:58.147678Z","caller":"traceutil/trace.go:171","msg":"trace[2127960184] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"689.10194ms","start":"2026-03-04T03:53:57.458556Z","end":"2026-03-04T03:53:58.147658Z","steps":["trace[2127960184] 'agreement among raft nodes before linearized reading' (duration: 688.453494ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:53:58.147436Z","caller":"traceutil/trace.go:171","msg":"trace[83909675] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"748.977106ms","start":"2026-03-04T03:53:57.398445Z","end":"2026-03-04T03:53:58.147422Z","steps":["trace[83909675] 'process raft request' (duration: 747.096073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.147901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.398432Z","time spent":"749.428095ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T03:53:58.146623Z","caller":"traceutil/trace.go:171","msg":"trace[1526741915] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"747.963369ms","start":"2026-03-04T03:53:57.398612Z","end":"2026-03-04T03:53:58.146576Z","steps":["trace[1526741915] 'process raft request' (duration: 746.954621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.148068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.458527Z","time spent":"689.317749ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-04T03:53:58.148155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.398601Z","time spent":"749.498842ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T03:53:58.147443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.936833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:58.148329Z","caller":"traceutil/trace.go:171","msg":"trace[355016626] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:19; }","duration":"515.83176ms","start":"2026-03-04T03:53:57.632469Z","end":"2026-03-04T03:53:58.148301Z","steps":["trace[355016626] 'agreement among raft nodes before linearized reading' (duration: 514.939773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.148387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.632457Z","time spent":"515.913407ms","remote":"127.0.0.1:36836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-03-04T03:53:58.147483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.550042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:58.148564Z","caller":"traceutil/trace.go:171","msg":"trace[595737211] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:19; }","duration":"514.654728ms","start":"2026-03-04T03:53:57.633899Z","end":"2026-03-04T03:53:58.148553Z","steps":["trace[595737211] 'agreement among raft nodes before linearized reading' (duration: 513.563423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.14861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.633876Z","time spent":"514.723344ms","remote":"127.0.0.1:36860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-03-04T03:53:58.148536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.828011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:58.146591Z","caller":"traceutil/trace.go:171","msg":"trace[108272631] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"742.239173ms","start":"2026-03-04T03:53:57.404335Z","end":"2026-03-04T03:53:58.146574Z","steps":["trace[108272631] 'process raft request' (duration: 741.539562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.148817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.404316Z","time spent":"744.454974ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T03:53:58.147492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.054165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-04T03:53:58.148941Z","caller":"traceutil/trace.go:171","msg":"trace[400391155] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:19; }","duration":"551.540994ms","start":"2026-03-04T03:53:57.597387Z","end":"2026-03-04T03:53:58.148928Z","steps":["trace[400391155] 'agreement among raft nodes before linearized reading' (duration: 550.063105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.14898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.597365Z","time spent":"551.60529ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-03-04T03:53:58.149047Z","caller":"traceutil/trace.go:171","msg":"trace[1289795387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"750.315942ms","start":"2026-03-04T03:53:57.398444Z","end":"2026-03-04T03:53:58.14876Z","steps":["trace[1289795387] 'agreement among raft nodes before linearized reading' (duration: 748.608414ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:53:58.14752Z","caller":"traceutil/trace.go:171","msg":"trace[178413225] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"747.361256ms","start":"2026-03-04T03:53:57.400149Z","end":"2026-03-04T03:53:58.14751Z","steps":["trace[178413225] 'process raft request' (duration: 745.655909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:53:58.149412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.40014Z","time spent":"749.23281ms","remote":"127.0.0.1:37048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T03:53:58.149265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:53:57.398439Z","time spent":"750.816497ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T03:54:04.682133Z","caller":"traceutil/trace.go:171","msg":"trace[1003963620] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"148.838222ms","start":"2026-03-04T03:54:04.53327Z","end":"2026-03-04T03:54:04.682108Z","steps":["trace[1003963620] 'read index received' (duration: 148.618823ms)","trace[1003963620] 'applied index is now lower than readState.Index' (duration: 218.369µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T03:54:04.682272Z","caller":"traceutil/trace.go:171","msg":"trace[300842409] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"169.520207ms","start":"2026-03-04T03:54:04.512737Z","end":"2026-03-04T03:54:04.682257Z","steps":["trace[300842409] 'process raft request' (duration: 169.239812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:54:04.682761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.468988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-04T03:54:04.68282Z","caller":"traceutil/trace.go:171","msg":"trace[783501954] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:348; }","duration":"149.568837ms","start":"2026-03-04T03:54:04.533238Z","end":"2026-03-04T03:54:04.682807Z","steps":["trace[783501954] 'agreement among raft nodes before linearized reading' (duration: 149.434515ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:54:24.905479Z","caller":"traceutil/trace.go:171","msg":"trace[24588217] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"108.823538ms","start":"2026-03-04T03:54:24.796634Z","end":"2026-03-04T03:54:24.905458Z","steps":["trace[24588217] 'process raft request' (duration: 108.669697ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:54:25.208735Z","caller":"traceutil/trace.go:171","msg":"trace[1866619674] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"102.68484ms","start":"2026-03-04T03:54:25.106025Z","end":"2026-03-04T03:54:25.20871Z","steps":["trace[1866619674] 'process raft request' (duration: 102.241926ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:54:32.019006Z","caller":"traceutil/trace.go:171","msg":"trace[584156491] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"190.282569ms","start":"2026-03-04T03:54:31.828689Z","end":"2026-03-04T03:54:32.018971Z","steps":["trace[584156491] 'read index received' (duration: 190.04527ms)","trace[584156491] 'applied index is now lower than readState.Index' (duration: 235.939µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T03:54:32.019071Z","caller":"traceutil/trace.go:171","msg":"trace[382687300] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"512.875288ms","start":"2026-03-04T03:54:31.506165Z","end":"2026-03-04T03:54:32.01904Z","steps":["trace[382687300] 'process raft request' (duration: 512.631859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:54:32.019162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.47027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-04T03:54:32.019183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:54:31.506148Z","time spent":"512.976713ms","remote":"127.0.0.1:36682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T03:54:32.019241Z","caller":"traceutil/trace.go:171","msg":"trace[102200441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"190.566707ms","start":"2026-03-04T03:54:31.828633Z","end":"2026-03-04T03:54:32.0192Z","steps":["trace[102200441] 'agreement among raft nodes before linearized reading' (duration: 190.460901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:54:33.488304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.999495ms","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-03-04T03:54:33.488387Z","caller":"traceutil/trace.go:171","msg":"trace[441361982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"500.112589ms","start":"2026-03-04T03:54:32.988254Z","end":"2026-03-04T03:54:33.488366Z","steps":["trace[441361982] 'range keys from in-memory index tree' (duration: 499.891349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:54:33.488433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:54:32.98824Z","time spent":"500.183556ms","remote":"127.0.0.1:36754","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-03-04T03:54:33.488431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.344713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-04T03:54:33.488372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128788335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T03:54:33.488488Z","caller":"traceutil/trace.go:171","msg":"trace[945352920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"220.424739ms","start":"2026-03-04T03:54:33.268046Z","end":"2026-03-04T03:54:33.488471Z","steps":["trace[945352920] 'range keys from in-memory index tree' (duration: 220.241107ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:54:33.488505Z","caller":"traceutil/trace.go:171","msg":"trace[757744019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"1.128933189s","start":"2026-03-04T03:54:32.359558Z","end":"2026-03-04T03:54:33.488491Z","steps":["trace[757744019] 'range keys from in-memory index tree' (duration: 1.128675549s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:54:33.488538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:54:32.359506Z","time spent":"1.129023754s","remote":"127.0.0.1:36464","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-03-04T03:55:55.691482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226724111152707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T03:55:55.798619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058525717s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T03:55:55.799141Z","caller":"traceutil/trace.go:171","msg":"trace[486366554] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"607.84059ms","start":"2026-03-04T03:55:55.191272Z","end":"2026-03-04T03:55:55.799113Z","steps":["trace[486366554] 'read index received' (duration: 607.564369ms)","trace[486366554] 'applied index is now lower than readState.Index' (duration: 275.211µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T03:55:55.799194Z","caller":"traceutil/trace.go:171","msg":"trace[1171557848] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"1.059159844s","start":"2026-03-04T03:55:54.739997Z","end":"2026-03-04T03:55:55.799157Z","steps":["trace[1171557848] 'process raft request' (duration: 1.058964236s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:55:55.799396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:55:54.739982Z","time spent":"1.059321261s","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T03:55:55.799422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.122052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c686efab-60c5-411b-90ff-db891c7745d9\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-03-04T03:55:55.799478Z","caller":"traceutil/trace.go:171","msg":"trace[1032443033] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c686efab-60c5-411b-90ff-db891c7745d9; range_end:; response_count:1; response_revision:941; }","duration":"608.229006ms","start":"2026-03-04T03:55:55.191235Z","end":"2026-03-04T03:55:55.799464Z","steps":["trace[1032443033] 'agreement among raft nodes before linearized reading' (duration: 608.022018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:55:55.799483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.034508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-04T03:55:55.799516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:55:55.191216Z","time spent":"608.290809ms","remote":"127.0.0.1:36682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3603,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c686efab-60c5-411b-90ff-db891c7745d9\" "} {"level":"info","ts":"2026-03-04T03:55:55.799544Z","caller":"traceutil/trace.go:171","msg":"trace[866293178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"180.127732ms","start":"2026-03-04T03:55:55.619404Z","end":"2026-03-04T03:55:55.799531Z","steps":["trace[866293178] 'agreement among raft nodes before linearized reading' (duration: 179.979646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:55:55.799459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.453823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T03:55:55.799673Z","caller":"traceutil/trace.go:171","msg":"trace[2087249724] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:941; }","duration":"490.749426ms","start":"2026-03-04T03:55:55.308898Z","end":"2026-03-04T03:55:55.799647Z","steps":["trace[2087249724] 'agreement among raft nodes before linearized reading' (duration: 490.436954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:55:55.799732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:55:55.308879Z","time spent":"490.8404ms","remote":"127.0.0.1:37036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-04T03:56:10.214896Z","caller":"traceutil/trace.go:171","msg":"trace[1724473714] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"179.489312ms","start":"2026-03-04T03:56:10.035354Z","end":"2026-03-04T03:56:10.214843Z","steps":["trace[1724473714] 'read index received' (duration: 179.264111ms)","trace[1724473714] 'applied index is now lower than readState.Index' (duration: 224.131µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T03:56:10.214951Z","caller":"traceutil/trace.go:171","msg":"trace[2111230953] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"179.64654ms","start":"2026-03-04T03:56:10.035259Z","end":"2026-03-04T03:56:10.214906Z","steps":["trace[2111230953] 'process raft request' (duration: 179.44387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:56:10.215115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.262672ms","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-03-04T03:56:10.215156Z","caller":"traceutil/trace.go:171","msg":"trace[111446342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"179.340726ms","start":"2026-03-04T03:56:10.035804Z","end":"2026-03-04T03:56:10.215145Z","steps":["trace[111446342] 'agreement among raft nodes before linearized reading' (duration: 179.196199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:56:10.215163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.786496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-04T03:56:10.215233Z","caller":"traceutil/trace.go:171","msg":"trace[647275431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"179.87056ms","start":"2026-03-04T03:56:10.035347Z","end":"2026-03-04T03:56:10.215217Z","steps":["trace[647275431] 'agreement among raft nodes before linearized reading' (duration: 179.65068ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:56:10.550509Z","caller":"traceutil/trace.go:171","msg":"trace[230894288] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"189.379111ms","start":"2026-03-04T03:56:10.361111Z","end":"2026-03-04T03:56:10.55049Z","steps":["trace[230894288] 'read index received' (duration: 189.285057ms)","trace[230894288] 'applied index is now lower than readState.Index' (duration: 93.134µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T03:56:10.550622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.500436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T03:56:10.550644Z","caller":"traceutil/trace.go:171","msg":"trace[305665575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"189.57771ms","start":"2026-03-04T03:56:10.361059Z","end":"2026-03-04T03:56:10.550637Z","steps":["trace[305665575] 'agreement among raft nodes before linearized reading' (duration: 189.503397ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T03:56:10.550647Z","caller":"traceutil/trace.go:171","msg":"trace[89399275] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"329.960004ms","start":"2026-03-04T03:56:10.220605Z","end":"2026-03-04T03:56:10.550565Z","steps":["trace[89399275] 'process raft request' (duration: 329.734284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T03:56:10.551011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T03:56:10.220581Z","time spent":"330.201235ms","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}