{"level":"warn","ts":"2026-03-18T08:05:25.247351Z","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-18T08:05:25.247445Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.124: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://199.19.213.124:2380","--initial-cluster=instance=https://199.19.213.124:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.124:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.124: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-18T08:05:25.247517Z","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-18T08:05:25.24753Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-03-18T08:05:25.247555Z","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-18T08:05:25.248265Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"]} {"level":"info","ts":"2026-03-18T08:05:25.248347Z","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://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.124: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-18T08:05:25.254955Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.362746ms"} {"level":"info","ts":"2026-03-18T08:05:25.2711Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6894f7e0c0041bce","cluster-id":"db11c089a44de10a"} {"level":"info","ts":"2026-03-18T08:05:25.271255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T08:05:25.271293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 0"} {"level":"info","ts":"2026-03-18T08:05:25.271317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6894f7e0c0041bce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T08:05:25.27134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 1"} {"level":"info","ts":"2026-03-18T08:05:25.27141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"warn","ts":"2026-03-18T08:05:25.279454Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T08:05:25.280931Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T08:05:25.282222Z","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-18T08:05:25.284345Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6894f7e0c0041bce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T08:05:25.285226Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6894f7e0c0041bce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T08:05:25.28464Z","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-18T08:05:25.286723Z","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-18T08:05:25.286742Z","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-18T08:05:25.287033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"info","ts":"2026-03-18T08:05:25.287323Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","added-peer-id":"6894f7e0c0041bce","added-peer-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-03-18T08:05:25.291045Z","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-18T08:05:25.291159Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-03-18T08:05:25.291202Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-03-18T08:05:25.291349Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6894f7e0c0041bce","initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T08:05:25.29141Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T08:05:26.172294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T08:05:26.172411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T08:05:26.172446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgPreVoteResp from 6894f7e0c0041bce at term 1"} {"level":"info","ts":"2026-03-18T08:05:26.172476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became candidate at term 2"} {"level":"info","ts":"2026-03-18T08:05:26.172494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgVoteResp from 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-03-18T08:05:26.172516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became leader at term 2"} {"level":"info","ts":"2026-03-18T08:05:26.172544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6894f7e0c0041bce elected leader 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-03-18T08:05:26.174783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T08:05:26.174809Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T08:05:26.174777Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6894f7e0c0041bce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.124:2379]}","request-path":"/0/members/6894f7e0c0041bce/attributes","cluster-id":"db11c089a44de10a","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T08:05:26.174948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T08:05:26.175116Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T08:05:26.175148Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T08:05:26.176028Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T08:05:26.176092Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T08:05:26.176108Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T08:05:26.179363Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.124:2379"} {"level":"info","ts":"2026-03-18T08:05:26.179422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T08:05:30.448781Z","caller":"traceutil/trace.go:171","msg":"trace[999489053] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"140.242728ms","start":"2026-03-18T08:05:30.308498Z","end":"2026-03-18T08:05:30.44874Z","steps":["trace[999489053] 'process raft request' (duration: 43.420021ms)","trace[999489053] 'compare' (duration: 96.601457ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:05:31.246273Z","caller":"traceutil/trace.go:171","msg":"trace[1237542024] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"119.931447ms","start":"2026-03-18T08:05:31.12629Z","end":"2026-03-18T08:05:31.246222Z","steps":["trace[1237542024] 'process raft request' (duration: 72.621344ms)","trace[1237542024] 'compare' (duration: 47.128005ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:05:31.567138Z","caller":"traceutil/trace.go:171","msg":"trace[933356274] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"149.748356ms","start":"2026-03-18T08:05:31.417362Z","end":"2026-03-18T08:05:31.56711Z","steps":["trace[933356274] 'process raft request' (duration: 133.473234ms)","trace[933356274] 'compare' (duration: 16.159647ms)"],"step_count":2} 2026/03/18 08:05:31 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-18T08:05:32.333232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.26534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T08:05:32.333351Z","caller":"traceutil/trace.go:171","msg":"trace[1915599413] linearizableReadLoop","detail":"{readStateIndex:244; appliedIndex:243; }","duration":"257.970817ms","start":"2026-03-18T08:05:32.075368Z","end":"2026-03-18T08:05:32.333338Z","steps":["trace[1915599413] 'read index received' (duration: 110.974437ms)","trace[1915599413] 'applied index is now lower than readState.Index' (duration: 146.9951ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:32.333436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.865253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T08:05:32.33347Z","caller":"traceutil/trace.go:171","msg":"trace[170195874] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:238; }","duration":"313.917074ms","start":"2026-03-18T08:05:32.019543Z","end":"2026-03-18T08:05:32.33346Z","steps":["trace[170195874] 'agreement among raft nodes before linearized reading' (duration: 313.831121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:32.333454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.428454ms","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-18T08:05:32.333524Z","caller":"traceutil/trace.go:171","msg":"trace[78390155] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:238; }","duration":"291.520387ms","start":"2026-03-18T08:05:32.041978Z","end":"2026-03-18T08:05:32.333499Z","steps":["trace[78390155] 'agreement among raft nodes before linearized reading' (duration: 291.438174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:32.333509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:32.019537Z","time spent":"313.964147ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-03-18T08:05:32.333485Z","caller":"traceutil/trace.go:171","msg":"trace[327046387] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"290.38404ms","start":"2026-03-18T08:05:32.043043Z","end":"2026-03-18T08:05:32.333427Z","steps":["trace[327046387] 'process raft request' (duration: 143.240064ms)","trace[327046387] 'compare' (duration: 145.975337ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:32.333517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.474966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-18T08:05:32.333847Z","caller":"traceutil/trace.go:171","msg":"trace[9917708] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:238; }","duration":"291.854161ms","start":"2026-03-18T08:05:32.041978Z","end":"2026-03-18T08:05:32.333832Z","steps":["trace[9917708] 'agreement among raft nodes before linearized reading' (duration: 291.398752ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:32.334196Z","caller":"traceutil/trace.go:171","msg":"trace[1694141673] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"255.639101ms","start":"2026-03-18T08:05:32.07851Z","end":"2026-03-18T08:05:32.334149Z","steps":["trace[1694141673] 'process raft request' (duration: 255.474524ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:32.334279Z","caller":"traceutil/trace.go:171","msg":"trace[528185606] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"173.721907ms","start":"2026-03-18T08:05:32.160553Z","end":"2026-03-18T08:05:32.334275Z","steps":["trace[528185606] 'process raft request' (duration: 173.597182ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:32.334311Z","caller":"traceutil/trace.go:171","msg":"trace[211302899] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"168.127806ms","start":"2026-03-18T08:05:32.166167Z","end":"2026-03-18T08:05:32.334295Z","steps":["trace[211302899] 'process raft request' (duration: 168.092414ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:32.334458Z","caller":"traceutil/trace.go:171","msg":"trace[1764026658] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"173.928335ms","start":"2026-03-18T08:05:32.160523Z","end":"2026-03-18T08:05:32.334452Z","steps":["trace[1764026658] 'process raft request' (duration: 173.579911ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:32.334239Z","caller":"traceutil/trace.go:171","msg":"trace[659219927] transaction","detail":"{read_only:false; number_of_response:0; response_revision:239; }","duration":"191.144198ms","start":"2026-03-18T08:05:32.143085Z","end":"2026-03-18T08:05:32.33423Z","steps":["trace[659219927] 'process raft request' (duration: 190.978551ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:32.334584Z","caller":"traceutil/trace.go:171","msg":"trace[847546134] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"173.402604ms","start":"2026-03-18T08:05:32.161169Z","end":"2026-03-18T08:05:32.334572Z","steps":["trace[847546134] 'process raft request' (duration: 173.007998ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:32.334711Z","caller":"traceutil/trace.go:171","msg":"trace[130816515] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"173.30506ms","start":"2026-03-18T08:05:32.161396Z","end":"2026-03-18T08:05:32.334701Z","steps":["trace[130816515] 'process raft request' (duration: 172.823321ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:33.463421Z","caller":"traceutil/trace.go:171","msg":"trace[386194475] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"385.369659ms","start":"2026-03-18T08:05:33.078008Z","end":"2026-03-18T08:05:33.463377Z","steps":["trace[386194475] 'process raft request' (duration: 385.14406ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:33.463435Z","caller":"traceutil/trace.go:171","msg":"trace[1117231898] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"385.304866ms","start":"2026-03-18T08:05:33.078093Z","end":"2026-03-18T08:05:33.463398Z","steps":["trace[1117231898] 'process raft request' (duration: 385.15393ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:33.463446Z","caller":"traceutil/trace.go:171","msg":"trace[1010917787] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:283; }","duration":"376.292007ms","start":"2026-03-18T08:05:33.087129Z","end":"2026-03-18T08:05:33.463421Z","steps":["trace[1010917787] 'read index received' (duration: 62.117853ms)","trace[1010917787] 'applied index is now lower than readState.Index' (duration: 314.172914ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:05:33.463613Z","caller":"traceutil/trace.go:171","msg":"trace[606488279] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"384.90663ms","start":"2026-03-18T08:05:33.078657Z","end":"2026-03-18T08:05:33.463564Z","steps":["trace[606488279] 'process raft request' (duration: 384.625788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:33.463623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:33.077979Z","time spent":"385.551926ms","remote":"127.0.0.1:36588","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-18T08:05:33.463571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.415043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T08:05:33.463582Z","caller":"traceutil/trace.go:171","msg":"trace[1728283029] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"369.914007ms","start":"2026-03-18T08:05:33.093649Z","end":"2026-03-18T08:05:33.463563Z","steps":["trace[1728283029] 'process raft request' (duration: 369.668227ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:33.463797Z","caller":"traceutil/trace.go:171","msg":"trace[1790515542] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:276; }","duration":"376.660733ms","start":"2026-03-18T08:05:33.087122Z","end":"2026-03-18T08:05:33.463783Z","steps":["trace[1790515542] 'agreement among raft nodes before linearized reading' (duration: 376.377311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:33.463825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.131057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"warn","ts":"2026-03-18T08:05:33.463855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:33.087064Z","time spent":"376.779307ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" "} {"level":"info","ts":"2026-03-18T08:05:33.463877Z","caller":"traceutil/trace.go:171","msg":"trace[1348348025] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:276; }","duration":"310.19337ms","start":"2026-03-18T08:05:33.153669Z","end":"2026-03-18T08:05:33.463862Z","steps":["trace[1348348025] 'agreement among raft nodes before linearized reading' (duration: 310.075995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:33.463891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:33.078626Z","time spent":"385.108488ms","remote":"127.0.0.1:36588","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-18T08:05:33.46391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:33.153608Z","time spent":"310.294915ms","remote":"127.0.0.1:36588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-18T08:05:33.464117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:33.093635Z","time spent":"370.204668ms","remote":"127.0.0.1:36588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T08:05:33.464217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:33.078089Z","time spent":"385.557336ms","remote":"127.0.0.1:36588","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-18T08:05:33.678413Z","caller":"traceutil/trace.go:171","msg":"trace[797611804] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"128.169429ms","start":"2026-03-18T08:05:33.550221Z","end":"2026-03-18T08:05:33.67839Z","steps":["trace[797611804] 'read index received' (duration: 34.402578ms)","trace[797611804] 'applied index is now lower than readState.Index' (duration: 93.765311ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:05:33.678527Z","caller":"traceutil/trace.go:171","msg":"trace[1430982603] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"128.810434ms","start":"2026-03-18T08:05:33.5497Z","end":"2026-03-18T08:05:33.678511Z","steps":["trace[1430982603] 'process raft request' (duration: 34.993852ms)","trace[1430982603] 'compare' (duration: 93.52164ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:33.678764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.502642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-03-18T08:05:33.679238Z","caller":"traceutil/trace.go:171","msg":"trace[359974639] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:279; }","duration":"128.645258ms","start":"2026-03-18T08:05:33.550171Z","end":"2026-03-18T08:05:33.678816Z","steps":["trace[359974639] 'agreement among raft nodes before linearized reading' (duration: 128.387997ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:34.077513Z","caller":"traceutil/trace.go:171","msg":"trace[3279706] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"274.553628ms","start":"2026-03-18T08:05:33.80293Z","end":"2026-03-18T08:05:34.077483Z","steps":["trace[3279706] 'process raft request' (duration: 183.540756ms)","trace[3279706] 'compare' (duration: 90.836565ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:05:34.07905Z","caller":"traceutil/trace.go:171","msg":"trace[2064281012] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:294; }","duration":"194.334533ms","start":"2026-03-18T08:05:33.884698Z","end":"2026-03-18T08:05:34.079032Z","steps":["trace[2064281012] 'read index received' (duration: 101.707535ms)","trace[2064281012] 'applied index is now lower than readState.Index' (duration: 92.625848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:34.079148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.430077ms","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-18T08:05:34.079182Z","caller":"traceutil/trace.go:171","msg":"trace[1622701560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:286; }","duration":"194.50719ms","start":"2026-03-18T08:05:33.884665Z","end":"2026-03-18T08:05:34.079173Z","steps":["trace[1622701560] 'agreement among raft nodes before linearized reading' (duration: 194.429127ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:34.079395Z","caller":"traceutil/trace.go:171","msg":"trace[1720333864] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"273.959042ms","start":"2026-03-18T08:05:33.805424Z","end":"2026-03-18T08:05:34.079383Z","steps":["trace[1720333864] 'process raft request' (duration: 273.559356ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:34.079626Z","caller":"traceutil/trace.go:171","msg":"trace[1646430888] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"274.270225ms","start":"2026-03-18T08:05:33.805321Z","end":"2026-03-18T08:05:34.079591Z","steps":["trace[1646430888] 'process raft request' (duration: 273.534915ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:34.079209Z","caller":"traceutil/trace.go:171","msg":"trace[395248938] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"273.848589ms","start":"2026-03-18T08:05:33.805341Z","end":"2026-03-18T08:05:34.079189Z","steps":["trace[395248938] 'process raft request' (duration: 273.598109ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:34.213238Z","caller":"traceutil/trace.go:171","msg":"trace[1495078423] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"121.926535ms","start":"2026-03-18T08:05:34.091287Z","end":"2026-03-18T08:05:34.213213Z","steps":["trace[1495078423] 'read index received' (duration: 93.456383ms)","trace[1495078423] 'applied index is now lower than readState.Index' (duration: 28.469272ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:34.213458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.215809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-18T08:05:34.213543Z","caller":"traceutil/trace.go:171","msg":"trace[648678582] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:288; }","duration":"128.334943ms","start":"2026-03-18T08:05:34.085195Z","end":"2026-03-18T08:05:34.21353Z","steps":["trace[648678582] 'agreement among raft nodes before linearized reading' (duration: 128.122205ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:34.214057Z","caller":"traceutil/trace.go:171","msg":"trace[435697816] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"127.971559ms","start":"2026-03-18T08:05:34.086071Z","end":"2026-03-18T08:05:34.214043Z","steps":["trace[435697816] 'process raft request' (duration: 98.747047ms)","trace[435697816] 'compare' (duration: 28.269574ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:05:34.215416Z","caller":"traceutil/trace.go:171","msg":"trace[131749381] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"117.418812ms","start":"2026-03-18T08:05:34.097974Z","end":"2026-03-18T08:05:34.215392Z","steps":["trace[131749381] 'process raft request' (duration: 117.287406ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:34.612538Z","caller":"traceutil/trace.go:171","msg":"trace[476054688] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"158.429651ms","start":"2026-03-18T08:05:34.454086Z","end":"2026-03-18T08:05:34.612515Z","steps":["trace[476054688] 'process raft request' (duration: 123.772399ms)","trace[476054688] 'compare' (duration: 34.560148ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:34.838719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.491617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-18T08:05:34.838814Z","caller":"traceutil/trace.go:171","msg":"trace[1248246517] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:296; }","duration":"100.661764ms","start":"2026-03-18T08:05:34.738132Z","end":"2026-03-18T08:05:34.838794Z","steps":["trace[1248246517] 'range keys from in-memory index tree' (duration: 100.375412ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:37.075148Z","caller":"traceutil/trace.go:171","msg":"trace[1560787142] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"126.22433ms","start":"2026-03-18T08:05:36.9489Z","end":"2026-03-18T08:05:37.075124Z","steps":["trace[1560787142] 'process raft request' (duration: 126.139107ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:37.075154Z","caller":"traceutil/trace.go:171","msg":"trace[1207298351] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"126.334875ms","start":"2026-03-18T08:05:36.948765Z","end":"2026-03-18T08:05:37.0751Z","steps":["trace[1207298351] 'process raft request' (duration: 126.103296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:37.380584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.159281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-03-18T08:05:37.380628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.591214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-18T08:05:37.380694Z","caller":"traceutil/trace.go:171","msg":"trace[1389475095] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:319; }","duration":"193.312057ms","start":"2026-03-18T08:05:37.187358Z","end":"2026-03-18T08:05:37.38067Z","steps":["trace[1389475095] 'range keys from in-memory index tree' (duration: 193.063448ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:37.380726Z","caller":"traceutil/trace.go:171","msg":"trace[1099949824] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:319; }","duration":"142.728499ms","start":"2026-03-18T08:05:37.237969Z","end":"2026-03-18T08:05:37.380698Z","steps":["trace[1099949824] 'range keys from in-memory index tree' (duration: 142.352074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:37.380584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.420116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-18T08:05:37.380835Z","caller":"traceutil/trace.go:171","msg":"trace[1639368518] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:319; }","duration":"242.717718ms","start":"2026-03-18T08:05:37.138102Z","end":"2026-03-18T08:05:37.38082Z","steps":["trace[1639368518] 'range keys from in-memory index tree' (duration: 242.323923ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:37.537359Z","caller":"traceutil/trace.go:171","msg":"trace[1334832742] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"151.809214ms","start":"2026-03-18T08:05:37.385532Z","end":"2026-03-18T08:05:37.537341Z","steps":["trace[1334832742] 'process raft request' (duration: 151.646578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.009739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.131199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T08:05:38.009991Z","caller":"traceutil/trace.go:171","msg":"trace[2056052572] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"622.514712ms","start":"2026-03-18T08:05:37.387439Z","end":"2026-03-18T08:05:38.009954Z","steps":["trace[2056052572] 'process raft request' (duration: 321.108382ms)","trace[2056052572] 'compare' (duration: 300.904321ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:38.01007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:37.387431Z","time spent":"622.610987ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T08:05:38.010074Z","caller":"traceutil/trace.go:171","msg":"trace[889122226] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"285.578001ms","start":"2026-03-18T08:05:37.724482Z","end":"2026-03-18T08:05:38.01006Z","steps":["trace[889122226] 'process raft request' (duration: 285.539ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:38.010296Z","caller":"traceutil/trace.go:171","msg":"trace[2017853029] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"619.662041ms","start":"2026-03-18T08:05:37.390624Z","end":"2026-03-18T08:05:38.010286Z","steps":["trace[2017853029] 'process raft request' (duration: 619.205443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.010373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:37.390599Z","time spent":"619.733554ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T08:05:38.010522Z","caller":"traceutil/trace.go:171","msg":"trace[482805586] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:331; }","duration":"622.276364ms","start":"2026-03-18T08:05:37.388235Z","end":"2026-03-18T08:05:38.010511Z","steps":["trace[482805586] 'read index received' (duration: 148.985384ms)","trace[482805586] 'applied index is now lower than readState.Index' (duration: 473.289659ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:05:38.010539Z","caller":"traceutil/trace.go:171","msg":"trace[99512358] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"619.880189ms","start":"2026-03-18T08:05:37.390623Z","end":"2026-03-18T08:05:38.010503Z","steps":["trace[99512358] 'process raft request' (duration: 619.264476ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:38.01052Z","caller":"traceutil/trace.go:171","msg":"trace[1972615404] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"619.195443ms","start":"2026-03-18T08:05:37.391144Z","end":"2026-03-18T08:05:38.01034Z","steps":["trace[1972615404] 'process raft request' (duration: 618.831689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.010634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.396928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-18T08:05:38.010913Z","caller":"traceutil/trace.go:171","msg":"trace[1814064539] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:325; }","duration":"622.687538ms","start":"2026-03-18T08:05:37.388186Z","end":"2026-03-18T08:05:38.010874Z","steps":["trace[1814064539] 'agreement among raft nodes before linearized reading' (duration: 622.412388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.01087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.897475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-03-18T08:05:38.010965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:37.388173Z","time spent":"622.780623ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-03-18T08:05:38.010994Z","caller":"traceutil/trace.go:171","msg":"trace[567845710] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:325; }","duration":"559.083772ms","start":"2026-03-18T08:05:37.45188Z","end":"2026-03-18T08:05:38.010964Z","steps":["trace[567845710] 'agreement among raft nodes before linearized reading' (duration: 558.822582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.010717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:37.390612Z","time spent":"620.018564ms","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T08:05:38.011068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:37.451864Z","time spent":"559.194027ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-18T08:05:38.011271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.341705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-18T08:05:38.011416Z","caller":"traceutil/trace.go:171","msg":"trace[1254284069] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:325; }","duration":"523.572884ms","start":"2026-03-18T08:05:37.48782Z","end":"2026-03-18T08:05:38.011393Z","steps":["trace[1254284069] 'agreement among raft nodes before linearized reading' (duration: 522.889497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.011521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:37.48778Z","time spent":"523.72543ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-03-18T08:05:38.013782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:37.391128Z","time spent":"619.64297ms","remote":"127.0.0.1:36650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T08:05:38.222431Z","caller":"traceutil/trace.go:171","msg":"trace[459394525] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"115.68854ms","start":"2026-03-18T08:05:38.106719Z","end":"2026-03-18T08:05:38.222407Z","steps":["trace[459394525] 'process raft request' (duration: 115.649539ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:38.222758Z","caller":"traceutil/trace.go:171","msg":"trace[320053694] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"169.363495ms","start":"2026-03-18T08:05:38.053325Z","end":"2026-03-18T08:05:38.222688Z","steps":["trace[320053694] 'process raft request' (duration: 155.601223ms)","trace[320053694] 'compare' (duration: 13.1882ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:38.222932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.822745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-03-18T08:05:38.222811Z","caller":"traceutil/trace.go:171","msg":"trace[1286159332] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:347; }","duration":"138.69196ms","start":"2026-03-18T08:05:38.084098Z","end":"2026-03-18T08:05:38.22279Z","steps":["trace[1286159332] 'read index received' (duration: 18.326748ms)","trace[1286159332] 'applied index is now lower than readState.Index' (duration: 120.362702ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:05:38.22298Z","caller":"traceutil/trace.go:171","msg":"trace[1421048923] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:340; }","duration":"138.92788ms","start":"2026-03-18T08:05:38.084042Z","end":"2026-03-18T08:05:38.222969Z","steps":["trace[1421048923] 'agreement among raft nodes before linearized reading' (duration: 138.802965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.223115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.813986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-5chdn\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-03-18T08:05:38.223168Z","caller":"traceutil/trace.go:171","msg":"trace[1944870634] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-5chdn; range_end:; response_count:1; response_revision:340; }","duration":"115.912099ms","start":"2026-03-18T08:05:38.107243Z","end":"2026-03-18T08:05:38.223155Z","steps":["trace[1944870634] 'agreement among raft nodes before linearized reading' (duration: 115.800865ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:38.223173Z","caller":"traceutil/trace.go:171","msg":"trace[506943520] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"167.93816ms","start":"2026-03-18T08:05:38.055223Z","end":"2026-03-18T08:05:38.223161Z","steps":["trace[506943520] 'process raft request' (duration: 167.093958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.223407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.416954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T08:05:38.223444Z","caller":"traceutil/trace.go:171","msg":"trace[1443961651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:340; }","duration":"112.476737ms","start":"2026-03-18T08:05:38.110959Z","end":"2026-03-18T08:05:38.223435Z","steps":["trace[1443961651] 'agreement among raft nodes before linearized reading' (duration: 112.419335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:38.223576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.412022ms","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-18T08:05:38.223618Z","caller":"traceutil/trace.go:171","msg":"trace[1124406418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:340; }","duration":"114.492185ms","start":"2026-03-18T08:05:38.109116Z","end":"2026-03-18T08:05:38.223608Z","steps":["trace[1124406418] 'agreement among raft nodes before linearized reading' (duration: 114.421903ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:38.222473Z","caller":"traceutil/trace.go:171","msg":"trace[1205002281] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"168.096147ms","start":"2026-03-18T08:05:38.054345Z","end":"2026-03-18T08:05:38.222442Z","steps":["trace[1205002281] 'process raft request' (duration: 167.91495ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:49.87019Z","caller":"traceutil/trace.go:171","msg":"trace[1214613213] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"341.493925ms","start":"2026-03-18T08:05:49.528664Z","end":"2026-03-18T08:05:49.870158Z","steps":["trace[1214613213] 'read index received' (duration: 341.35298ms)","trace[1214613213] 'applied index is now lower than readState.Index' (duration: 139.185µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:49.870244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:49.227084Z","time spent":"643.149078ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-18T08:05:49.870357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.673262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T08:05:49.870395Z","caller":"traceutil/trace.go:171","msg":"trace[642833931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"341.788306ms","start":"2026-03-18T08:05:49.528595Z","end":"2026-03-18T08:05:49.870383Z","steps":["trace[642833931] 'agreement among raft nodes before linearized reading' (duration: 341.683562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:49.870428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:49.52857Z","time spent":"341.850827ms","remote":"127.0.0.1:36386","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-18T08:05:50.236515Z","caller":"traceutil/trace.go:171","msg":"trace[1361213648] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"364.795045ms","start":"2026-03-18T08:05:49.871698Z","end":"2026-03-18T08:05:50.236493Z","steps":["trace[1361213648] 'process raft request' (duration: 364.63885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:05:50.236688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:05:49.871666Z","time spent":"364.920229ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T08:05:58.639126Z","caller":"traceutil/trace.go:171","msg":"trace[1273686092] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"125.991775ms","start":"2026-03-18T08:05:58.513089Z","end":"2026-03-18T08:05:58.63908Z","steps":["trace[1273686092] 'process raft request' (duration: 125.485719ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:05:59.436683Z","caller":"traceutil/trace.go:171","msg":"trace[1531938809] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"112.728027ms","start":"2026-03-18T08:05:59.323931Z","end":"2026-03-18T08:05:59.436659Z","steps":["trace[1531938809] 'read index received' (duration: 112.580233ms)","trace[1531938809] 'applied index is now lower than readState.Index' (duration: 146.604µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:05:59.436833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.891332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-18T08:05:59.43687Z","caller":"traceutil/trace.go:171","msg":"trace[1855088803] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:483; }","duration":"112.991266ms","start":"2026-03-18T08:05:59.323869Z","end":"2026-03-18T08:05:59.43686Z","steps":["trace[1855088803] 'agreement among raft nodes before linearized reading' (duration: 112.893263ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:06:05.455931Z","caller":"traceutil/trace.go:171","msg":"trace[692437727] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"289.923198ms","start":"2026-03-18T08:06:05.16598Z","end":"2026-03-18T08:06:05.455903Z","steps":["trace[692437727] 'process raft request' (duration: 289.757544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:06:06.110114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.988109ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T08:06:06.110246Z","caller":"traceutil/trace.go:171","msg":"trace[613212318] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"583.012067ms","start":"2026-03-18T08:06:05.527214Z","end":"2026-03-18T08:06:06.110226Z","steps":["trace[613212318] 'read index received' (duration: 10.807733ms)","trace[613212318] 'applied index is now lower than readState.Index' (duration: 572.202874ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:06:06.110336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.10806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T08:06:06.110367Z","caller":"traceutil/trace.go:171","msg":"trace[571772008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"583.182242ms","start":"2026-03-18T08:06:05.527176Z","end":"2026-03-18T08:06:06.110358Z","steps":["trace[571772008] 'agreement among raft nodes before linearized reading' (duration: 583.10544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:06:06.110407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:06:05.527161Z","time spent":"583.236693ms","remote":"127.0.0.1:36408","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-18T08:06:06.653224Z","caller":"traceutil/trace.go:171","msg":"trace[2131512627] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"236.730771ms","start":"2026-03-18T08:06:06.416464Z","end":"2026-03-18T08:06:06.653195Z","steps":["trace[2131512627] 'process raft request' (duration: 236.407442ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:06:06.671116Z","caller":"traceutil/trace.go:171","msg":"trace[83873637] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"245.698789ms","start":"2026-03-18T08:06:06.425396Z","end":"2026-03-18T08:06:06.671095Z","steps":["trace[83873637] 'read index received' (duration: 227.415766ms)","trace[83873637] 'applied index is now lower than readState.Index' (duration: 18.282053ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:06:06.671234Z","caller":"traceutil/trace.go:171","msg":"trace[2112093303] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"252.353849ms","start":"2026-03-18T08:06:06.418858Z","end":"2026-03-18T08:06:06.671212Z","steps":["trace[2112093303] 'process raft request' (duration: 252.118752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:06:06.67134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.84836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T08:06:06.67137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.875136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T08:06:06.671444Z","caller":"traceutil/trace.go:171","msg":"trace[2008651833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"144.939353ms","start":"2026-03-18T08:06:06.526434Z","end":"2026-03-18T08:06:06.671373Z","steps":["trace[2008651833] 'agreement among raft nodes before linearized reading' (duration: 144.86306ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:06:06.671491Z","caller":"traceutil/trace.go:171","msg":"trace[245879890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"244.02218ms","start":"2026-03-18T08:06:06.42745Z","end":"2026-03-18T08:06:06.671473Z","steps":["trace[245879890] 'agreement among raft nodes before linearized reading' (duration: 243.829345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:06:06.671826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.429939ms","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-18T08:06:06.671873Z","caller":"traceutil/trace.go:171","msg":"trace[1815031348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"246.490751ms","start":"2026-03-18T08:06:06.42537Z","end":"2026-03-18T08:06:06.671861Z","steps":["trace[1815031348] 'agreement among raft nodes before linearized reading' (duration: 245.841193ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:07:17.319925Z","caller":"traceutil/trace.go:171","msg":"trace[1951299644] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"207.399512ms","start":"2026-03-18T08:07:17.112486Z","end":"2026-03-18T08:07:17.319886Z","steps":["trace[1951299644] 'process raft request' (duration: 117.375637ms)","trace[1951299644] 'compare' (duration: 89.871072ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T08:07:35.605861Z","caller":"traceutil/trace.go:171","msg":"trace[1375493105] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"417.921167ms","start":"2026-03-18T08:07:35.187922Z","end":"2026-03-18T08:07:35.605843Z","steps":["trace[1375493105] 'read index received' (duration: 417.707834ms)","trace[1375493105] 'applied index is now lower than readState.Index' (duration: 212.603µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T08:07:35.606092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.215515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T08:07:35.606017Z","caller":"traceutil/trace.go:171","msg":"trace[865627357] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"464.490038ms","start":"2026-03-18T08:07:35.141452Z","end":"2026-03-18T08:07:35.605942Z","steps":["trace[865627357] 'process raft request' (duration: 464.240454ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T08:07:35.606391Z","caller":"traceutil/trace.go:171","msg":"trace[57382702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"381.55186ms","start":"2026-03-18T08:07:35.224828Z","end":"2026-03-18T08:07:35.60638Z","steps":["trace[57382702] 'agreement among raft nodes before linearized reading' (duration: 381.133774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:07:35.606434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:07:35.224817Z","time spent":"381.607061ms","remote":"127.0.0.1:36652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T08:07:35.606157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.164761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-18T08:07:35.60652Z","caller":"traceutil/trace.go:171","msg":"trace[690569709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"418.593727ms","start":"2026-03-18T08:07:35.187896Z","end":"2026-03-18T08:07:35.60649Z","steps":["trace[690569709] 'agreement among raft nodes before linearized reading' (duration: 418.051669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:07:35.606297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.994594ms","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-18T08:07:35.606584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:07:35.187882Z","time spent":"418.686778ms","remote":"127.0.0.1:36652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T08:07:35.60659Z","caller":"traceutil/trace.go:171","msg":"trace[1057888976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"201.299409ms","start":"2026-03-18T08:07:35.405268Z","end":"2026-03-18T08:07:35.606567Z","steps":["trace[1057888976] 'agreement among raft nodes before linearized reading' (duration: 200.824862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T08:07:35.606641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T08:07:35.141425Z","time spent":"465.053507ms","remote":"127.0.0.1:36652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}