{"level":"warn","ts":"2026-03-09T22:22:08.587371Z","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-09T22:22:08.587518Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-09T22:22:08.587641Z","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-09T22:22:08.587658Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-09T22:22:08.587689Z","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-09T22:22:08.588956Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-03-09T22:22:08.589107Z","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.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-09T22:22:08.59643Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.928082ms"} {"level":"info","ts":"2026-03-09T22:22:08.604662Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-03-09T22:22:08.60474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T22:22:08.604767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-03-09T22:22:08.604778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T22:22:08.604791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-03-09T22:22:08.604833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-03-09T22:22:08.615136Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T22:22:08.616505Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T22:22:08.617906Z","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-09T22:22:08.620103Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T22:22:08.620245Z","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-09T22:22:08.620346Z","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-09T22:22:08.620366Z","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-09T22:22:08.620736Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T22:22:08.632314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-03-09T22:22:08.632431Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-09T22:22:08.633644Z","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-09T22:22:08.633724Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-09T22:22:08.633763Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-09T22:22:08.634019Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T22:22:08.634064Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T22:22:09.105216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T22:22:09.105278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T22:22:09.105332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-03-09T22:22:09.105354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-03-09T22:22:09.105365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-09T22:22:09.105382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-03-09T22:22:09.105395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-09T22:22:09.144983Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T22:22:09.145243Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T22:22:09.145293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T22:22:09.145276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T22:22:09.145568Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T22:22:09.145692Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T22:22:09.146998Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T22:22:09.157671Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-03-09T22:22:09.160803Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T22:22:09.162535Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T22:22:09.162679Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-09T22:22:13.830615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.843553ms","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-09T22:22:13.830712Z","caller":"traceutil/trace.go:171","msg":"trace[1999157781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:233; }","duration":"378.985304ms","start":"2026-03-09T22:22:13.451707Z","end":"2026-03-09T22:22:13.830692Z","steps":["trace[1999157781] 'range keys from in-memory index tree' (duration: 378.747422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:13.830762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.451698Z","time spent":"379.054984ms","remote":"127.0.0.1:35086","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-03-09T22:22:13.834725Z","caller":"traceutil/trace.go:171","msg":"trace[1794511011] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"346.803873ms","start":"2026-03-09T22:22:13.487904Z","end":"2026-03-09T22:22:13.834708Z","steps":["trace[1794511011] 'process raft request' (duration: 346.624241ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:13.834703Z","caller":"traceutil/trace.go:171","msg":"trace[36154276] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"211.667898ms","start":"2026-03-09T22:22:13.623009Z","end":"2026-03-09T22:22:13.834677Z","steps":["trace[36154276] 'read index received' (duration: 211.480727ms)","trace[36154276] 'applied index is now lower than readState.Index' (duration: 185.991µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T22:22:13.834909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.87344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T22:22:13.834955Z","caller":"traceutil/trace.go:171","msg":"trace[443426514] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:234; }","duration":"211.995921ms","start":"2026-03-09T22:22:13.622947Z","end":"2026-03-09T22:22:13.834943Z","steps":["trace[443426514] 'agreement among raft nodes before linearized reading' (duration: 211.88359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:13.835131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.487879Z","time spent":"346.892554ms","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T22:22:14.334985Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763222192381505,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-09T22:22:14.786993Z","caller":"traceutil/trace.go:171","msg":"trace[655440459] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"1.075122689s","start":"2026-03-09T22:22:13.711838Z","end":"2026-03-09T22:22:14.786961Z","steps":["trace[655440459] 'process raft request' (duration: 1.074886367s)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:14.787198Z","caller":"traceutil/trace.go:171","msg":"trace[525222347] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"954.248174ms","start":"2026-03-09T22:22:13.832932Z","end":"2026-03-09T22:22:14.78718Z","steps":["trace[525222347] 'process raft request' (duration: 954.200384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:14.787194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.711819Z","time spent":"1.075290281s","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T22:22:14.787275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.832917Z","time spent":"954.323025ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T22:22:14.787471Z","caller":"traceutil/trace.go:171","msg":"trace[1244006366] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"974.442204ms","start":"2026-03-09T22:22:13.813017Z","end":"2026-03-09T22:22:14.787459Z","steps":["trace[1244006366] 'process raft request' (duration: 973.849269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:14.787537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.81299Z","time spent":"974.515985ms","remote":"127.0.0.1:34986","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-09T22:22:14.787732Z","caller":"traceutil/trace.go:171","msg":"trace[1234560285] transaction","detail":"{read_only:false; number_of_response:0; response_revision:237; }","duration":"954.89063ms","start":"2026-03-09T22:22:13.832827Z","end":"2026-03-09T22:22:14.787718Z","steps":["trace[1234560285] 'process raft request' (duration: 954.192954ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:14.787748Z","caller":"traceutil/trace.go:171","msg":"trace[597856935] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"954.874589ms","start":"2026-03-09T22:22:13.83279Z","end":"2026-03-09T22:22:14.787665Z","steps":["trace[597856935] 'process raft request' (duration: 954.192063ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:14.787862Z","caller":"traceutil/trace.go:171","msg":"trace[785189908] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"955.198223ms","start":"2026-03-09T22:22:13.832647Z","end":"2026-03-09T22:22:14.787845Z","steps":["trace[785189908] 'process raft request' (duration: 954.267285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:14.787808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.832797Z","time spent":"954.97832ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T22:22:14.787969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.832632Z","time spent":"955.288523ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T22:22:14.787962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.832782Z","time spent":"955.083451ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T22:22:14.78853Z","caller":"traceutil/trace.go:171","msg":"trace[1027759936] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"955.02624ms","start":"2026-03-09T22:22:13.832909Z","end":"2026-03-09T22:22:14.787936Z","steps":["trace[1027759936] 'process raft request' (duration: 954.143153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:14.788984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.832899Z","time spent":"956.024419ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T22:22:14.818172Z","caller":"traceutil/trace.go:171","msg":"trace[931360477] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:241; }","duration":"983.348916ms","start":"2026-03-09T22:22:13.834802Z","end":"2026-03-09T22:22:14.81815Z","steps":["trace[931360477] 'read index received' (duration: 952.081227ms)","trace[931360477] 'applied index is now lower than readState.Index' (duration: 31.266419ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T22:22:14.818261Z","caller":"traceutil/trace.go:171","msg":"trace[708836492] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"982.823101ms","start":"2026-03-09T22:22:13.835399Z","end":"2026-03-09T22:22:14.818222Z","steps":["trace[708836492] 'process raft request' (duration: 982.63597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:14.818304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106421679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T22:22:14.818339Z","caller":"traceutil/trace.go:171","msg":"trace[638985663] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:240; }","duration":"1.10650919s","start":"2026-03-09T22:22:13.71182Z","end":"2026-03-09T22:22:14.818329Z","steps":["trace[638985663] 'agreement among raft nodes before linearized reading' (duration: 1.10643728s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:14.818377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.7118Z","time spent":"1.106569791s","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-03-09T22:22:14.818372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106319838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-09T22:22:14.818464Z","caller":"traceutil/trace.go:171","msg":"trace[723597290] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:240; }","duration":"1.106464569s","start":"2026-03-09T22:22:13.711972Z","end":"2026-03-09T22:22:14.818437Z","steps":["trace[723597290] 'agreement among raft nodes before linearized reading' (duration: 1.106269638s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:14.81852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.711951Z","time spent":"1.10655564s","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-09T22:22:14.818892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.835388Z","time spent":"982.940022ms","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T22:22:15.224488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.30796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-09T22:22:15.224596Z","caller":"traceutil/trace.go:171","msg":"trace[566727754] linearizableReadLoop","detail":"{readStateIndex:249; appliedIndex:248; }","duration":"406.344157ms","start":"2026-03-09T22:22:14.818236Z","end":"2026-03-09T22:22:15.22458Z","steps":["trace[566727754] 'read index received' (duration: 277.643393ms)","trace[566727754] 'applied index is now lower than readState.Index' (duration: 128.699454ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T22:22:15.224802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052488257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T22:22:15.224839Z","caller":"traceutil/trace.go:171","msg":"trace[77311890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:241; }","duration":"1.052551068s","start":"2026-03-09T22:22:14.172278Z","end":"2026-03-09T22:22:15.224829Z","steps":["trace[77311890] 'agreement among raft nodes before linearized reading' (duration: 1.052493588s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:15.224828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.461564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-03-09T22:22:15.224873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.172268Z","time spent":"1.052596739s","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-09T22:22:15.224904Z","caller":"traceutil/trace.go:171","msg":"trace[1412791063] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:241; }","duration":"432.566265ms","start":"2026-03-09T22:22:14.792319Z","end":"2026-03-09T22:22:15.224885Z","steps":["trace[1412791063] 'agreement among raft nodes before linearized reading' (duration: 432.309033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:15.224959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.79231Z","time spent":"432.640126ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-03-09T22:22:15.224973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385557718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T22:22:15.225031Z","caller":"traceutil/trace.go:171","msg":"trace[304527012] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:241; }","duration":"1.385683459s","start":"2026-03-09T22:22:13.839333Z","end":"2026-03-09T22:22:15.225016Z","steps":["trace[304527012] 'agreement among raft nodes before linearized reading' (duration: 1.385592158s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:15.225072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.839308Z","time spent":"1.38575645s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" "} {"level":"warn","ts":"2026-03-09T22:22:15.225067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38333797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T22:22:15.225154Z","caller":"traceutil/trace.go:171","msg":"trace[488094043] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:241; }","duration":"1.383455831s","start":"2026-03-09T22:22:13.841676Z","end":"2026-03-09T22:22:15.225132Z","steps":["trace[488094043] 'agreement among raft nodes before linearized reading' (duration: 1.3833018s)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:15.225182Z","caller":"traceutil/trace.go:171","msg":"trace[338981809] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"432.663396ms","start":"2026-03-09T22:22:14.792503Z","end":"2026-03-09T22:22:15.225167Z","steps":["trace[338981809] 'process raft request' (duration: 303.428318ms)","trace[338981809] 'compare' (duration: 127.949768ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T22:22:15.225205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.741765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T22:22:15.225205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:13.841667Z","time spent":"1.383530091s","remote":"127.0.0.1:34912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"info","ts":"2026-03-09T22:22:15.225245Z","caller":"traceutil/trace.go:171","msg":"trace[473416133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:241; }","duration":"333.817776ms","start":"2026-03-09T22:22:14.891418Z","end":"2026-03-09T22:22:15.225236Z","steps":["trace[473416133] 'agreement among raft nodes before linearized reading' (duration: 333.761015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:15.225267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.792479Z","time spent":"432.740096ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T22:22:15.225278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.891402Z","time spent":"333.870196ms","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-09T22:22:15.225309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.780377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-03-09T22:22:15.225443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.263909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T22:22:15.225474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.256457ms","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-03-09T22:22:15.225495Z","caller":"traceutil/trace.go:171","msg":"trace[179545277] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:241; }","duration":"403.989868ms","start":"2026-03-09T22:22:14.821453Z","end":"2026-03-09T22:22:15.225442Z","steps":["trace[179545277] 'agreement among raft nodes before linearized reading' (duration: 403.651836ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:15.225514Z","caller":"traceutil/trace.go:171","msg":"trace[56126196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:241; }","duration":"277.327937ms","start":"2026-03-09T22:22:14.948176Z","end":"2026-03-09T22:22:15.225504Z","steps":["trace[56126196] 'agreement among raft nodes before linearized reading' (duration: 277.207466ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:15.225483Z","caller":"traceutil/trace.go:171","msg":"trace[1179168704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:241; }","duration":"428.31572ms","start":"2026-03-09T22:22:14.797158Z","end":"2026-03-09T22:22:15.225474Z","steps":["trace[1179168704] 'agreement among raft nodes before linearized reading' (duration: 428.255829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:15.225625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.633409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-03-09T22:22:15.225633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.797151Z","time spent":"428.467991ms","remote":"127.0.0.1:34770","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-09T22:22:15.225586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.821439Z","time spent":"404.108569ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-09T22:22:15.225671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.986518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-03-09T22:22:15.225665Z","caller":"traceutil/trace.go:171","msg":"trace[1630246507] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:241; }","duration":"436.700569ms","start":"2026-03-09T22:22:14.788954Z","end":"2026-03-09T22:22:15.225654Z","steps":["trace[1630246507] 'agreement among raft nodes before linearized reading' (duration: 435.721201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:15.225881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.788938Z","time spent":"436.927781ms","remote":"127.0.0.1:34986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-09T22:22:15.225721Z","caller":"traceutil/trace.go:171","msg":"trace[1140070318] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:241; }","duration":"404.075919ms","start":"2026-03-09T22:22:14.821633Z","end":"2026-03-09T22:22:15.225708Z","steps":["trace[1140070318] 'agreement among raft nodes before linearized reading' (duration: 403.557454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:15.226373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.821626Z","time spent":"404.734794ms","remote":"127.0.0.1:34986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-09T22:22:15.225932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.857749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-03-09T22:22:15.226662Z","caller":"traceutil/trace.go:171","msg":"trace[321655327] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:241; }","duration":"432.808807ms","start":"2026-03-09T22:22:14.79377Z","end":"2026-03-09T22:22:15.226579Z","steps":["trace[321655327] 'agreement among raft nodes before linearized reading' (duration: 431.761048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:15.22673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:14.793752Z","time spent":"432.962709ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3997,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-03-09T22:22:39.725292Z","caller":"traceutil/trace.go:171","msg":"trace[1538687717] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"350.3802ms","start":"2026-03-09T22:22:39.374875Z","end":"2026-03-09T22:22:39.725255Z","steps":["trace[1538687717] 'read index received' (duration: 350.169109ms)","trace[1538687717] 'applied index is now lower than readState.Index' (duration: 209.481µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T22:22:39.725647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.711712ms","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-09T22:22:39.725639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.267836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-09T22:22:39.725532Z","caller":"traceutil/trace.go:171","msg":"trace[111052426] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"377.330866ms","start":"2026-03-09T22:22:39.348026Z","end":"2026-03-09T22:22:39.725357Z","steps":["trace[111052426] 'process raft request' (duration: 377.017994ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:39.725824Z","caller":"traceutil/trace.go:171","msg":"trace[648667172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"345.497827ms","start":"2026-03-09T22:22:39.380274Z","end":"2026-03-09T22:22:39.725772Z","steps":["trace[648667172] 'agreement among raft nodes before linearized reading' (duration: 345.238566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:39.725852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.030145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-09T22:22:39.725945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:39.380253Z","time spent":"345.675379ms","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-09T22:22:39.725936Z","caller":"traceutil/trace.go:171","msg":"trace[848996323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:470; }","duration":"319.150565ms","start":"2026-03-09T22:22:39.406768Z","end":"2026-03-09T22:22:39.725919Z","steps":["trace[848996323] 'agreement among raft nodes before linearized reading' (duration: 318.785903ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:39.725737Z","caller":"traceutil/trace.go:171","msg":"trace[302787219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"350.890653ms","start":"2026-03-09T22:22:39.374796Z","end":"2026-03-09T22:22:39.725687Z","steps":["trace[302787219] 'agreement among raft nodes before linearized reading' (duration: 350.658011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:39.726031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:39.406756Z","time spent":"319.244486ms","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T22:22:39.726122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:39.374783Z","time spent":"351.294455ms","remote":"127.0.0.1:35086","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-03-09T22:22:39.726337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:39.347979Z","time spent":"377.867309ms","remote":"127.0.0.1:34986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4566,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T22:22:46.077514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.540276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T22:22:46.077608Z","caller":"traceutil/trace.go:171","msg":"trace[1072349098] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"275.066567ms","start":"2026-03-09T22:22:45.802524Z","end":"2026-03-09T22:22:46.07759Z","steps":["trace[1072349098] 'process raft request' (duration: 146.38472ms)","trace[1072349098] 'compare' (duration: 128.459825ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T22:22:46.644216Z","caller":"traceutil/trace.go:171","msg":"trace[1377305019] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:525; }","duration":"814.098604ms","start":"2026-03-09T22:22:45.830066Z","end":"2026-03-09T22:22:46.644165Z","steps":["trace[1377305019] 'read index received' (duration: 118.415395ms)","trace[1377305019] 'applied index is now lower than readState.Index' (duration: 695.682389ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T22:22:46.644256Z","caller":"traceutil/trace.go:171","msg":"trace[1431388188] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"841.647196ms","start":"2026-03-09T22:22:45.802572Z","end":"2026-03-09T22:22:46.644219Z","steps":["trace[1431388188] 'process raft request' (duration: 841.406435ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:46.644319Z","caller":"traceutil/trace.go:171","msg":"trace[760802007] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"740.809103ms","start":"2026-03-09T22:22:45.903473Z","end":"2026-03-09T22:22:46.644282Z","steps":["trace[760802007] 'process raft request' (duration: 740.651012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:46.64439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.303455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gwsmk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-09T22:22:46.644414Z","caller":"traceutil/trace.go:171","msg":"trace[750990426] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gwsmk; range_end:; response_count:1; response_revision:513; }","duration":"814.365295ms","start":"2026-03-09T22:22:45.830042Z","end":"2026-03-09T22:22:46.644407Z","steps":["trace[750990426] 'agreement among raft nodes before linearized reading' (duration: 814.217444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:46.644434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:45.830028Z","time spent":"814.401316ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-gwsmk\" "} {"level":"warn","ts":"2026-03-09T22:22:46.644423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:45.802558Z","time spent":"841.782097ms","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T22:22:46.644505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:45.903452Z","time spent":"740.948163ms","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T22:22:47.306182Z","caller":"traceutil/trace.go:171","msg":"trace[1942988372] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"202.069456ms","start":"2026-03-09T22:22:47.104087Z","end":"2026-03-09T22:22:47.306157Z","steps":["trace[1942988372] 'process raft request' (duration: 201.928955ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:47.982151Z","caller":"traceutil/trace.go:171","msg":"trace[1349526356] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"183.739192ms","start":"2026-03-09T22:22:47.798383Z","end":"2026-03-09T22:22:47.982122Z","steps":["trace[1349526356] 'read index received' (duration: 137.359203ms)","trace[1349526356] 'applied index is now lower than readState.Index' (duration: 46.378629ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T22:22:47.982273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.876332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T22:22:47.982327Z","caller":"traceutil/trace.go:171","msg":"trace[628503243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"183.964943ms","start":"2026-03-09T22:22:47.79835Z","end":"2026-03-09T22:22:47.982315Z","steps":["trace[628503243] 'agreement among raft nodes before linearized reading' (duration: 183.862662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:47.982437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.496951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-09T22:22:47.982496Z","caller":"traceutil/trace.go:171","msg":"trace[1315315792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"180.581302ms","start":"2026-03-09T22:22:47.801898Z","end":"2026-03-09T22:22:47.982479Z","steps":["trace[1315315792] 'agreement among raft nodes before linearized reading' (duration: 180.391291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T22:22:49.426656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T22:22:49.082289Z","time spent":"344.360975ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-09T22:22:49.426757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.802759ms","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-09T22:22:49.426976Z","caller":"traceutil/trace.go:171","msg":"trace[605451524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"109.03213ms","start":"2026-03-09T22:22:49.317928Z","end":"2026-03-09T22:22:49.42696Z","steps":["trace[605451524] 'range keys from in-memory index tree' (duration: 108.623147ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T22:22:53.156066Z","caller":"traceutil/trace.go:171","msg":"trace[981077895] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"129.63211ms","start":"2026-03-09T22:22:53.026413Z","end":"2026-03-09T22:22:53.156045Z","steps":["trace[981077895] 'process raft request' (duration: 128.418523ms)"],"step_count":1}