{"level":"warn","ts":"2026-04-02T13:55:30.824883Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T13:55:30.825344Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-04-02T13:55:30.825608Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T13:55:30.825633Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-02T13:55:30.825763Z","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-04-02T13:55:30.827782Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-02T13:55:30.827939Z","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.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-04-02T13:55:30.832119Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.880488ms"} {"level":"info","ts":"2026-04-02T13:55:30.844786Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-02T13:55:30.844934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T13:55:30.844971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-02T13:55:30.844986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T13:55:30.845004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-02T13:55:30.845059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-02T13:55:30.853321Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T13:55:30.854611Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T13:55:30.856388Z","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-04-02T13:55:30.861371Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T13:55:30.861575Z","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-04-02T13:55:30.861716Z","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-04-02T13:55:30.86174Z","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-04-02T13:55:30.862573Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T13:55:30.86315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-02T13:55:30.865355Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-02T13:55:30.869725Z","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-04-02T13:55:30.870008Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T13:55:30.870051Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T13:55:30.870866Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-02T13:55:30.870904Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-02T13:55:30.945436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T13:55:30.94553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T13:55:30.94558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-02T13:55:30.945604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-02T13:55:30.945618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-02T13:55:30.945631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-02T13:55:30.945643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-02T13:55:30.946574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T13:55:30.947266Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T13:55:30.947255Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T13:55:30.947389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T13:55:30.947599Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T13:55:30.947624Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T13:55:30.94993Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-02T13:55:30.95046Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T13:55:30.950554Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T13:55:30.950584Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T13:55:30.951372Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T13:55:32.935698Z","caller":"traceutil/trace.go:171","msg":"trace[1101771105] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"147.687931ms","start":"2026-04-02T13:55:32.787952Z","end":"2026-04-02T13:55:32.93564Z","steps":["trace[1101771105] 'process raft request' (duration: 113.119123ms)","trace[1101771105] 'compare' (duration: 34.351956ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:32.93576Z","caller":"traceutil/trace.go:171","msg":"trace[1858050623] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"147.699442ms","start":"2026-04-02T13:55:32.788036Z","end":"2026-04-02T13:55:32.935736Z","steps":["trace[1858050623] 'process raft request' (duration: 147.529781ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:32.936182Z","caller":"traceutil/trace.go:171","msg":"trace[1733677385] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"147.887964ms","start":"2026-04-02T13:55:32.788196Z","end":"2026-04-02T13:55:32.936084Z","steps":["trace[1733677385] 'process raft request' (duration: 147.401749ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:32.936903Z","caller":"traceutil/trace.go:171","msg":"trace[862221008] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"141.13073ms","start":"2026-04-02T13:55:32.795739Z","end":"2026-04-02T13:55:32.93687Z","steps":["trace[862221008] 'process raft request' (duration: 140.555935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:32.937288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.702972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-tjk67\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-02T13:55:32.937361Z","caller":"traceutil/trace.go:171","msg":"trace[1259342649] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-tjk67; range_end:; response_count:1; response_revision:25; }","duration":"148.824893ms","start":"2026-04-02T13:55:32.788523Z","end":"2026-04-02T13:55:32.937348Z","steps":["trace[1259342649] 'agreement among raft nodes before linearized reading' (duration: 148.663761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:32.937869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.735761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-02T13:55:32.937938Z","caller":"traceutil/trace.go:171","msg":"trace[1760976960] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:25; }","duration":"149.841172ms","start":"2026-04-02T13:55:32.788076Z","end":"2026-04-02T13:55:32.937917Z","steps":["trace[1760976960] 'agreement among raft nodes before linearized reading' (duration: 149.720721ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:33.941595Z","caller":"traceutil/trace.go:171","msg":"trace[1179208538] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"115.288823ms","start":"2026-04-02T13:55:33.826271Z","end":"2026-04-02T13:55:33.941559Z","steps":["trace[1179208538] 'process raft request' (duration: 38.096918ms)","trace[1179208538] 'compare' (duration: 76.537069ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:34.259994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.400225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T13:55:34.260129Z","caller":"traceutil/trace.go:171","msg":"trace[1121340435] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"289.919898ms","start":"2026-04-02T13:55:33.970188Z","end":"2026-04-02T13:55:34.260108Z","steps":["trace[1121340435] 'process raft request' (duration: 92.886137ms)","trace[1121340435] 'compare' (duration: 196.203033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:34.527105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.808156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T13:55:34.527219Z","caller":"traceutil/trace.go:171","msg":"trace[899734345] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"184.156232ms","start":"2026-04-02T13:55:34.343038Z","end":"2026-04-02T13:55:34.527194Z","steps":["trace[899734345] 'process raft request' (duration: 26.181854ms)","trace[899734345] 'compare' (duration: 157.641114ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:34.695075Z","caller":"traceutil/trace.go:171","msg":"trace[2016115255] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"163.45585ms","start":"2026-04-02T13:55:34.53159Z","end":"2026-04-02T13:55:34.695046Z","steps":["trace[2016115255] 'process raft request' (duration: 163.264968ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:34.960012Z","caller":"traceutil/trace.go:171","msg":"trace[1700411678] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:91; }","duration":"177.280178ms","start":"2026-04-02T13:55:34.7827Z","end":"2026-04-02T13:55:34.95998Z","steps":["trace[1700411678] 'read index received' (duration: 152.329206ms)","trace[1700411678] 'applied index is now lower than readState.Index' (duration: 24.949702ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:34.960077Z","caller":"traceutil/trace.go:171","msg":"trace[499709138] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"218.760273ms","start":"2026-04-02T13:55:34.741286Z","end":"2026-04-02T13:55:34.960047Z","steps":["trace[499709138] 'process raft request' (duration: 193.842581ms)","trace[499709138] 'compare' (duration: 24.667249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:34.960341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.611981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T13:55:34.960432Z","caller":"traceutil/trace.go:171","msg":"trace[1640386385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:88; }","duration":"177.722142ms","start":"2026-04-02T13:55:34.782686Z","end":"2026-04-02T13:55:34.960408Z","steps":["trace[1640386385] 'agreement among raft nodes before linearized reading' (duration: 177.46276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:35.618546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.915478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T13:55:35.618707Z","caller":"traceutil/trace.go:171","msg":"trace[51480347] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"302.530272ms","start":"2026-04-02T13:55:35.31615Z","end":"2026-04-02T13:55:35.618681Z","steps":["trace[51480347] 'process raft request' (duration: 66.367591ms)","trace[51480347] 'compare' (duration: 235.646466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:35.618778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:35.31613Z","time spent":"302.623612ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":685,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:55:35.928432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.849161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T13:55:35.928526Z","caller":"traceutil/trace.go:171","msg":"trace[1482291358] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:113; }","duration":"145.028954ms","start":"2026-04-02T13:55:35.783481Z","end":"2026-04-02T13:55:35.92851Z","steps":["trace[1482291358] 'read index received' (duration: 35.995881ms)","trace[1482291358] 'applied index is now lower than readState.Index' (duration: 109.032023ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:35.928594Z","caller":"traceutil/trace.go:171","msg":"trace[1988168434] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"247.029833ms","start":"2026-04-02T13:55:35.681497Z","end":"2026-04-02T13:55:35.928527Z","steps":["trace[1988168434] 'process raft request' (duration: 138.00859ms)","trace[1988168434] 'compare' (duration: 108.70348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:35.92886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.259976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T13:55:35.928912Z","caller":"traceutil/trace.go:171","msg":"trace[22034278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:110; }","duration":"145.492929ms","start":"2026-04-02T13:55:35.783407Z","end":"2026-04-02T13:55:35.9289Z","steps":["trace[22034278] 'agreement among raft nodes before linearized reading' (duration: 145.188986ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:36.502264Z","caller":"traceutil/trace.go:171","msg":"trace[1231807311] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"363.002628ms","start":"2026-04-02T13:55:36.13922Z","end":"2026-04-02T13:55:36.502223Z","steps":["trace[1231807311] 'process raft request' (duration: 333.315058ms)","trace[1231807311] 'compare' (duration: 29.487728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:36.502526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:36.139193Z","time spent":"363.17974ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:55:37.251257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.45297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T13:55:37.251372Z","caller":"traceutil/trace.go:171","msg":"trace[1252167110] linearizableReadLoop","detail":"{readStateIndex:154; appliedIndex:153; }","duration":"368.637664ms","start":"2026-04-02T13:55:36.882717Z","end":"2026-04-02T13:55:37.251355Z","steps":["trace[1252167110] 'read index received' (duration: 241.938732ms)","trace[1252167110] 'applied index is now lower than readState.Index' (duration: 126.697322ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:37.251483Z","caller":"traceutil/trace.go:171","msg":"trace[164404384] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"390.929797ms","start":"2026-04-02T13:55:36.860541Z","end":"2026-04-02T13:55:37.25147Z","steps":["trace[164404384] 'process raft request' (duration: 264.231825ms)","trace[164404384] 'compare' (duration: 126.005356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:37.251543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:36.860523Z","time spent":"390.994447ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:55:37.251778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.049127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:55:37.251856Z","caller":"traceutil/trace.go:171","msg":"trace[51529690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:150; }","duration":"369.191908ms","start":"2026-04-02T13:55:36.882645Z","end":"2026-04-02T13:55:37.251837Z","steps":["trace[51529690] 'agreement among raft nodes before linearized reading' (duration: 369.015377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:37.251924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:36.882611Z","time spent":"369.3052ms","remote":"127.0.0.1:44388","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-04-02T13:55:37.430161Z","caller":"traceutil/trace.go:171","msg":"trace[637545711] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"171.043003ms","start":"2026-04-02T13:55:37.259083Z","end":"2026-04-02T13:55:37.430126Z","steps":["trace[637545711] 'process raft request' (duration: 131.483416ms)","trace[637545711] 'compare' (duration: 39.342545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:38.08669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.322093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:55:38.086867Z","caller":"traceutil/trace.go:171","msg":"trace[308419439] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:0; response_revision:167; }","duration":"246.551956ms","start":"2026-04-02T13:55:37.840283Z","end":"2026-04-02T13:55:38.086835Z","steps":["trace[308419439] 'range keys from in-memory index tree' (duration: 246.228803ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:38.112275Z","caller":"traceutil/trace.go:171","msg":"trace[519978417] linearizableReadLoop","detail":"{readStateIndex:173; appliedIndex:172; }","duration":"129.217406ms","start":"2026-04-02T13:55:37.983038Z","end":"2026-04-02T13:55:38.112255Z","steps":["trace[519978417] 'read index received' (duration: 129.084975ms)","trace[519978417] 'applied index is now lower than readState.Index' (duration: 131.111µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:38.112314Z","caller":"traceutil/trace.go:171","msg":"trace[809213382] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"271.834062ms","start":"2026-04-02T13:55:37.840459Z","end":"2026-04-02T13:55:38.112293Z","steps":["trace[809213382] 'process raft request' (duration: 271.65108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:38.112434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.383638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:55:38.112476Z","caller":"traceutil/trace.go:171","msg":"trace[978800049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:168; }","duration":"129.489218ms","start":"2026-04-02T13:55:37.982973Z","end":"2026-04-02T13:55:38.112463Z","steps":["trace[978800049] 'agreement among raft nodes before linearized reading' (duration: 129.390027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:38.117462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.682598ms","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-04-02T13:55:38.117564Z","caller":"traceutil/trace.go:171","msg":"trace[578748963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:169; }","duration":"110.830209ms","start":"2026-04-02T13:55:38.006715Z","end":"2026-04-02T13:55:38.117545Z","steps":["trace[578748963] 'agreement among raft nodes before linearized reading' (duration: 110.662598ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:38.316463Z","caller":"traceutil/trace.go:171","msg":"trace[1895014123] linearizableReadLoop","detail":"{readStateIndex:178; appliedIndex:175; }","duration":"184.641828ms","start":"2026-04-02T13:55:38.131798Z","end":"2026-04-02T13:55:38.31644Z","steps":["trace[1895014123] 'read index received' (duration: 103.195131ms)","trace[1895014123] 'applied index is now lower than readState.Index' (duration: 81.445347ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:38.316556Z","caller":"traceutil/trace.go:171","msg":"trace[1558615498] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"195.742787ms","start":"2026-04-02T13:55:38.120769Z","end":"2026-04-02T13:55:38.316512Z","steps":["trace[1558615498] 'process raft request' (duration: 195.565376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:38.316702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.871911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a28eb95f5a513e\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-04-02T13:55:38.316786Z","caller":"traceutil/trace.go:171","msg":"trace[264461327] range","detail":"{range_begin:/registry/events/default/instance.18a28eb95f5a513e; range_end:; response_count:1; response_revision:173; }","duration":"185.024462ms","start":"2026-04-02T13:55:38.131749Z","end":"2026-04-02T13:55:38.316774Z","steps":["trace[264461327] 'agreement among raft nodes before linearized reading' (duration: 184.82107ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:38.316889Z","caller":"traceutil/trace.go:171","msg":"trace[1764005021] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"193.648318ms","start":"2026-04-02T13:55:38.123107Z","end":"2026-04-02T13:55:38.316755Z","steps":["trace[1764005021] 'process raft request' (duration: 193.281135ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:38.316501Z","caller":"traceutil/trace.go:171","msg":"trace[674045757] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"195.778868ms","start":"2026-04-02T13:55:38.120667Z","end":"2026-04-02T13:55:38.316446Z","steps":["trace[674045757] 'process raft request' (duration: 114.28002ms)","trace[674045757] 'compare' (duration: 81.258556ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:39.172633Z","caller":"traceutil/trace.go:171","msg":"trace[1869093530] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"125.46221ms","start":"2026-04-02T13:55:39.047132Z","end":"2026-04-02T13:55:39.172594Z","steps":["trace[1869093530] 'read index received' (duration: 51.852969ms)","trace[1869093530] 'applied index is now lower than readState.Index' (duration: 73.607481ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:39.172672Z","caller":"traceutil/trace.go:171","msg":"trace[214709467] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"142.713131ms","start":"2026-04-02T13:55:39.02992Z","end":"2026-04-02T13:55:39.172633Z","steps":["trace[214709467] 'process raft request' (duration: 69.134731ms)","trace[214709467] 'compare' (duration: 73.363798ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:39.172969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.803913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/extension-apiserver-authentication-reader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:55:39.173101Z","caller":"traceutil/trace.go:171","msg":"trace[1635139625] range","detail":"{range_begin:/registry/roles/kube-system/extension-apiserver-authentication-reader; range_end:; response_count:0; response_revision:213; }","duration":"125.980454ms","start":"2026-04-02T13:55:39.047084Z","end":"2026-04-02T13:55:39.173064Z","steps":["trace[1635139625] 'agreement among raft nodes before linearized reading' (duration: 125.679891ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:39.354266Z","caller":"traceutil/trace.go:171","msg":"trace[251947837] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"130.262253ms","start":"2026-04-02T13:55:39.223951Z","end":"2026-04-02T13:55:39.354213Z","steps":["trace[251947837] 'process raft request' (duration: 124.818534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:39.917129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.664954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T13:55:39.917245Z","caller":"traceutil/trace.go:171","msg":"trace[1310545606] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"193.484641ms","start":"2026-04-02T13:55:39.72374Z","end":"2026-04-02T13:55:39.917224Z","steps":["trace[1310545606] 'process raft request' (duration: 26.644876ms)","trace[1310545606] 'compare' (duration: 166.519153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:40.208127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.841214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T13:55:40.208219Z","caller":"traceutil/trace.go:171","msg":"trace[743564741] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"166.673632ms","start":"2026-04-02T13:55:40.04153Z","end":"2026-04-02T13:55:40.208203Z","steps":["trace[743564741] 'read index received' (duration: 36.93µs)","trace[743564741] 'applied index is now lower than readState.Index' (duration: 166.635582ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:40.208297Z","caller":"traceutil/trace.go:171","msg":"trace[1840705357] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"201.782879ms","start":"2026-04-02T13:55:40.006501Z","end":"2026-04-02T13:55:40.208284Z","steps":["trace[1840705357] 'process raft request' (duration: 11.715153ms)","trace[1840705357] 'compare' (duration: 189.749534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:40.208475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.468178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T13:55:40.208508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.952434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-04-02T13:55:40.208544Z","caller":"traceutil/trace.go:171","msg":"trace[489896728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:246; }","duration":"111.579959ms","start":"2026-04-02T13:55:40.096946Z","end":"2026-04-02T13:55:40.208526Z","steps":["trace[489896728] 'agreement among raft nodes before linearized reading' (duration: 111.389368ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:40.208585Z","caller":"traceutil/trace.go:171","msg":"trace[1306618552] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:246; }","duration":"167.087845ms","start":"2026-04-02T13:55:40.041479Z","end":"2026-04-02T13:55:40.208567Z","steps":["trace[1306618552] 'agreement among raft nodes before linearized reading' (duration: 166.889023ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:40.356028Z","caller":"traceutil/trace.go:171","msg":"trace[751017929] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"128.036282ms","start":"2026-04-02T13:55:40.227958Z","end":"2026-04-02T13:55:40.355995Z","steps":["trace[751017929] 'process raft request' (duration: 127.938791ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:40.356052Z","caller":"traceutil/trace.go:171","msg":"trace[1477843145] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"142.763281ms","start":"2026-04-02T13:55:40.213265Z","end":"2026-04-02T13:55:40.356029Z","steps":["trace[1477843145] 'process raft request' (duration: 133.833343ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:41.232703Z","caller":"traceutil/trace.go:171","msg":"trace[386719158] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"111.558829ms","start":"2026-04-02T13:55:41.121099Z","end":"2026-04-02T13:55:41.232658Z","steps":["trace[386719158] 'process raft request' (duration: 94.445931ms)","trace[386719158] 'compare' (duration: 16.851146ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:41.877636Z","caller":"traceutil/trace.go:171","msg":"trace[1135425153] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"141.710132ms","start":"2026-04-02T13:55:41.735884Z","end":"2026-04-02T13:55:41.877594Z","steps":["trace[1135425153] 'process raft request' (duration: 141.401189ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.015996Z","caller":"traceutil/trace.go:171","msg":"trace[1181851638] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"129.416542ms","start":"2026-04-02T13:55:41.886553Z","end":"2026-04-02T13:55:42.015969Z","steps":["trace[1181851638] 'read index received' (duration: 129.269391ms)","trace[1181851638] 'applied index is now lower than readState.Index' (duration: 145.681µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:42.016091Z","caller":"traceutil/trace.go:171","msg":"trace[145079885] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"132.360998ms","start":"2026-04-02T13:55:41.883678Z","end":"2026-04-02T13:55:42.016039Z","steps":["trace[145079885] 'process raft request' (duration: 132.122606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:42.016278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.694946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T13:55:42.016352Z","caller":"traceutil/trace.go:171","msg":"trace[1555142287] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:286; }","duration":"129.806966ms","start":"2026-04-02T13:55:41.886518Z","end":"2026-04-02T13:55:42.016325Z","steps":["trace[1555142287] 'agreement among raft nodes before linearized reading' (duration: 129.552824ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.028785Z","caller":"traceutil/trace.go:171","msg":"trace[1664888000] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"141.98023ms","start":"2026-04-02T13:55:41.886785Z","end":"2026-04-02T13:55:42.028765Z","steps":["trace[1664888000] 'process raft request' (duration: 141.90582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:42.663987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.056374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T13:55:42.664123Z","caller":"traceutil/trace.go:171","msg":"trace[1404854290] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"115.08442ms","start":"2026-04-02T13:55:42.549014Z","end":"2026-04-02T13:55:42.664099Z","steps":["trace[1404854290] 'compare' (duration: 104.917962ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.891173Z","caller":"traceutil/trace.go:171","msg":"trace[526661310] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"210.22878ms","start":"2026-04-02T13:55:42.680911Z","end":"2026-04-02T13:55:42.89114Z","steps":["trace[526661310] 'read index received' (duration: 136.730827ms)","trace[526661310] 'applied index is now lower than readState.Index' (duration: 73.496883ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:42.891191Z","caller":"traceutil/trace.go:171","msg":"trace[1759117210] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"211.36181ms","start":"2026-04-02T13:55:42.679796Z","end":"2026-04-02T13:55:42.891158Z","steps":["trace[1759117210] 'process raft request' (duration: 137.782766ms)","trace[1759117210] 'compare' (duration: 73.457753ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:42.891417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.472422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-02T13:55:42.891524Z","caller":"traceutil/trace.go:171","msg":"trace[1384768016] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:305; }","duration":"210.626734ms","start":"2026-04-02T13:55:42.680871Z","end":"2026-04-02T13:55:42.891498Z","steps":["trace[1384768016] 'agreement among raft nodes before linearized reading' (duration: 210.377101ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.90164Z","caller":"traceutil/trace.go:171","msg":"trace[135824502] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"219.987734ms","start":"2026-04-02T13:55:42.681629Z","end":"2026-04-02T13:55:42.901617Z","steps":["trace[135824502] 'process raft request' (duration: 219.755802ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.901669Z","caller":"traceutil/trace.go:171","msg":"trace[1632575647] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"219.378089ms","start":"2026-04-02T13:55:42.682265Z","end":"2026-04-02T13:55:42.901643Z","steps":["trace[1632575647] 'process raft request' (duration: 219.238008ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.901763Z","caller":"traceutil/trace.go:171","msg":"trace[1046966146] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"122.301433ms","start":"2026-04-02T13:55:42.779433Z","end":"2026-04-02T13:55:42.901734Z","steps":["trace[1046966146] 'process raft request' (duration: 122.119721ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.902176Z","caller":"traceutil/trace.go:171","msg":"trace[502086954] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"100.800297ms","start":"2026-04-02T13:55:42.801358Z","end":"2026-04-02T13:55:42.902159Z","steps":["trace[502086954] 'process raft request' (duration: 100.234272ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.902339Z","caller":"traceutil/trace.go:171","msg":"trace[508881399] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"100.827818ms","start":"2026-04-02T13:55:42.801494Z","end":"2026-04-02T13:55:42.902322Z","steps":["trace[508881399] 'process raft request' (duration: 100.144742ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.902477Z","caller":"traceutil/trace.go:171","msg":"trace[1862644954] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"100.966119ms","start":"2026-04-02T13:55:42.801498Z","end":"2026-04-02T13:55:42.902464Z","steps":["trace[1862644954] 'process raft request' (duration: 100.755837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:42.902605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.130968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-02T13:55:42.902629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.97125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-04-02T13:55:42.902652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.038263ms","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-04-02T13:55:42.90269Z","caller":"traceutil/trace.go:171","msg":"trace[1850278211] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:308; }","duration":"215.053902ms","start":"2026-04-02T13:55:42.68762Z","end":"2026-04-02T13:55:42.902674Z","steps":["trace[1850278211] 'agreement among raft nodes before linearized reading' (duration: 214.948561ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.902666Z","caller":"traceutil/trace.go:171","msg":"trace[1068932158] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"218.209169ms","start":"2026-04-02T13:55:42.68444Z","end":"2026-04-02T13:55:42.902649Z","steps":["trace[1068932158] 'agreement among raft nodes before linearized reading' (duration: 218.076587ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:42.902699Z","caller":"traceutil/trace.go:171","msg":"trace[177363975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:308; }","duration":"221.108164ms","start":"2026-04-02T13:55:42.681577Z","end":"2026-04-02T13:55:42.902685Z","steps":["trace[177363975] 'agreement among raft nodes before linearized reading' (duration: 220.957913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:43.723671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.590442ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:55:43.723788Z","caller":"traceutil/trace.go:171","msg":"trace[161104862] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:315; }","duration":"434.730423ms","start":"2026-04-02T13:55:43.289043Z","end":"2026-04-02T13:55:43.723773Z","steps":["trace[161104862] 'range keys from in-memory index tree' (duration: 434.571922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:43.723895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.100639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T13:55:43.724072Z","caller":"traceutil/trace.go:171","msg":"trace[1040622911] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"768.199402ms","start":"2026-04-02T13:55:42.955853Z","end":"2026-04-02T13:55:43.724052Z","steps":["trace[1040622911] 'process raft request' (duration: 48.871851ms)","trace[1040622911] 'compare' (duration: 718.974148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:43.724146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:42.955837Z","time spent":"768.284082ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:55:43.724189Z","caller":"traceutil/trace.go:171","msg":"trace[2146280511] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"763.735344ms","start":"2026-04-02T13:55:42.960428Z","end":"2026-04-02T13:55:43.724164Z","steps":["trace[2146280511] 'process raft request' (duration: 763.555072ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:43.724229Z","caller":"traceutil/trace.go:171","msg":"trace[1615668455] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:326; }","duration":"760.613936ms","start":"2026-04-02T13:55:42.963588Z","end":"2026-04-02T13:55:43.724202Z","steps":["trace[1615668455] 'read index received' (duration: 41.233795ms)","trace[1615668455] 'applied index is now lower than readState.Index' (duration: 719.377281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:43.724335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:42.960415Z","time spent":"763.852645ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:55:43.724388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.795098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T13:55:43.724392Z","caller":"traceutil/trace.go:171","msg":"trace[1017507875] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"759.780399ms","start":"2026-04-02T13:55:42.964581Z","end":"2026-04-02T13:55:43.724361Z","steps":["trace[1017507875] 'process raft request' (duration: 759.481237ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:43.7244Z","caller":"traceutil/trace.go:171","msg":"trace[83715873] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"525.006344ms","start":"2026-04-02T13:55:43.199377Z","end":"2026-04-02T13:55:43.724383Z","steps":["trace[83715873] 'process raft request' (duration: 524.909263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:43.724496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.199341Z","time spent":"525.110744ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:55:43.724493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:42.964571Z","time spent":"759.85808ms","remote":"127.0.0.1:44570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:55:43.724422Z","caller":"traceutil/trace.go:171","msg":"trace[1355480020] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:319; }","duration":"760.854509ms","start":"2026-04-02T13:55:42.963559Z","end":"2026-04-02T13:55:43.724413Z","steps":["trace[1355480020] 'agreement among raft nodes before linearized reading' (duration: 760.777578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:43.724548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:42.963548Z","time spent":"760.99466ms","remote":"127.0.0.1:44476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-02T13:55:44.224946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764449293967334,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T13:55:44.726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764449293967334,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T13:55:44.990665Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.269889257s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T13:55:46.09453Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103519431s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T13:55:46.394487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403439186s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-02T13:55:46.394968Z","caller":"traceutil/trace.go:171","msg":"trace[992522674] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:330; }","duration":"2.670635621s","start":"2026-04-02T13:55:43.72431Z","end":"2026-04-02T13:55:46.394946Z","steps":["trace[992522674] 'read index received' (duration: 1.26666226s)","trace[992522674] 'applied index is now lower than readState.Index' (duration: 1.403972081s)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:46.395082Z","caller":"traceutil/trace.go:171","msg":"trace[201440052] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"2.674415443s","start":"2026-04-02T13:55:43.720655Z","end":"2026-04-02T13:55:46.395071Z","steps":["trace[201440052] 'process raft request' (duration: 1.270299831s)","trace[201440052] 'compare' (duration: 1.403364015s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:46.395152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.720626Z","time spent":"2.674496184s","remote":"127.0.0.1:44548","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-04-02T13:55:46.395558Z","caller":"traceutil/trace.go:171","msg":"trace[2105747756] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"2.666696438s","start":"2026-04-02T13:55:43.728845Z","end":"2026-04-02T13:55:46.395542Z","steps":["trace[2105747756] 'process raft request' (duration: 2.665887651s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.39566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.728829Z","time spent":"2.666799898s","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:55:46.395849Z","caller":"traceutil/trace.go:171","msg":"trace[2078049129] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"2.673724117s","start":"2026-04-02T13:55:43.722103Z","end":"2026-04-02T13:55:46.395828Z","steps":["trace[2078049129] 'process raft request' (duration: 2.672547467s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:46.395838Z","caller":"traceutil/trace.go:171","msg":"trace[2008943190] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"2.666002671s","start":"2026-04-02T13:55:43.729784Z","end":"2026-04-02T13:55:46.395786Z","steps":["trace[2008943190] 'process raft request' (duration: 2.665053443s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.395905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.72207Z","time spent":"2.673809007s","remote":"127.0.0.1:44548","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-04-02T13:55:46.395904Z","caller":"traceutil/trace.go:171","msg":"trace[470258580] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"2.673569835s","start":"2026-04-02T13:55:43.722297Z","end":"2026-04-02T13:55:46.395867Z","steps":["trace[470258580] 'process raft request' (duration: 2.672397295s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.397015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.722284Z","time spent":"2.674636324s","remote":"127.0.0.1:44548","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-04-02T13:55:46.396042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.729775Z","time spent":"2.666164323s","remote":"127.0.0.1:44570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:55:46.39611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.934951815s","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-04-02T13:55:46.397192Z","caller":"traceutil/trace.go:171","msg":"trace[608574806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:322; }","duration":"1.936105384s","start":"2026-04-02T13:55:44.461065Z","end":"2026-04-02T13:55:46.39717Z","steps":["trace[608574806] 'agreement among raft nodes before linearized reading' (duration: 1.934885244s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.397263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:44.461038Z","time spent":"1.936206875s","remote":"127.0.0.1:44628","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-04-02T13:55:46.396139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.854007ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:55:46.397505Z","caller":"traceutil/trace.go:171","msg":"trace[15764499] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:322; }","duration":"389.233589ms","start":"2026-04-02T13:55:46.008259Z","end":"2026-04-02T13:55:46.397492Z","steps":["trace[15764499] 'agreement among raft nodes before linearized reading' (duration: 387.818097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.396195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.728253725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T13:55:46.39626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401701741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:55:46.397737Z","caller":"traceutil/trace.go:171","msg":"trace[2020597321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:322; }","duration":"1.729831798s","start":"2026-04-02T13:55:44.667895Z","end":"2026-04-02T13:55:46.397726Z","steps":["trace[2020597321] 'agreement among raft nodes before linearized reading' (duration: 1.728266515s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:46.397837Z","caller":"traceutil/trace.go:171","msg":"trace[1507148937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:322; }","duration":"1.403314875s","start":"2026-04-02T13:55:44.994504Z","end":"2026-04-02T13:55:46.397819Z","steps":["trace[1507148937] 'agreement among raft nodes before linearized reading' (duration: 1.401716251s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.396305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.675553303s","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-04-02T13:55:46.397848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:44.667872Z","time spent":"1.72996244s","remote":"127.0.0.1:44628","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-04-02T13:55:46.397882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:44.994482Z","time spent":"1.403387995s","remote":"127.0.0.1:44388","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-04-02T13:55:46.3979Z","caller":"traceutil/trace.go:171","msg":"trace[2007321196] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:322; }","duration":"2.677183006s","start":"2026-04-02T13:55:43.720695Z","end":"2026-04-02T13:55:46.397878Z","steps":["trace[2007321196] 'agreement among raft nodes before linearized reading' (duration: 2.675532422s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.396363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.948054879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-02T13:55:46.39798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.720665Z","time spent":"2.677296707s","remote":"127.0.0.1:44548","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":"info","ts":"2026-04-02T13:55:46.398018Z","caller":"traceutil/trace.go:171","msg":"trace[24097955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:322; }","duration":"2.949751273s","start":"2026-04-02T13:55:43.448255Z","end":"2026-04-02T13:55:46.398007Z","steps":["trace[24097955] 'agreement among raft nodes before linearized reading' (duration: 2.948053979s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.398054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.448233Z","time spent":"2.949812783s","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T13:55:46.395504Z","caller":"traceutil/trace.go:171","msg":"trace[688234068] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"2.661484812s","start":"2026-04-02T13:55:43.734007Z","end":"2026-04-02T13:55:46.395492Z","steps":["trace[688234068] 'process raft request' (duration: 2.660881797s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.398138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:43.733999Z","time spent":"2.664115414s","remote":"127.0.0.1:44848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:55:46.400645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4416921s","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-04-02T13:55:46.400706Z","caller":"traceutil/trace.go:171","msg":"trace[1878869650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:322; }","duration":"1.444950677s","start":"2026-04-02T13:55:44.955742Z","end":"2026-04-02T13:55:46.400693Z","steps":["trace[1878869650] 'agreement among raft nodes before linearized reading' (duration: 1.440370348s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.400734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:44.955722Z","time spent":"1.445001427s","remote":"127.0.0.1:44628","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-04-02T13:55:46.817381Z","caller":"traceutil/trace.go:171","msg":"trace[1597208707] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"414.854503ms","start":"2026-04-02T13:55:46.402492Z","end":"2026-04-02T13:55:46.817347Z","steps":["trace[1597208707] 'process raft request' (duration: 344.529608ms)","trace[1597208707] 'compare' (duration: 70.192134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:46.817591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:46.402473Z","time spent":"414.992245ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T13:55:46.8185Z","caller":"traceutil/trace.go:171","msg":"trace[51620478] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:336; }","duration":"413.338951ms","start":"2026-04-02T13:55:46.405138Z","end":"2026-04-02T13:55:46.818477Z","steps":["trace[51620478] 'read index received' (duration: 341.896056ms)","trace[51620478] 'applied index is now lower than readState.Index' (duration: 71.441575ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:46.818625Z","caller":"traceutil/trace.go:171","msg":"trace[420952057] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"414.062637ms","start":"2026-04-02T13:55:46.404551Z","end":"2026-04-02T13:55:46.818614Z","steps":["trace[420952057] 'process raft request' (duration: 413.780994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.818758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:46.404544Z","time spent":"414.113147ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:55:46.818777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.904768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-04-02T13:55:46.818506Z","caller":"traceutil/trace.go:171","msg":"trace[1172140712] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"414.113448ms","start":"2026-04-02T13:55:46.404358Z","end":"2026-04-02T13:55:46.818471Z","steps":["trace[1172140712] 'process raft request' (duration: 413.825645ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:46.81895Z","caller":"traceutil/trace.go:171","msg":"trace[1738706634] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"409.845752ms","start":"2026-04-02T13:55:46.409094Z","end":"2026-04-02T13:55:46.818939Z","steps":["trace[1738706634] 'process raft request' (duration: 409.326018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.819078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:46.409084Z","time spent":"409.951803ms","remote":"127.0.0.1:44548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:55:46.819092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:46.404344Z","time spent":"414.639932ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T13:55:46.818826Z","caller":"traceutil/trace.go:171","msg":"trace[2095769849] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:327; }","duration":"412.968158ms","start":"2026-04-02T13:55:46.405844Z","end":"2026-04-02T13:55:46.818813Z","steps":["trace[2095769849] 'agreement among raft nodes before linearized reading' (duration: 412.868848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.819325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:46.405837Z","time spent":"413.452502ms","remote":"127.0.0.1:44426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":138,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-02T13:55:46.818874Z","caller":"traceutil/trace.go:171","msg":"trace[458128158] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"413.601713ms","start":"2026-04-02T13:55:46.405253Z","end":"2026-04-02T13:55:46.818855Z","steps":["trace[458128158] 'process raft request' (duration: 413.134839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.819322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.118067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-02T13:55:46.819451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:46.405225Z","time spent":"414.177678ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T13:55:46.819475Z","caller":"traceutil/trace.go:171","msg":"trace[1953428491] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:327; }","duration":"414.322229ms","start":"2026-04-02T13:55:46.405132Z","end":"2026-04-02T13:55:46.819455Z","steps":["trace[1953428491] 'agreement among raft nodes before linearized reading' (duration: 413.623983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:46.819559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:46.405105Z","time spent":"414.443561ms","remote":"127.0.0.1:44476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-02T13:55:47.184213Z","caller":"traceutil/trace.go:171","msg":"trace[123823151] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"155.718397ms","start":"2026-04-02T13:55:47.028473Z","end":"2026-04-02T13:55:47.184192Z","steps":["trace[123823151] 'process raft request' (duration: 109.017031ms)","trace[123823151] 'compare' (duration: 46.590175ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:48.120745Z","caller":"traceutil/trace.go:171","msg":"trace[816126185] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"177.996993ms","start":"2026-04-02T13:55:47.942712Z","end":"2026-04-02T13:55:48.120709Z","steps":["trace[816126185] 'process raft request' (duration: 169.718705ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.120852Z","caller":"traceutil/trace.go:171","msg":"trace[481502956] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"134.684445ms","start":"2026-04-02T13:55:47.986142Z","end":"2026-04-02T13:55:48.120826Z","steps":["trace[481502956] 'process raft request' (duration: 134.621894ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.120819Z","caller":"traceutil/trace.go:171","msg":"trace[162179811] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"176.266068ms","start":"2026-04-02T13:55:47.944527Z","end":"2026-04-02T13:55:48.120793Z","steps":["trace[162179811] 'process raft request' (duration: 176.102367ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.120897Z","caller":"traceutil/trace.go:171","msg":"trace[612326437] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:397; }","duration":"135.009018ms","start":"2026-04-02T13:55:47.985845Z","end":"2026-04-02T13:55:48.120854Z","steps":["trace[612326437] 'read index received' (duration: 126.569539ms)","trace[612326437] 'applied index is now lower than readState.Index' (duration: 8.435219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:48.121337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.362433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"warn","ts":"2026-04-02T13:55:48.121335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.455652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-x9742\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-02T13:55:48.121386Z","caller":"traceutil/trace.go:171","msg":"trace[1227040438] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:386; }","duration":"128.448244ms","start":"2026-04-02T13:55:47.992923Z","end":"2026-04-02T13:55:48.121371Z","steps":["trace[1227040438] 'agreement among raft nodes before linearized reading' (duration: 128.312833ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.121407Z","caller":"traceutil/trace.go:171","msg":"trace[787234384] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-x9742; range_end:; response_count:1; response_revision:386; }","duration":"135.596502ms","start":"2026-04-02T13:55:47.985795Z","end":"2026-04-02T13:55:48.121392Z","steps":["trace[787234384] 'agreement among raft nodes before linearized reading' (duration: 135.179199ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.338288Z","caller":"traceutil/trace.go:171","msg":"trace[1562378329] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"204.94737ms","start":"2026-04-02T13:55:48.133311Z","end":"2026-04-02T13:55:48.338258Z","steps":["trace[1562378329] 'process raft request' (duration: 121.780978ms)","trace[1562378329] 'compare' (duration: 82.92584ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:48.338557Z","caller":"traceutil/trace.go:171","msg":"trace[157525905] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"205.051301ms","start":"2026-04-02T13:55:48.13348Z","end":"2026-04-02T13:55:48.338531Z","steps":["trace[157525905] 'process raft request' (duration: 204.704358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:48.33869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.616596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-02T13:55:48.338621Z","caller":"traceutil/trace.go:171","msg":"trace[583772079] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:401; }","duration":"171.251984ms","start":"2026-04-02T13:55:48.167332Z","end":"2026-04-02T13:55:48.338584Z","steps":["trace[583772079] 'read index received' (duration: 87.732639ms)","trace[583772079] 'applied index is now lower than readState.Index' (duration: 83.427784ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:48.338734Z","caller":"traceutil/trace.go:171","msg":"trace[374478665] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:389; }","duration":"205.692556ms","start":"2026-04-02T13:55:48.133031Z","end":"2026-04-02T13:55:48.338724Z","steps":["trace[374478665] 'agreement among raft nodes before linearized reading' (duration: 205.606646ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.372978Z","caller":"traceutil/trace.go:171","msg":"trace[891156899] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"196.776723ms","start":"2026-04-02T13:55:48.176182Z","end":"2026-04-02T13:55:48.372958Z","steps":["trace[891156899] 'process raft request' (duration: 196.693822ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.714201Z","caller":"traceutil/trace.go:171","msg":"trace[2119392326] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"334.544783ms","start":"2026-04-02T13:55:48.379613Z","end":"2026-04-02T13:55:48.714158Z","steps":["trace[2119392326] 'process raft request' (duration: 249.668608ms)","trace[2119392326] 'compare' (duration: 84.507522ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:48.7143Z","caller":"traceutil/trace.go:171","msg":"trace[1240739619] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"334.375572ms","start":"2026-04-02T13:55:48.379886Z","end":"2026-04-02T13:55:48.714261Z","steps":["trace[1240739619] 'process raft request' (duration: 334.09283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:48.714405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:48.37958Z","time spent":"334.735425ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:55:48.714509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:48.379876Z","time spent":"334.510903ms","remote":"127.0.0.1:44548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:55:48.714588Z","caller":"traceutil/trace.go:171","msg":"trace[597668821] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"329.26369ms","start":"2026-04-02T13:55:48.385238Z","end":"2026-04-02T13:55:48.714502Z","steps":["trace[597668821] 'process raft request' (duration: 328.803956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:48.714846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:48.385212Z","time spent":"329.509212ms","remote":"127.0.0.1:44548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T13:55:48.715057Z","caller":"traceutil/trace.go:171","msg":"trace[1297022714] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"328.796805ms","start":"2026-04-02T13:55:48.386237Z","end":"2026-04-02T13:55:48.715034Z","steps":["trace[1297022714] 'process raft request' (duration: 327.859368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:48.715183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:55:48.386226Z","time spent":"328.882176ms","remote":"127.0.0.1:44848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T13:55:48.715286Z","caller":"traceutil/trace.go:171","msg":"trace[2130008235] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:405; }","duration":"219.706211ms","start":"2026-04-02T13:55:48.495561Z","end":"2026-04-02T13:55:48.715267Z","steps":["trace[2130008235] 'read index received' (duration: 133.716636ms)","trace[2130008235] 'applied index is now lower than readState.Index' (duration: 85.987565ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:55:48.715531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.945103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-02T13:55:48.716071Z","caller":"traceutil/trace.go:171","msg":"trace[1661222869] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:395; }","duration":"220.529048ms","start":"2026-04-02T13:55:48.495514Z","end":"2026-04-02T13:55:48.716043Z","steps":["trace[1661222869] 'agreement among raft nodes before linearized reading' (duration: 219.914163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:48.99677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.516166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T13:55:48.997023Z","caller":"traceutil/trace.go:171","msg":"trace[562773188] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:411; }","duration":"173.843755ms","start":"2026-04-02T13:55:48.823163Z","end":"2026-04-02T13:55:48.997006Z","steps":["trace[562773188] 'read index received' (duration: 24.039357ms)","trace[562773188] 'applied index is now lower than readState.Index' (duration: 149.803818ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:48.996995Z","caller":"traceutil/trace.go:171","msg":"trace[800232194] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"268.684313ms","start":"2026-04-02T13:55:48.728278Z","end":"2026-04-02T13:55:48.996962Z","steps":["trace[800232194] 'process raft request' (duration: 118.908745ms)","trace[800232194] 'compare' (duration: 149.386415ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:48.997094Z","caller":"traceutil/trace.go:171","msg":"trace[1509925926] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"265.92381ms","start":"2026-04-02T13:55:48.731151Z","end":"2026-04-02T13:55:48.997075Z","steps":["trace[1509925926] 'process raft request' (duration: 265.788079ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.997192Z","caller":"traceutil/trace.go:171","msg":"trace[1235456348] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"268.748273ms","start":"2026-04-02T13:55:48.728409Z","end":"2026-04-02T13:55:48.997157Z","steps":["trace[1235456348] 'process raft request' (duration: 268.475681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:48.997301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.772165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-02T13:55:48.997299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.108637ms","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-04-02T13:55:48.997292Z","caller":"traceutil/trace.go:171","msg":"trace[672019728] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"242.172145ms","start":"2026-04-02T13:55:48.755084Z","end":"2026-04-02T13:55:48.997256Z","steps":["trace[672019728] 'process raft request' (duration: 241.890413ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.997368Z","caller":"traceutil/trace.go:171","msg":"trace[1620577832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"174.245758ms","start":"2026-04-02T13:55:48.823107Z","end":"2026-04-02T13:55:48.997353Z","steps":["trace[1620577832] 'agreement among raft nodes before linearized reading' (duration: 174.080817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:48.997481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.685924ms","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-04-02T13:55:48.997525Z","caller":"traceutil/trace.go:171","msg":"trace[1181159286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"173.777584ms","start":"2026-04-02T13:55:48.823735Z","end":"2026-04-02T13:55:48.997512Z","steps":["trace[1181159286] 'agreement among raft nodes before linearized reading' (duration: 173.666483ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:48.997326Z","caller":"traceutil/trace.go:171","msg":"trace[218953820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"173.842035ms","start":"2026-04-02T13:55:48.823476Z","end":"2026-04-02T13:55:48.997318Z","steps":["trace[218953820] 'agreement among raft nodes before linearized reading' (duration: 173.784584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:55:48.997543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.583964ms","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-04-02T13:55:48.997591Z","caller":"traceutil/trace.go:171","msg":"trace[1026114170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"172.686666ms","start":"2026-04-02T13:55:48.824891Z","end":"2026-04-02T13:55:48.997578Z","steps":["trace[1026114170] 'agreement among raft nodes before linearized reading' (duration: 172.523385ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:55:49.231823Z","caller":"traceutil/trace.go:171","msg":"trace[617665141] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"152.607591ms","start":"2026-04-02T13:55:49.079179Z","end":"2026-04-02T13:55:49.231786Z","steps":["trace[617665141] 'process raft request' (duration: 93.829273ms)","trace[617665141] 'compare' (duration: 58.613617ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:51.335866Z","caller":"traceutil/trace.go:171","msg":"trace[597367423] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"244.573092ms","start":"2026-04-02T13:55:51.091251Z","end":"2026-04-02T13:55:51.335824Z","steps":["trace[597367423] 'process raft request' (duration: 184.093547ms)","trace[597367423] 'compare' (duration: 60.321683ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:55:51.345633Z","caller":"traceutil/trace.go:171","msg":"trace[881686852] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"253.610944ms","start":"2026-04-02T13:55:51.092003Z","end":"2026-04-02T13:55:51.345614Z","steps":["trace[881686852] 'process raft request' (duration: 253.522274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:14.408564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764449293967771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T13:56:14.718081Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.12185552s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T13:56:14.718451Z","caller":"traceutil/trace.go:171","msg":"trace[954071651] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.122341009s","start":"2026-04-02T13:56:13.596085Z","end":"2026-04-02T13:56:14.718426Z","steps":["trace[954071651] 'process raft request' (duration: 1.122201232s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:14.718696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.596062Z","time spent":"1.122545538s","remote":"127.0.0.1:44628","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-04-02T13:56:14.731672Z","caller":"traceutil/trace.go:171","msg":"trace[881363239] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.059943394s","start":"2026-04-02T13:56:13.671697Z","end":"2026-04-02T13:56:14.73164Z","steps":["trace[881363239] 'process raft request' (duration: 1.05985372s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:14.731758Z","caller":"traceutil/trace.go:171","msg":"trace[355032554] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.046306142s","start":"2026-04-02T13:56:13.685439Z","end":"2026-04-02T13:56:14.731745Z","steps":["trace[355032554] 'process raft request' (duration: 1.046203947s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:14.731746Z","caller":"traceutil/trace.go:171","msg":"trace[6212359] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.046340461s","start":"2026-04-02T13:56:13.685368Z","end":"2026-04-02T13:56:14.731709Z","steps":["trace[6212359] 'process raft request' (duration: 1.046226765s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:14.731702Z","caller":"traceutil/trace.go:171","msg":"trace[449114463] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.064271934s","start":"2026-04-02T13:56:13.667349Z","end":"2026-04-02T13:56:14.731621Z","steps":["trace[449114463] 'process raft request' (duration: 1.064101266s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:14.731836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.685434Z","time spent":"1.046348824s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:56:14.731861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.667324Z","time spent":"1.064505235s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:56:14.731924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.685347Z","time spent":"1.046492148s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:56:14.732008Z","caller":"traceutil/trace.go:171","msg":"trace[1843709076] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.045241106s","start":"2026-04-02T13:56:13.686751Z","end":"2026-04-02T13:56:14.731992Z","steps":["trace[1843709076] 'process raft request' (duration: 1.0449313s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:14.732071Z","caller":"traceutil/trace.go:171","msg":"trace[404828789] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.041982153s","start":"2026-04-02T13:56:13.690039Z","end":"2026-04-02T13:56:14.732022Z","steps":["trace[404828789] 'process raft request' (duration: 1.041753751s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:14.732091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.686742Z","time spent":"1.045309439s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:56:14.732157Z","caller":"traceutil/trace.go:171","msg":"trace[1567785354] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.042988256s","start":"2026-04-02T13:56:13.689153Z","end":"2026-04-02T13:56:14.732142Z","steps":["trace[1567785354] 'process raft request' (duration: 1.042606037s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:14.732172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.690018Z","time spent":"1.04210848s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:56:14.73221Z","caller":"traceutil/trace.go:171","msg":"trace[1353408781] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.027069005s","start":"2026-04-02T13:56:13.705127Z","end":"2026-04-02T13:56:14.732196Z","steps":["trace[1353408781] 'process raft request' (duration: 1.026823592s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:14.732214Z","caller":"traceutil/trace.go:171","msg":"trace[510446988] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.03566843s","start":"2026-04-02T13:56:13.696396Z","end":"2026-04-02T13:56:14.732065Z","steps":["trace[510446988] 'process raft request' (duration: 1.035436178s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:14.732271Z","caller":"traceutil/trace.go:171","msg":"trace[1596449966] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"996.57496ms","start":"2026-04-02T13:56:13.735685Z","end":"2026-04-02T13:56:14.73226Z","steps":["trace[1596449966] 'process raft request' (duration: 996.346029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:14.732323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.735664Z","time spent":"996.639483ms","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:56:14.732217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.689148Z","time spent":"1.043044779s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:56:14.732324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.705096Z","time spent":"1.027163708s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:56:14.732328Z","caller":"traceutil/trace.go:171","msg":"trace[530405056] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:557; }","duration":"824.338195ms","start":"2026-04-02T13:56:13.907961Z","end":"2026-04-02T13:56:14.732299Z","steps":["trace[530405056] 'read index received' (duration: 810.33054ms)","trace[530405056] 'applied index is now lower than readState.Index' (duration: 14.006475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:56:14.732364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.696389Z","time spent":"1.035908982s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:56:14.732442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.472632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:56:14.732446Z","caller":"traceutil/trace.go:171","msg":"trace[689740886] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"246.864007ms","start":"2026-04-02T13:56:14.485567Z","end":"2026-04-02T13:56:14.732431Z","steps":["trace[689740886] 'process raft request' (duration: 246.663707ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:14.732451Z","caller":"traceutil/trace.go:171","msg":"trace[1600954386] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"857.057545ms","start":"2026-04-02T13:56:13.875379Z","end":"2026-04-02T13:56:14.732437Z","steps":["trace[1600954386] 'process raft request' (duration: 856.77223ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:14.732486Z","caller":"traceutil/trace.go:171","msg":"trace[1673227592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"824.560355ms","start":"2026-04-02T13:56:13.907915Z","end":"2026-04-02T13:56:14.732475Z","steps":["trace[1673227592] 'agreement among raft nodes before linearized reading' (duration: 824.46378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:14.732526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.907892Z","time spent":"824.625727ms","remote":"127.0.0.1:44388","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-04-02T13:56:14.732529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.87535Z","time spent":"857.144068ms","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:56:14.732552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.671678Z","time spent":"1.060085921s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:56:14.732309Z","caller":"traceutil/trace.go:171","msg":"trace[1456315794] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.04323123s","start":"2026-04-02T13:56:13.688753Z","end":"2026-04-02T13:56:14.731984Z","steps":["trace[1456315794] 'process raft request' (duration: 1.042966096s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:14.732966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:13.688732Z","time spent":"1.044170539s","remote":"127.0.0.1:44412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T13:56:20.958179Z","caller":"traceutil/trace.go:171","msg":"trace[1870946333] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"120.03746ms","start":"2026-04-02T13:56:20.838102Z","end":"2026-04-02T13:56:20.958139Z","steps":["trace[1870946333] 'process raft request' (duration: 119.82479ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:21.372128Z","caller":"traceutil/trace.go:171","msg":"trace[1989841120] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"135.79847ms","start":"2026-04-02T13:56:21.236277Z","end":"2026-04-02T13:56:21.372076Z","steps":["trace[1989841120] 'read index received' (duration: 134.219355ms)","trace[1989841120] 'applied index is now lower than readState.Index' (duration: 1.578055ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:56:21.372201Z","caller":"traceutil/trace.go:171","msg":"trace[2139088152] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"366.843453ms","start":"2026-04-02T13:56:21.00532Z","end":"2026-04-02T13:56:21.372164Z","steps":["trace[2139088152] 'process raft request' (duration: 365.461407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:21.37238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:21.005292Z","time spent":"366.99593ms","remote":"127.0.0.1:44458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:56:21.37268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.341516ms","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-04-02T13:56:21.372934Z","caller":"traceutil/trace.go:171","msg":"trace[244511556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"136.508014ms","start":"2026-04-02T13:56:21.23627Z","end":"2026-04-02T13:56:21.372778Z","steps":["trace[244511556] 'agreement among raft nodes before linearized reading' (duration: 135.915236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:23.184766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523806664s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T13:56:23.184882Z","caller":"traceutil/trace.go:171","msg":"trace[1682690411] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"1.789902853s","start":"2026-04-02T13:56:21.394964Z","end":"2026-04-02T13:56:23.184867Z","steps":["trace[1682690411] 'read index received' (duration: 266.087019ms)","trace[1682690411] 'applied index is now lower than readState.Index' (duration: 1.523814884s)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:56:23.185164Z","caller":"traceutil/trace.go:171","msg":"trace[850267420] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.807587954s","start":"2026-04-02T13:56:21.377545Z","end":"2026-04-02T13:56:23.185133Z","steps":["trace[850267420] 'process raft request' (duration: 283.34993ms)","trace[850267420] 'compare' (duration: 1.52371798s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:56:23.185261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790283551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T13:56:23.18528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:21.377513Z","time spent":"1.807708361s","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T13:56:23.185301Z","caller":"traceutil/trace.go:171","msg":"trace[115212269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"1.790389646s","start":"2026-04-02T13:56:21.394899Z","end":"2026-04-02T13:56:23.185288Z","steps":["trace[115212269] 'agreement among raft nodes before linearized reading' (duration: 1.790230948s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:23.185338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:21.394882Z","time spent":"1.790450679s","remote":"127.0.0.1:44628","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-04-02T13:56:23.362783Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.701773571s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T13:56:23.376618Z","caller":"traceutil/trace.go:171","msg":"trace[2063710245] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"191.682373ms","start":"2026-04-02T13:56:23.1849Z","end":"2026-04-02T13:56:23.376583Z","steps":["trace[2063710245] 'read index received' (duration: 178.129934ms)","trace[2063710245] 'applied index is now lower than readState.Index' (duration: 13.550469ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:56:23.37664Z","caller":"traceutil/trace.go:171","msg":"trace[430367462] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.949442577s","start":"2026-04-02T13:56:21.427166Z","end":"2026-04-02T13:56:23.376608Z","steps":["trace[430367462] 'process raft request' (duration: 1.93593242s)","trace[430367462] 'compare' (duration: 13.276076ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:56:23.37675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469396049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:56:23.376783Z","caller":"traceutil/trace.go:171","msg":"trace[411973188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"1.469482913s","start":"2026-04-02T13:56:21.907292Z","end":"2026-04-02T13:56:23.376775Z","steps":["trace[411973188] 'agreement among raft nodes before linearized reading' (duration: 1.469400379s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:23.376827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:21.907265Z","time spent":"1.469556017s","remote":"127.0.0.1:44388","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-04-02T13:56:23.376881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:21.427124Z","time spent":"1.949602145s","remote":"127.0.0.1:44608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T13:56:23.376952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862586465s","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-04-02T13:56:23.377004Z","caller":"traceutil/trace.go:171","msg":"trace[1422662025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.862639877s","start":"2026-04-02T13:56:21.514351Z","end":"2026-04-02T13:56:23.376991Z","steps":["trace[1422662025] 'agreement among raft nodes before linearized reading' (duration: 1.86248583s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:23.377042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:21.514286Z","time spent":"1.862748962s","remote":"127.0.0.1:44628","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-04-02T13:56:23.377069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862808615s","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-04-02T13:56:23.377091Z","caller":"traceutil/trace.go:171","msg":"trace[740872717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.862883838s","start":"2026-04-02T13:56:21.5142Z","end":"2026-04-02T13:56:23.377084Z","steps":["trace[740872717] 'agreement among raft nodes before linearized reading' (duration: 1.862819715s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:23.377116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:21.514175Z","time spent":"1.862936952s","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T13:56:23.879547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764449293968030,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T13:56:25.228101Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.206836963s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T13:56:25.229001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207359928s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T13:56:25.229446Z","caller":"traceutil/trace.go:171","msg":"trace[687268835] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:676; }","duration":"1.850839938s","start":"2026-04-02T13:56:23.378587Z","end":"2026-04-02T13:56:25.229427Z","steps":["trace[687268835] 'read index received' (duration: 642.670774ms)","trace[687268835] 'applied index is now lower than readState.Index' (duration: 1.208168324s)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:56:25.229433Z","caller":"traceutil/trace.go:171","msg":"trace[1245812757] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"2.04034399s","start":"2026-04-02T13:56:23.18904Z","end":"2026-04-02T13:56:25.229384Z","steps":["trace[1245812757] 'process raft request' (duration: 832.300102ms)","trace[1245812757] 'compare' (duration: 1.206653825s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T13:56:25.229741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:23.189022Z","time spent":"2.040505828s","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T13:56:25.229685Z","caller":"traceutil/trace.go:171","msg":"trace[667266218] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.8478828s","start":"2026-04-02T13:56:23.381784Z","end":"2026-04-02T13:56:25.229667Z","steps":["trace[667266218] 'process raft request' (duration: 1.847517663s)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:25.22996Z","caller":"traceutil/trace.go:171","msg":"trace[1167818383] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.847317133s","start":"2026-04-02T13:56:23.382627Z","end":"2026-04-02T13:56:25.229944Z","steps":["trace[1167818383] 'process raft request' (duration: 1.846763878s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:25.230055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:23.382586Z","time spent":"1.847414718s","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:56:25.230124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:23.381771Z","time spent":"1.848226916s","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:56:25.230332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851739148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:56:25.230375Z","caller":"traceutil/trace.go:171","msg":"trace[1567177026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"1.851826802s","start":"2026-04-02T13:56:23.378537Z","end":"2026-04-02T13:56:25.230364Z","steps":["trace[1567177026] 'agreement among raft nodes before linearized reading' (duration: 1.851755629s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:25.230404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:23.378506Z","time spent":"1.851892115s","remote":"127.0.0.1:44392","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-04-02T13:56:25.234734Z","caller":"traceutil/trace.go:171","msg":"trace[1669885262] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"323.480454ms","start":"2026-04-02T13:56:24.911234Z","end":"2026-04-02T13:56:25.234714Z","steps":["trace[1669885262] 'process raft request' (duration: 323.341137ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:25.23484Z","caller":"traceutil/trace.go:171","msg":"trace[687784209] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"121.586045ms","start":"2026-04-02T13:56:25.11323Z","end":"2026-04-02T13:56:25.234816Z","steps":["trace[687784209] 'process raft request' (duration: 121.441359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:25.234889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853579129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-02T13:56:25.23492Z","caller":"traceutil/trace.go:171","msg":"trace[1323980344] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:654; }","duration":"1.853645702s","start":"2026-04-02T13:56:23.381266Z","end":"2026-04-02T13:56:25.234912Z","steps":["trace[1323980344] 'agreement among raft nodes before linearized reading' (duration: 1.853524236s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:25.234947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:23.381247Z","time spent":"1.853695614s","remote":"127.0.0.1:44570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-02T13:56:25.234861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:24.911207Z","time spent":"323.588208ms","remote":"127.0.0.1:44628","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-04-02T13:56:27.172758Z","caller":"traceutil/trace.go:171","msg":"trace[1958605361] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"193.37209ms","start":"2026-04-02T13:56:26.979361Z","end":"2026-04-02T13:56:27.172733Z","steps":["trace[1958605361] 'process raft request' (duration: 193.205553ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T13:56:41.254634Z","caller":"traceutil/trace.go:171","msg":"trace[1954802268] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"347.168784ms","start":"2026-04-02T13:56:40.907437Z","end":"2026-04-02T13:56:41.254606Z","steps":["trace[1954802268] 'read index received' (duration: 347.06671ms)","trace[1954802268] 'applied index is now lower than readState.Index' (duration: 100.854µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:56:41.254802Z","caller":"traceutil/trace.go:171","msg":"trace[663476146] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"385.8007ms","start":"2026-04-02T13:56:40.868988Z","end":"2026-04-02T13:56:41.254789Z","steps":["trace[663476146] 'process raft request' (duration: 385.380714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:41.255065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.57424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T13:56:41.255248Z","caller":"traceutil/trace.go:171","msg":"trace[1567206454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"347.83948ms","start":"2026-04-02T13:56:40.90738Z","end":"2026-04-02T13:56:41.25522Z","steps":["trace[1567206454] 'agreement among raft nodes before linearized reading' (duration: 347.540038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:41.255153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:40.868962Z","time spent":"385.870273ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:56:41.255319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:40.90736Z","time spent":"347.945154ms","remote":"127.0.0.1:44388","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-04-02T13:56:41.255668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.50161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4tfkc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-02T13:56:41.25577Z","caller":"traceutil/trace.go:171","msg":"trace[1371781143] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4tfkc; range_end:; response_count:1; response_revision:808; }","duration":"301.637015ms","start":"2026-04-02T13:56:40.954106Z","end":"2026-04-02T13:56:41.255743Z","steps":["trace[1371781143] 'agreement among raft nodes before linearized reading' (duration: 300.785923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:41.255888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:40.954091Z","time spent":"301.78255ms","remote":"127.0.0.1:44548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4tfkc\" "} {"level":"warn","ts":"2026-04-02T13:56:41.807001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764449293968352,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T13:56:42.125257Z","caller":"traceutil/trace.go:171","msg":"trace[1665984707] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"818.732047ms","start":"2026-04-02T13:56:41.306506Z","end":"2026-04-02T13:56:42.125238Z","steps":["trace[1665984707] 'read index received' (duration: 818.53865ms)","trace[1665984707] 'applied index is now lower than readState.Index' (duration: 192.797µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T13:56:42.125368Z","caller":"traceutil/trace.go:171","msg":"trace[986656660] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"859.85022ms","start":"2026-04-02T13:56:41.265467Z","end":"2026-04-02T13:56:42.125317Z","steps":["trace[986656660] 'process raft request' (duration: 859.625562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:42.125428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.914534ms","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-04-02T13:56:42.125499Z","caller":"traceutil/trace.go:171","msg":"trace[2060886042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"819.021558ms","start":"2026-04-02T13:56:41.306467Z","end":"2026-04-02T13:56:42.125489Z","steps":["trace[2060886042] 'agreement among raft nodes before linearized reading' (duration: 818.868652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:42.125564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:41.306445Z","time spent":"819.0831ms","remote":"127.0.0.1:44628","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-04-02T13:56:42.125612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:41.265434Z","time spent":"860.056499ms","remote":"127.0.0.1:44548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T13:56:42.125673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.939109ms","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-04-02T13:56:42.125709Z","caller":"traceutil/trace.go:171","msg":"trace[583826757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"807.994352ms","start":"2026-04-02T13:56:41.317704Z","end":"2026-04-02T13:56:42.125698Z","steps":["trace[583826757] 'agreement among raft nodes before linearized reading' (duration: 807.907228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:42.125728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:41.317694Z","time spent":"808.029804ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T13:56:42.125827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.796249ms","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-04-02T13:56:42.12585Z","caller":"traceutil/trace.go:171","msg":"trace[1620057538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"818.835391ms","start":"2026-04-02T13:56:41.307008Z","end":"2026-04-02T13:56:42.125843Z","steps":["trace[1620057538] 'agreement among raft nodes before linearized reading' (duration: 818.766648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:42.125869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:41.307001Z","time spent":"818.863782ms","remote":"127.0.0.1:44628","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-04-02T13:56:42.126464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.239936ms","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-04-02T13:56:42.126548Z","caller":"traceutil/trace.go:171","msg":"trace[873079273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"819.780697ms","start":"2026-04-02T13:56:41.306747Z","end":"2026-04-02T13:56:42.126528Z","steps":["trace[873079273] 'agreement among raft nodes before linearized reading' (duration: 818.858512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T13:56:42.126599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T13:56:41.30674Z","time spent":"819.846949ms","remote":"127.0.0.1:44628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}