{"level":"warn","ts":"2026-05-14T11:10:51.245981Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T11:10:51.246228Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-05-14T11:10:51.246342Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T11:10:51.246382Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-14T11:10:51.246426Z","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-05-14T11:10:51.2473Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-05-14T11:10:51.247436Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-05-14T11:10:51.282595Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"34.844903ms"} {"level":"info","ts":"2026-05-14T11:10:51.303162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-05-14T11:10:51.303257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T11:10:51.303281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-05-14T11:10:51.303296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T11:10:51.303314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-05-14T11:10:51.303353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-05-14T11:10:51.34861Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T11:10:51.351698Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T11:10:51.356525Z","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-05-14T11:10:51.365637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T11:10:51.382173Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T11:10:51.382232Z","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-05-14T11:10:51.382276Z","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-05-14T11:10:51.382297Z","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-05-14T11:10:51.382556Z","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-05-14T11:10:51.38265Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-14T11:10:51.382694Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-14T11:10:51.382831Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T11:10:51.382858Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T11:10:51.387332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-05-14T11:10:51.387529Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-14T11:10:52.303936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T11:10:52.304092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T11:10:52.304124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-05-14T11:10:52.30415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-05-14T11:10:52.304163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-14T11:10:52.304182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-05-14T11:10:52.304196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-14T11:10:52.308932Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T11:10:52.308952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T11:10:52.309104Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T11:10:52.309352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T11:10:52.309859Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T11:10:52.309915Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T11:10:52.31239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-05-14T11:10:52.312799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T11:10:52.313638Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T11:10:52.313729Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T11:10:52.313762Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-14T11:10:54.035944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.716669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-14T11:10:54.036278Z","caller":"traceutil/trace.go:171","msg":"trace[398321677] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"342.114147ms","start":"2026-05-14T11:10:53.694135Z","end":"2026-05-14T11:10:54.03625Z","steps":["trace[398321677] 'process raft request' (duration: 122.483163ms)","trace[398321677] 'compare' (duration: 218.545928ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:10:54.036384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:10:53.694121Z","time spent":"342.226109ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:10:54.036655Z","caller":"traceutil/trace.go:171","msg":"trace[1680748120] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"329.494437ms","start":"2026-05-14T11:10:53.707096Z","end":"2026-05-14T11:10:54.036591Z","steps":["trace[1680748120] 'process raft request' (duration: 329.127285ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:10:54.036797Z","caller":"traceutil/trace.go:171","msg":"trace[887646519] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"295.921796ms","start":"2026-05-14T11:10:53.740853Z","end":"2026-05-14T11:10:54.036775Z","steps":["trace[887646519] 'process raft request' (duration: 295.568423ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:10:54.036764Z","caller":"traceutil/trace.go:171","msg":"trace[215181530] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:13; }","duration":"301.701342ms","start":"2026-05-14T11:10:53.734998Z","end":"2026-05-14T11:10:54.036699Z","steps":["trace[215181530] 'read index received' (duration: 81.694526ms)","trace[215181530] 'applied index is now lower than readState.Index' (duration: 219.993006ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:10:54.036936Z","caller":"traceutil/trace.go:171","msg":"trace[808328303] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"295.661715ms","start":"2026-05-14T11:10:53.741233Z","end":"2026-05-14T11:10:54.036894Z","steps":["trace[808328303] 'process raft request' (duration: 295.408863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:54.037199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.491942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-14T11:10:54.036836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:10:53.707081Z","time spent":"329.678008ms","remote":"127.0.0.1:59116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:10:54.037267Z","caller":"traceutil/trace.go:171","msg":"trace[865977928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14; }","duration":"331.657812ms","start":"2026-05-14T11:10:53.705591Z","end":"2026-05-14T11:10:54.037248Z","steps":["trace[865977928] 'agreement among raft nodes before linearized reading' (duration: 331.477341ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:10:54.037169Z","caller":"traceutil/trace.go:171","msg":"trace[179749416] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"295.818416ms","start":"2026-05-14T11:10:53.741332Z","end":"2026-05-14T11:10:54.03715Z","steps":["trace[179749416] 'process raft request' (duration: 295.368192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:54.037375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.951244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-05-14T11:10:54.037302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:10:53.705576Z","time spent":"331.718143ms","remote":"127.0.0.1:58604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T11:10:54.037442Z","caller":"traceutil/trace.go:171","msg":"trace[1330634193] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:14; }","duration":"313.271876ms","start":"2026-05-14T11:10:53.724147Z","end":"2026-05-14T11:10:54.037419Z","steps":["trace[1330634193] 'agreement among raft nodes before linearized reading' (duration: 312.818224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:54.03805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:10:53.724133Z","time spent":"313.8803ms","remote":"127.0.0.1:58704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-14T11:10:54.037486Z","caller":"traceutil/trace.go:171","msg":"trace[598108287] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"295.423534ms","start":"2026-05-14T11:10:53.742052Z","end":"2026-05-14T11:10:54.037475Z","steps":["trace[598108287] 'process raft request' (duration: 294.674288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:54.037535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.698062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-14T11:10:54.038482Z","caller":"traceutil/trace.go:171","msg":"trace[289076540] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:14; }","duration":"351.676578ms","start":"2026-05-14T11:10:53.686795Z","end":"2026-05-14T11:10:54.038471Z","steps":["trace[289076540] 'agreement among raft nodes before linearized reading' (duration: 350.226609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:54.038522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:10:53.686785Z","time spent":"351.727658ms","remote":"127.0.0.1:58704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-14T11:10:54.037826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.528716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T11:10:54.038667Z","caller":"traceutil/trace.go:171","msg":"trace[1968934207] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:18; }","duration":"298.384751ms","start":"2026-05-14T11:10:53.740273Z","end":"2026-05-14T11:10:54.038658Z","steps":["trace[1968934207] 'agreement among raft nodes before linearized reading' (duration: 297.482085ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:10:54.037872Z","caller":"traceutil/trace.go:171","msg":"trace[1833468240] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"295.254183ms","start":"2026-05-14T11:10:53.742602Z","end":"2026-05-14T11:10:54.037856Z","steps":["trace[1833468240] 'process raft request' (duration: 295.110692ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:10:54.037896Z","caller":"traceutil/trace.go:171","msg":"trace[1950931485] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"295.403404ms","start":"2026-05-14T11:10:53.742422Z","end":"2026-05-14T11:10:54.037825Z","steps":["trace[1950931485] 'process raft request' (duration: 295.238083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:54.037917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.585182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-14T11:10:54.039043Z","caller":"traceutil/trace.go:171","msg":"trace[2015761915] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"283.730239ms","start":"2026-05-14T11:10:53.755303Z","end":"2026-05-14T11:10:54.039033Z","steps":["trace[2015761915] 'agreement among raft nodes before linearized reading' (duration: 282.590912ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:10:54.037958Z","caller":"traceutil/trace.go:171","msg":"trace[1352561485] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"295.593845ms","start":"2026-05-14T11:10:53.742356Z","end":"2026-05-14T11:10:54.037949Z","steps":["trace[1352561485] 'process raft request' (duration: 295.030741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:54.041349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.465286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T11:10:54.042208Z","caller":"traceutil/trace.go:171","msg":"trace[1026094794] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:18; }","duration":"352.332331ms","start":"2026-05-14T11:10:53.689816Z","end":"2026-05-14T11:10:54.042148Z","steps":["trace[1026094794] 'agreement among raft nodes before linearized reading' (duration: 351.475666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:54.042282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:10:53.689802Z","time spent":"352.459452ms","remote":"127.0.0.1:59000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-14T11:10:55.205484Z","caller":"traceutil/trace.go:171","msg":"trace[1722634820] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"104.728842ms","start":"2026-05-14T11:10:55.100727Z","end":"2026-05-14T11:10:55.205456Z","steps":["trace[1722634820] 'compare' (duration: 98.499593ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:10:55.37602Z","caller":"traceutil/trace.go:171","msg":"trace[1520257824] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"113.291635ms","start":"2026-05-14T11:10:55.2627Z","end":"2026-05-14T11:10:55.375992Z","steps":["trace[1520257824] 'process raft request' (duration: 103.172642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:55.853728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.034467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T11:10:55.853819Z","caller":"traceutil/trace.go:171","msg":"trace[108161562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:224; }","duration":"112.153367ms","start":"2026-05-14T11:10:55.741639Z","end":"2026-05-14T11:10:55.853793Z","steps":["trace[108161562] 'range keys from in-memory index tree' (duration: 111.972626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:55.854122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.815573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-14T11:10:55.854207Z","caller":"traceutil/trace.go:171","msg":"trace[1656105271] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:224; }","duration":"188.932745ms","start":"2026-05-14T11:10:55.665251Z","end":"2026-05-14T11:10:55.854184Z","steps":["trace[1656105271] 'range keys from in-memory index tree' (duration: 188.713822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:10:56.061401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.96478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T11:10:56.061516Z","caller":"traceutil/trace.go:171","msg":"trace[432051084] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"140.946863ms","start":"2026-05-14T11:10:55.920548Z","end":"2026-05-14T11:10:56.061495Z","steps":["trace[432051084] 'process raft request' (duration: 38.754861ms)","trace[432051084] 'compare' (duration: 101.86865ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:10:56.175211Z","caller":"traceutil/trace.go:171","msg":"trace[1370795484] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"106.086255ms","start":"2026-05-14T11:10:56.069092Z","end":"2026-05-14T11:10:56.175178Z","steps":["trace[1370795484] 'process raft request' (duration: 104.828286ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:04.862225Z","caller":"traceutil/trace.go:171","msg":"trace[1266149068] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"423.785449ms","start":"2026-05-14T11:11:04.438408Z","end":"2026-05-14T11:11:04.862193Z","steps":["trace[1266149068] 'read index received' (duration: 423.602738ms)","trace[1266149068] 'applied index is now lower than readState.Index' (duration: 180.861µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:11:04.862269Z","caller":"traceutil/trace.go:171","msg":"trace[1510344481] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"428.396096ms","start":"2026-05-14T11:11:04.433837Z","end":"2026-05-14T11:11:04.862233Z","steps":["trace[1510344481] 'process raft request' (duration: 428.212095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:04.862501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.03633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-14T11:11:04.862558Z","caller":"traceutil/trace.go:171","msg":"trace[547383254] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:395; }","duration":"253.126541ms","start":"2026-05-14T11:11:04.609406Z","end":"2026-05-14T11:11:04.862532Z","steps":["trace[547383254] 'agreement among raft nodes before linearized reading' (duration: 253.04638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:04.862657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:04.433819Z","time spent":"428.525588ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T11:11:04.862996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.98371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T11:11:04.86303Z","caller":"traceutil/trace.go:171","msg":"trace[10083476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:395; }","duration":"424.702184ms","start":"2026-05-14T11:11:04.438318Z","end":"2026-05-14T11:11:04.86302Z","steps":["trace[10083476] 'agreement among raft nodes before linearized reading' (duration: 424.017761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:04.863061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:04.438245Z","time spent":"424.799535ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T11:11:04.971792Z","caller":"traceutil/trace.go:171","msg":"trace[145720200] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"101.234742ms","start":"2026-05-14T11:11:04.870538Z","end":"2026-05-14T11:11:04.971772Z","steps":["trace[145720200] 'process raft request' (duration: 101.123421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:05.563146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869390738238069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T11:11:06.063828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869390738238069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T11:11:06.293017Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.310375061s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-14T11:11:07.115946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.647933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T11:11:07.116053Z","caller":"traceutil/trace.go:171","msg":"trace[1472102496] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"2.053666243s","start":"2026-05-14T11:11:05.06237Z","end":"2026-05-14T11:11:07.116036Z","steps":["trace[1472102496] 'read index received' (duration: 1.230787879s)","trace[1472102496] 'applied index is now lower than readState.Index' (duration: 822.877364ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:11:07.116083Z","caller":"traceutil/trace.go:171","msg":"trace[1986283041] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"2.133523077s","start":"2026-05-14T11:11:04.982537Z","end":"2026-05-14T11:11:07.11606Z","steps":["trace[1986283041] 'process raft request' (duration: 1.310672453s)","trace[1986283041] 'compare' (duration: 822.511332ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:11:07.116182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095722412s","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-05-14T11:11:07.116212Z","caller":"traceutil/trace.go:171","msg":"trace[2139942968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:398; }","duration":"1.095769714s","start":"2026-05-14T11:11:06.020432Z","end":"2026-05-14T11:11:07.116201Z","steps":["trace[2139942968] 'agreement among raft nodes before linearized reading' (duration: 1.095696013s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:07.116194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:04.982518Z","time spent":"2.133617518s","remote":"127.0.0.1:58774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T11:11:07.116254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:06.020375Z","time spent":"1.095871554s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T11:11:07.116274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.5115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-14T11:11:07.116289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.805225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-14T11:11:07.11636Z","caller":"traceutil/trace.go:171","msg":"trace[1393960774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:398; }","duration":"154.648782ms","start":"2026-05-14T11:11:06.961689Z","end":"2026-05-14T11:11:07.116338Z","steps":["trace[1393960774] 'agreement among raft nodes before linearized reading' (duration: 154.46486ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:07.116373Z","caller":"traceutil/trace.go:171","msg":"trace[108177302] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:398; }","duration":"772.946906ms","start":"2026-05-14T11:11:06.343405Z","end":"2026-05-14T11:11:07.116352Z","steps":["trace[108177302] 'agreement among raft nodes before linearized reading' (duration: 772.793285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:07.11638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.138508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-14T11:11:07.116418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:06.343383Z","time spent":"773.026216ms","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"info","ts":"2026-05-14T11:11:07.116456Z","caller":"traceutil/trace.go:171","msg":"trace[1669958882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:398; }","duration":"837.260218ms","start":"2026-05-14T11:11:06.27917Z","end":"2026-05-14T11:11:07.11643Z","steps":["trace[1669958882] 'agreement among raft nodes before linearized reading' (duration: 837.091707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:07.116514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:06.279138Z","time spent":"837.362389ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T11:11:07.116895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.054492898s","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-05-14T11:11:07.116946Z","caller":"traceutil/trace.go:171","msg":"trace[1011614689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"2.054646759s","start":"2026-05-14T11:11:05.062284Z","end":"2026-05-14T11:11:07.116931Z","steps":["trace[1011614689] 'agreement among raft nodes before linearized reading' (duration: 2.053835064s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:07.11714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:05.06226Z","time spent":"2.054865521s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T11:11:13.746518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.581352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T11:11:13.746726Z","caller":"traceutil/trace.go:171","msg":"trace[604383190] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"568.053957ms","start":"2026-05-14T11:11:13.178653Z","end":"2026-05-14T11:11:13.746707Z","steps":["trace[604383190] 'process raft request' (duration: 567.981676ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:13.746998Z","caller":"traceutil/trace.go:171","msg":"trace[300945118] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"571.552278ms","start":"2026-05-14T11:11:13.175421Z","end":"2026-05-14T11:11:13.746974Z","steps":["trace[300945118] 'read index received' (duration: 23.261401ms)","trace[300945118] 'applied index is now lower than readState.Index' (duration: 548.288227ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:11:13.747017Z","caller":"traceutil/trace.go:171","msg":"trace[1314989476] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"583.720385ms","start":"2026-05-14T11:11:13.163269Z","end":"2026-05-14T11:11:13.746989Z","steps":["trace[1314989476] 'process raft request' (duration: 35.399828ms)","trace[1314989476] 'compare' (duration: 547.301741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:11:13.74714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:13.163231Z","time spent":"583.838806ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T11:11:13.747174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.348914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T11:11:13.747205Z","caller":"traceutil/trace.go:171","msg":"trace[1336070380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"585.405044ms","start":"2026-05-14T11:11:13.161789Z","end":"2026-05-14T11:11:13.747194Z","steps":["trace[1336070380] 'agreement among raft nodes before linearized reading' (duration: 585.302924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:13.747235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:13.161779Z","time spent":"585.449624ms","remote":"127.0.0.1:58860","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-05-14T11:11:13.747215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.112831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T11:11:13.747275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.295511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-05-14T11:11:13.747291Z","caller":"traceutil/trace.go:171","msg":"trace[1142631861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"572.215272ms","start":"2026-05-14T11:11:13.175056Z","end":"2026-05-14T11:11:13.747271Z","steps":["trace[1142631861] 'agreement among raft nodes before linearized reading' (duration: 572.00024ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:13.747317Z","caller":"traceutil/trace.go:171","msg":"trace[197781557] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:420; }","duration":"432.378762ms","start":"2026-05-14T11:11:13.314925Z","end":"2026-05-14T11:11:13.747304Z","steps":["trace[197781557] 'agreement among raft nodes before linearized reading' (duration: 432.302771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:13.747333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:13.175049Z","time spent":"572.276392ms","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T11:11:13.747351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:13.314903Z","time spent":"432.442372ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-14T11:11:13.747897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:13.178634Z","time spent":"568.149047ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T11:11:24.155941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.075869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T11:11:24.156057Z","caller":"traceutil/trace.go:171","msg":"trace[1313610030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"221.211266ms","start":"2026-05-14T11:11:23.934792Z","end":"2026-05-14T11:11:24.156004Z","steps":["trace[1313610030] 'range keys from in-memory index tree' (duration: 220.964283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.609344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869390738238318,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T11:11:28.897974Z","caller":"traceutil/trace.go:171","msg":"trace[1540279196] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"811.840124ms","start":"2026-05-14T11:11:28.085812Z","end":"2026-05-14T11:11:28.897652Z","steps":["trace[1540279196] 'process raft request' (duration: 811.694996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.898193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.085797Z","time spent":"812.314188ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:11:28.908426Z","caller":"traceutil/trace.go:171","msg":"trace[207055196] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"822.192221ms","start":"2026-05-14T11:11:28.086206Z","end":"2026-05-14T11:11:28.908398Z","steps":["trace[207055196] 'process raft request' (duration: 821.893325ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:28.908493Z","caller":"traceutil/trace.go:171","msg":"trace[194847792] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"810.03072ms","start":"2026-05-14T11:11:28.09841Z","end":"2026-05-14T11:11:28.908441Z","steps":["trace[194847792] 'process raft request' (duration: 809.854721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.908674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.098389Z","time spent":"810.195408ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T11:11:28.908702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.086181Z","time spent":"822.324607ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:11:28.908881Z","caller":"traceutil/trace.go:171","msg":"trace[780547418] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"799.247533ms","start":"2026-05-14T11:11:28.109504Z","end":"2026-05-14T11:11:28.908751Z","steps":["trace[780547418] 'process raft request' (duration: 798.864763ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:28.908528Z","caller":"traceutil/trace.go:171","msg":"trace[1064229538] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"799.906456ms","start":"2026-05-14T11:11:28.108564Z","end":"2026-05-14T11:11:28.90847Z","steps":["trace[1064229538] 'process raft request' (duration: 799.752508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.909294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.108551Z","time spent":"800.611853ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T11:11:28.908968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.109497Z","time spent":"799.427512ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:11:28.909483Z","caller":"traceutil/trace.go:171","msg":"trace[2075449402] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"793.41376ms","start":"2026-05-14T11:11:28.116002Z","end":"2026-05-14T11:11:28.909416Z","steps":["trace[2075449402] 'process raft request' (duration: 793.147606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.909536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.115994Z","time spent":"793.518745ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:11:28.909581Z","caller":"traceutil/trace.go:171","msg":"trace[1717603064] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"787.131065ms","start":"2026-05-14T11:11:28.122431Z","end":"2026-05-14T11:11:28.909562Z","steps":["trace[1717603064] 'process raft request' (duration: 786.891032ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:28.909645Z","caller":"traceutil/trace.go:171","msg":"trace[1762292940] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"782.99393ms","start":"2026-05-14T11:11:28.126635Z","end":"2026-05-14T11:11:28.909629Z","steps":["trace[1762292940] 'process raft request' (duration: 782.757758ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:28.909763Z","caller":"traceutil/trace.go:171","msg":"trace[1105712465] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"783.026291ms","start":"2026-05-14T11:11:28.126728Z","end":"2026-05-14T11:11:28.909754Z","steps":["trace[1105712465] 'process raft request' (duration: 782.721726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.909816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.126723Z","time spent":"783.069593ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:11:28.909842Z","caller":"traceutil/trace.go:171","msg":"trace[1716432278] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"757.173124ms","start":"2026-05-14T11:11:28.152626Z","end":"2026-05-14T11:11:28.909799Z","steps":["trace[1716432278] 'process raft request' (duration: 756.91299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.909761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.126605Z","time spent":"783.116976ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T11:11:28.909647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.122421Z","time spent":"787.192208ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T11:11:28.909929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.152605Z","time spent":"757.269149ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:11:28.910002Z","caller":"traceutil/trace.go:171","msg":"trace[801317106] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"647.453902ms","start":"2026-05-14T11:11:28.262535Z","end":"2026-05-14T11:11:28.909989Z","steps":["trace[801317106] 'process raft request' (duration: 647.20636ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:28.910054Z","caller":"traceutil/trace.go:171","msg":"trace[550072795] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"409.498283ms","start":"2026-05-14T11:11:28.500512Z","end":"2026-05-14T11:11:28.910011Z","steps":["trace[550072795] 'process raft request' (duration: 409.293322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.910132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.500486Z","time spent":"409.601128ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T11:11:28.910155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.262497Z","time spent":"647.583049ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:11:28.909933Z","caller":"traceutil/trace.go:171","msg":"trace[599524774] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"752.013356ms","start":"2026-05-14T11:11:28.157892Z","end":"2026-05-14T11:11:28.909905Z","steps":["trace[599524774] 'process raft request' (duration: 751.70184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.91023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.157843Z","time spent":"752.363985ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T11:11:28.910321Z","caller":"traceutil/trace.go:171","msg":"trace[630946529] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:537; }","duration":"801.779863ms","start":"2026-05-14T11:11:28.10853Z","end":"2026-05-14T11:11:28.91031Z","steps":["trace[630946529] 'read index received' (duration: 789.376051ms)","trace[630946529] 'applied index is now lower than readState.Index' (duration: 12.402372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:11:28.910408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.873918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T11:11:28.910437Z","caller":"traceutil/trace.go:171","msg":"trace[224636227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"801.9262ms","start":"2026-05-14T11:11:28.108502Z","end":"2026-05-14T11:11:28.910428Z","steps":["trace[224636227] 'agreement among raft nodes before linearized reading' (duration: 801.869817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.910507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.108486Z","time spent":"802.014965ms","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T11:11:28.91619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.938259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T11:11:28.916233Z","caller":"traceutil/trace.go:171","msg":"trace[656347590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"722.030523ms","start":"2026-05-14T11:11:28.194194Z","end":"2026-05-14T11:11:28.916225Z","steps":["trace[656347590] 'agreement among raft nodes before linearized reading' (duration: 721.950619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:28.916253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:28.194172Z","time spent":"722.077085ms","remote":"127.0.0.1:58860","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-05-14T11:11:35.598482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869390738238559,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T11:11:35.694066Z","caller":"traceutil/trace.go:171","msg":"trace[53706693] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"636.00072ms","start":"2026-05-14T11:11:35.05801Z","end":"2026-05-14T11:11:35.694011Z","steps":["trace[53706693] 'process raft request' (duration: 629.636208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:35.694229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.057987Z","time spent":"636.160307ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T11:11:35.753029Z","caller":"traceutil/trace.go:171","msg":"trace[271786798] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:647; }","duration":"655.331077ms","start":"2026-05-14T11:11:35.097648Z","end":"2026-05-14T11:11:35.752979Z","steps":["trace[271786798] 'read index received' (duration: 589.084333ms)","trace[271786798] 'applied index is now lower than readState.Index' (duration: 66.245834ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:11:35.753062Z","caller":"traceutil/trace.go:171","msg":"trace[1363736671] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"666.330739ms","start":"2026-05-14T11:11:35.086711Z","end":"2026-05-14T11:11:35.753041Z","steps":["trace[1363736671] 'process raft request' (duration: 666.107128ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:35.753478Z","caller":"traceutil/trace.go:171","msg":"trace[1882372175] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"270.266604ms","start":"2026-05-14T11:11:35.483176Z","end":"2026-05-14T11:11:35.753443Z","steps":["trace[1882372175] 'process raft request' (duration: 269.76185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:35.753507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.08667Z","time spent":"666.746028ms","remote":"127.0.0.1:58774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T11:11:35.753107Z","caller":"traceutil/trace.go:171","msg":"trace[181591710] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"676.100802ms","start":"2026-05-14T11:11:35.076999Z","end":"2026-05-14T11:11:35.753099Z","steps":["trace[181591710] 'process raft request' (duration: 675.681983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:35.753709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.076978Z","time spent":"676.641698ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T11:11:35.753745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.54101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T11:11:35.754005Z","caller":"traceutil/trace.go:171","msg":"trace[362205838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"469.871195ms","start":"2026-05-14T11:11:35.284116Z","end":"2026-05-14T11:11:35.753987Z","steps":["trace[362205838] 'agreement among raft nodes before linearized reading' (duration: 469.401483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:35.753893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.139865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T11:11:35.754159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.284092Z","time spent":"470.051534ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T11:11:35.754362Z","caller":"traceutil/trace.go:171","msg":"trace[585469956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"656.575935ms","start":"2026-05-14T11:11:35.097602Z","end":"2026-05-14T11:11:35.754178Z","steps":["trace[585469956] 'agreement among raft nodes before linearized reading' (duration: 655.964006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:35.75503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.097582Z","time spent":"657.428466ms","remote":"127.0.0.1:58604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T11:11:36.376951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869390738238565,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T11:11:36.557901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.645958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T11:11:36.558198Z","caller":"traceutil/trace.go:171","msg":"trace[1026262551] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"681.851032ms","start":"2026-05-14T11:11:35.876319Z","end":"2026-05-14T11:11:36.55817Z","steps":["trace[1026262551] 'read index received' (duration: 580.860808ms)","trace[1026262551] 'applied index is now lower than readState.Index' (duration: 100.989344ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:11:36.558301Z","caller":"traceutil/trace.go:171","msg":"trace[949578930] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"798.407484ms","start":"2026-05-14T11:11:35.759883Z","end":"2026-05-14T11:11:36.558291Z","steps":["trace[949578930] 'process raft request' (duration: 798.189214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:36.55839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.75986Z","time spent":"798.479867ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T11:11:36.55864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.792229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T11:11:36.558678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.093543ms","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-05-14T11:11:36.558806Z","caller":"traceutil/trace.go:171","msg":"trace[2072333949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"460.935006ms","start":"2026-05-14T11:11:36.097818Z","end":"2026-05-14T11:11:36.558753Z","steps":["trace[2072333949] 'agreement among raft nodes before linearized reading' (duration: 460.749667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:36.558891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:36.097769Z","time spent":"461.109804ms","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T11:11:36.55884Z","caller":"traceutil/trace.go:171","msg":"trace[1019279004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"635.260982ms","start":"2026-05-14T11:11:35.923527Z","end":"2026-05-14T11:11:36.558788Z","steps":["trace[1019279004] 'agreement among raft nodes before linearized reading' (duration: 634.986488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:36.55922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.923505Z","time spent":"635.701092ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T11:11:36.558644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.678735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-14T11:11:36.559341Z","caller":"traceutil/trace.go:171","msg":"trace[1386981275] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:627; }","duration":"214.409121ms","start":"2026-05-14T11:11:36.344916Z","end":"2026-05-14T11:11:36.559325Z","steps":["trace[1386981275] 'agreement among raft nodes before linearized reading' (duration: 213.641754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:36.558669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.347286ms","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-05-14T11:11:36.55951Z","caller":"traceutil/trace.go:171","msg":"trace[1859827971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"683.220107ms","start":"2026-05-14T11:11:35.876276Z","end":"2026-05-14T11:11:36.559496Z","steps":["trace[1859827971] 'agreement among raft nodes before linearized reading' (duration: 682.286223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:36.559585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.876254Z","time spent":"683.315951ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T11:11:36.558678Z","caller":"traceutil/trace.go:171","msg":"trace[834351702] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"800.641359ms","start":"2026-05-14T11:11:35.758002Z","end":"2026-05-14T11:11:36.558643Z","steps":["trace[834351702] 'process raft request' (duration: 699.178933ms)","trace[834351702] 'compare' (duration: 100.184307ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:11:36.559707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:35.757982Z","time spent":"801.673258ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T11:11:37.599731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869390738238576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T11:11:38.100102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869390738238576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T11:11:38.315618Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.04887503s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T11:11:38.368846Z","caller":"traceutil/trace.go:171","msg":"trace[564547704] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.796345964s","start":"2026-05-14T11:11:36.572473Z","end":"2026-05-14T11:11:38.368819Z","steps":["trace[564547704] 'process raft request' (duration: 1.743319329s)","trace[564547704] 'compare' (duration: 52.91617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:11:38.368991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:36.572457Z","time spent":"1.796456219s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T11:11:38.396123Z","caller":"traceutil/trace.go:171","msg":"trace[130037928] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.398523075s","start":"2026-05-14T11:11:36.997567Z","end":"2026-05-14T11:11:38.39609Z","steps":["trace[130037928] 'process raft request' (duration: 1.397917028s)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:11:38.396253Z","caller":"traceutil/trace.go:171","msg":"trace[1682743174] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"1.296939039s","start":"2026-05-14T11:11:37.099293Z","end":"2026-05-14T11:11:38.396232Z","steps":["trace[1682743174] 'read index received' (duration: 1.216555313s)","trace[1682743174] 'applied index is now lower than readState.Index' (duration: 80.381436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:11:38.39641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297113398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T11:11:38.396464Z","caller":"traceutil/trace.go:171","msg":"trace[2018809408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"1.297208722s","start":"2026-05-14T11:11:37.099239Z","end":"2026-05-14T11:11:38.396447Z","steps":["trace[2018809408] 'agreement among raft nodes before linearized reading' (duration: 1.297121568s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:38.396511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:37.099216Z","time spent":"1.297286727s","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T11:11:38.396617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.857716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T11:11:38.396679Z","caller":"traceutil/trace.go:171","msg":"trace[1187338198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"636.974632ms","start":"2026-05-14T11:11:37.759693Z","end":"2026-05-14T11:11:38.396667Z","steps":["trace[1187338198] 'agreement among raft nodes before linearized reading' (duration: 636.791244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:38.396723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:37.759675Z","time spent":"637.042395ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T11:11:38.396878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:36.997534Z","time spent":"1.398657442s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T11:11:40.378848Z","caller":"traceutil/trace.go:171","msg":"trace[1267333308] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"127.825637ms","start":"2026-05-14T11:11:40.250988Z","end":"2026-05-14T11:11:40.378814Z","steps":["trace[1267333308] 'process raft request' (duration: 127.714292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:52.355408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.926206ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-14T11:11:52.355558Z","caller":"traceutil/trace.go:171","msg":"trace[400569093] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"413.232305ms","start":"2026-05-14T11:11:51.942291Z","end":"2026-05-14T11:11:52.355523Z","steps":["trace[400569093] 'read index received' (duration: 246.121502ms)","trace[400569093] 'applied index is now lower than readState.Index' (duration: 167.109023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:11:52.355819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.510545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-14T11:11:52.355799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.60928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T11:11:52.355854Z","caller":"traceutil/trace.go:171","msg":"trace[1728947091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"413.587958ms","start":"2026-05-14T11:11:51.942256Z","end":"2026-05-14T11:11:52.355844Z","steps":["trace[1728947091] 'agreement among raft nodes before linearized reading' (duration: 413.408701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:52.355891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:51.942239Z","time spent":"413.64466ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T11:11:52.355895Z","caller":"traceutil/trace.go:171","msg":"trace[280146488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"256.755725ms","start":"2026-05-14T11:11:52.099116Z","end":"2026-05-14T11:11:52.355871Z","steps":["trace[280146488] 'agreement among raft nodes before linearized reading' (duration: 256.62103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:55.245318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869390738238894,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T11:11:55.492374Z","caller":"traceutil/trace.go:171","msg":"trace[1830279752] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"748.553328ms","start":"2026-05-14T11:11:54.743757Z","end":"2026-05-14T11:11:55.49231Z","steps":["trace[1830279752] 'process raft request' (duration: 748.209135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:55.492583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:54.743726Z","time spent":"748.756076ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T11:11:55.644219Z","caller":"traceutil/trace.go:171","msg":"trace[1889886316] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"899.444806ms","start":"2026-05-14T11:11:54.744737Z","end":"2026-05-14T11:11:55.644182Z","steps":["trace[1889886316] 'read index received' (duration: 747.229969ms)","trace[1889886316] 'applied index is now lower than readState.Index' (duration: 152.213717ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:11:55.644337Z","caller":"traceutil/trace.go:171","msg":"trace[1455605313] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"502.357052ms","start":"2026-05-14T11:11:55.14197Z","end":"2026-05-14T11:11:55.644327Z","steps":["trace[1455605313] 'process raft request' (duration: 502.053371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:55.644485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:55.141928Z","time spent":"502.439064ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T11:11:55.644662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.03995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T11:11:55.644751Z","caller":"traceutil/trace.go:171","msg":"trace[2029056530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"545.196795ms","start":"2026-05-14T11:11:55.099534Z","end":"2026-05-14T11:11:55.644731Z","steps":["trace[2029056530] 'agreement among raft nodes before linearized reading' (duration: 544.890424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:55.644701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.935793ms","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-05-14T11:11:55.644805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:55.099514Z","time spent":"545.282608ms","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T11:11:55.644917Z","caller":"traceutil/trace.go:171","msg":"trace[1818596251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"900.158723ms","start":"2026-05-14T11:11:54.744693Z","end":"2026-05-14T11:11:55.644852Z","steps":["trace[1818596251] 'agreement among raft nodes before linearized reading' (duration: 899.783398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:11:55.645149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:11:54.74467Z","time spent":"900.464155ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T11:12:09.528231Z","caller":"traceutil/trace.go:171","msg":"trace[454610701] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"120.407802ms","start":"2026-05-14T11:12:09.407759Z","end":"2026-05-14T11:12:09.528167Z","steps":["trace[454610701] 'process raft request' (duration: 120.259097ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:12:14.184154Z","caller":"traceutil/trace.go:171","msg":"trace[365321941] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"127.818998ms","start":"2026-05-14T11:12:14.056312Z","end":"2026-05-14T11:12:14.184131Z","steps":["trace[365321941] 'process raft request' (duration: 127.611862ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:13:00.500928Z","caller":"traceutil/trace.go:171","msg":"trace[1709815386] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"122.109433ms","start":"2026-05-14T11:13:00.378774Z","end":"2026-05-14T11:13:00.500884Z","steps":["trace[1709815386] 'process raft request' (duration: 121.909299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:13:00.682273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.081933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T11:13:00.682732Z","caller":"traceutil/trace.go:171","msg":"trace[1116126649] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1148; }","duration":"142.643714ms","start":"2026-05-14T11:13:00.540066Z","end":"2026-05-14T11:13:00.68271Z","steps":["trace[1116126649] 'count revisions from in-memory index tree' (duration: 141.95909ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:13:00.967452Z","caller":"traceutil/trace.go:171","msg":"trace[102374768] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"157.350199ms","start":"2026-05-14T11:13:00.810068Z","end":"2026-05-14T11:13:00.967418Z","steps":["trace[102374768] 'process raft request' (duration: 157.138375ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:13:01.306452Z","caller":"traceutil/trace.go:171","msg":"trace[1923530948] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"102.741757ms","start":"2026-05-14T11:13:01.20368Z","end":"2026-05-14T11:13:01.306421Z","steps":["trace[1923530948] 'process raft request' (duration: 102.576053ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:13:26.485376Z","caller":"traceutil/trace.go:171","msg":"trace[1055146322] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"106.271646ms","start":"2026-05-14T11:13:26.379061Z","end":"2026-05-14T11:13:26.485333Z","steps":["trace[1055146322] 'process raft request' (duration: 48.758699ms)","trace[1055146322] 'compare' (duration: 57.306883ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:13:27.385528Z","caller":"traceutil/trace.go:171","msg":"trace[1875620339] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"116.292734ms","start":"2026-05-14T11:13:27.269196Z","end":"2026-05-14T11:13:27.385489Z","steps":["trace[1875620339] 'process raft request' (duration: 66.38935ms)","trace[1875620339] 'compare' (duration: 49.729571ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:13:28.249713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.902065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T11:13:28.249824Z","caller":"traceutil/trace.go:171","msg":"trace[1487165149] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1238; }","duration":"110.072909ms","start":"2026-05-14T11:13:28.139724Z","end":"2026-05-14T11:13:28.249797Z","steps":["trace[1487165149] 'count revisions from in-memory index tree' (duration: 109.830165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:13:28.249904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.719938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-14T11:13:28.250086Z","caller":"traceutil/trace.go:171","msg":"trace[454861285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"128.985664ms","start":"2026-05-14T11:13:28.121065Z","end":"2026-05-14T11:13:28.25005Z","steps":["trace[454861285] 'range keys from in-memory index tree' (duration: 128.438003ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:13:41.365644Z","caller":"traceutil/trace.go:171","msg":"trace[1511374905] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"138.58756ms","start":"2026-05-14T11:13:41.22702Z","end":"2026-05-14T11:13:41.365608Z","steps":["trace[1511374905] 'process raft request' (duration: 138.381057ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:13:49.819172Z","caller":"traceutil/trace.go:171","msg":"trace[1278980140] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"267.44341ms","start":"2026-05-14T11:13:49.551691Z","end":"2026-05-14T11:13:49.819135Z","steps":["trace[1278980140] 'read index received' (duration: 267.179857ms)","trace[1278980140] 'applied index is now lower than readState.Index' (duration: 262.563µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:13:49.819267Z","caller":"traceutil/trace.go:171","msg":"trace[1162991383] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"410.874631ms","start":"2026-05-14T11:13:49.408351Z","end":"2026-05-14T11:13:49.819226Z","steps":["trace[1162991383] 'process raft request' (duration: 410.545876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:13:49.819386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.769606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T11:13:49.819443Z","caller":"traceutil/trace.go:171","msg":"trace[1246853695] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1347; }","duration":"160.888229ms","start":"2026-05-14T11:13:49.658541Z","end":"2026-05-14T11:13:49.819429Z","steps":["trace[1246853695] 'agreement among raft nodes before linearized reading' (duration: 160.770397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:13:49.819454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.748755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-14T11:13:49.819463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:13:49.408333Z","time spent":"411.018913ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T11:13:49.819527Z","caller":"traceutil/trace.go:171","msg":"trace[1471630022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1347; }","duration":"267.875717ms","start":"2026-05-14T11:13:49.551633Z","end":"2026-05-14T11:13:49.819508Z","steps":["trace[1471630022] 'agreement among raft nodes before linearized reading' (duration: 267.648283ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:13:50.958925Z","caller":"traceutil/trace.go:171","msg":"trace[479499158] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"493.34875ms","start":"2026-05-14T11:13:50.465553Z","end":"2026-05-14T11:13:50.958902Z","steps":["trace[479499158] 'read index received' (duration: 493.271429ms)","trace[479499158] 'applied index is now lower than readState.Index' (duration: 75.841µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:13:50.959155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.596124ms","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-05-14T11:13:50.95921Z","caller":"traceutil/trace.go:171","msg":"trace[1865966092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"493.665815ms","start":"2026-05-14T11:13:50.46553Z","end":"2026-05-14T11:13:50.959196Z","steps":["trace[1865966092] 'agreement among raft nodes before linearized reading' (duration: 493.463092ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T11:13:50.959217Z","caller":"traceutil/trace.go:171","msg":"trace[819297610] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"601.138353ms","start":"2026-05-14T11:13:50.358041Z","end":"2026-05-14T11:13:50.959179Z","steps":["trace[819297610] 'process raft request' (duration: 600.731817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:13:50.95925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:13:50.465468Z","time spent":"493.770716ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T11:13:50.959393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T11:13:50.357982Z","time spent":"601.319386ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T11:13:50.974068Z","caller":"traceutil/trace.go:171","msg":"trace[1890921862] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"182.099451ms","start":"2026-05-14T11:13:50.791908Z","end":"2026-05-14T11:13:50.974007Z","steps":["trace[1890921862] 'process raft request' (duration: 181.944569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:14:15.403608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.677036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T11:14:15.403823Z","caller":"traceutil/trace.go:171","msg":"trace[1411769004] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"219.0085ms","start":"2026-05-14T11:14:15.184764Z","end":"2026-05-14T11:14:15.403772Z","steps":["trace[1411769004] 'read index received' (duration: 101.896808ms)","trace[1411769004] 'applied index is now lower than readState.Index' (duration: 117.104892ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T11:14:15.403867Z","caller":"traceutil/trace.go:171","msg":"trace[1228076667] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"227.877728ms","start":"2026-05-14T11:14:15.175916Z","end":"2026-05-14T11:14:15.403794Z","steps":["trace[1228076667] 'process raft request' (duration: 110.814307ms)","trace[1228076667] 'compare' (duration: 116.306781ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T11:14:15.404053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.463043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-05-14T11:14:15.404122Z","caller":"traceutil/trace.go:171","msg":"trace[1989229452] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1435; }","duration":"146.554084ms","start":"2026-05-14T11:14:15.257535Z","end":"2026-05-14T11:14:15.404089Z","steps":["trace[1989229452] 'agreement among raft nodes before linearized reading' (duration: 146.420022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T11:14:15.404744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.933472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:87 size:64708"} {"level":"info","ts":"2026-05-14T11:14:15.404873Z","caller":"traceutil/trace.go:171","msg":"trace[590882068] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:87; response_revision:1435; }","duration":"220.103865ms","start":"2026-05-14T11:14:15.184743Z","end":"2026-05-14T11:14:15.404847Z","steps":["trace[590882068] 'agreement among raft nodes before linearized reading' (duration: 219.217273ms)"],"step_count":1}