{"level":"warn","ts":"2026-07-10T21:21:26.619637Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-10T21:21:26.619758Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-07-10T21:21:26.619856Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-10T21:21:26.619872Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-10T21:21:26.6199Z","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-07-10T21:21:26.621585Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-07-10T21:21:26.621719Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-07-10T21:21:26.628472Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.353422ms"} {"level":"info","ts":"2026-07-10T21:21:26.64427Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-07-10T21:21:26.644392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-07-10T21:21:26.644424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-07-10T21:21:26.644447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-10T21:21:26.644468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-07-10T21:21:26.644535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-07-10T21:21:26.650422Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-10T21:21:26.651831Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-10T21:21:26.653801Z","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-07-10T21:21:26.657095Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-10T21:21:26.657419Z","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-07-10T21:21:26.657528Z","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-07-10T21:21:26.657563Z","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-07-10T21:21:26.657677Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-10T21:21:26.658415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-07-10T21:21:26.65881Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-10T21:21:26.661342Z","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-07-10T21:21:26.661639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-10T21:21:26.661431Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-10T21:21:26.662468Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-10T21:21:26.66177Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-10T21:21:26.745115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-07-10T21:21:26.745241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-10T21:21:26.745283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-07-10T21:21:26.745311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-07-10T21:21:26.745338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-10T21:21:26.745368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-07-10T21:21:26.745393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-10T21:21:26.746169Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-10T21:21:26.746246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-10T21:21:26.746323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-10T21:21:26.746508Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-10T21:21:26.746683Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-10T21:21:26.746777Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-10T21:21:26.747471Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-07-10T21:21:26.747588Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-10T21:21:26.747694Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-10T21:21:26.748985Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-10T21:21:26.749777Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-07-10T21:21:28.92982Z","caller":"traceutil/trace.go:171","msg":"trace[1352303853] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:38; }","duration":"195.544999ms","start":"2026-07-10T21:21:28.734255Z","end":"2026-07-10T21:21:28.9298Z","steps":["trace[1352303853] 'read index received' (duration: 33.550925ms)","trace[1352303853] 'applied index is now lower than readState.Index' (duration: 161.993294ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:21:28.929898Z","caller":"traceutil/trace.go:171","msg":"trace[1881276209] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"215.870346ms","start":"2026-07-10T21:21:28.713966Z","end":"2026-07-10T21:21:28.929836Z","steps":["trace[1881276209] 'process raft request' (duration: 215.754883ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:28.929905Z","caller":"traceutil/trace.go:171","msg":"trace[1873941309] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"216.03817ms","start":"2026-07-10T21:21:28.713784Z","end":"2026-07-10T21:21:28.929822Z","steps":["trace[1873941309] 'process raft request' (duration: 150.983506ms)","trace[1873941309] 'compare' (duration: 64.846089ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T21:21:28.930166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.885096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-10T21:21:28.930167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.180249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-10T21:21:28.930254Z","caller":"traceutil/trace.go:171","msg":"trace[91474999] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:38; }","duration":"166.277041ms","start":"2026-07-10T21:21:28.763963Z","end":"2026-07-10T21:21:28.93024Z","steps":["trace[91474999] 'agreement among raft nodes before linearized reading' (duration: 166.116157ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:28.930252Z","caller":"traceutil/trace.go:171","msg":"trace[1660690888] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:38; }","duration":"196.014499ms","start":"2026-07-10T21:21:28.734221Z","end":"2026-07-10T21:21:28.930236Z","steps":["trace[1660690888] 'agreement among raft nodes before linearized reading' (duration: 195.840685ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:28.930167Z","caller":"traceutil/trace.go:171","msg":"trace[108119577] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"216.037359ms","start":"2026-07-10T21:21:28.714098Z","end":"2026-07-10T21:21:28.930135Z","steps":["trace[108119577] 'process raft request' (duration: 215.666851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:21:28.930352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.197461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-10T21:21:28.930762Z","caller":"traceutil/trace.go:171","msg":"trace[43541859] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:38; }","duration":"159.483788ms","start":"2026-07-10T21:21:28.771029Z","end":"2026-07-10T21:21:28.930513Z","steps":["trace[43541859] 'agreement among raft nodes before linearized reading' (duration: 159.13605ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:29.109954Z","caller":"traceutil/trace.go:171","msg":"trace[1110005131] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"104.725855ms","start":"2026-07-10T21:21:29.005204Z","end":"2026-07-10T21:21:29.10993Z","steps":["trace[1110005131] 'process raft request' (duration: 104.636003ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:29.110205Z","caller":"traceutil/trace.go:171","msg":"trace[564789058] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"108.044039ms","start":"2026-07-10T21:21:29.00199Z","end":"2026-07-10T21:21:29.110034Z","steps":["trace[564789058] 'process raft request' (duration: 38.783652ms)","trace[564789058] 'compare' (duration: 68.862728ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:21:29.110839Z","caller":"traceutil/trace.go:171","msg":"trace[762463304] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"101.789427ms","start":"2026-07-10T21:21:29.009027Z","end":"2026-07-10T21:21:29.110816Z","steps":["trace[762463304] 'process raft request' (duration: 101.704855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:21:29.339746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.437368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-10T21:21:29.339865Z","caller":"traceutil/trace.go:171","msg":"trace[2057507182] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"134.048493ms","start":"2026-07-10T21:21:29.205794Z","end":"2026-07-10T21:21:29.339842Z","steps":["trace[2057507182] 'process raft request' (duration: 12.941581ms)","trace[2057507182] 'compare' (duration: 120.306685ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:21:29.35057Z","caller":"traceutil/trace.go:171","msg":"trace[744390617] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"143.774023ms","start":"2026-07-10T21:21:29.206759Z","end":"2026-07-10T21:21:29.350533Z","steps":["trace[744390617] 'process raft request' (duration: 143.511057ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:29.350645Z","caller":"traceutil/trace.go:171","msg":"trace[1453224968] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"143.369123ms","start":"2026-07-10T21:21:29.207243Z","end":"2026-07-10T21:21:29.350612Z","steps":["trace[1453224968] 'process raft request' (duration: 143.184479ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:45.611836Z","caller":"traceutil/trace.go:171","msg":"trace[2064550628] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"343.734766ms","start":"2026-07-10T21:21:45.268044Z","end":"2026-07-10T21:21:45.611779Z","steps":["trace[2064550628] 'read index received' (duration: 343.391547ms)","trace[2064550628] 'applied index is now lower than readState.Index' (duration: 342.099µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:21:45.611882Z","caller":"traceutil/trace.go:171","msg":"trace[535588757] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"391.822891ms","start":"2026-07-10T21:21:45.220035Z","end":"2026-07-10T21:21:45.611858Z","steps":["trace[535588757] 'process raft request' (duration: 391.395629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:21:45.612041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.988444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-10T21:21:45.612093Z","caller":"traceutil/trace.go:171","msg":"trace[1233583407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"344.096477ms","start":"2026-07-10T21:21:45.267981Z","end":"2026-07-10T21:21:45.612078Z","steps":["trace[1233583407] 'agreement among raft nodes before linearized reading' (duration: 343.985644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:21:45.612104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.8456ms","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-07-10T21:21:45.612139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:21:45.267958Z","time spent":"344.171249ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-10T21:21:45.612204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:21:45.220008Z","time spent":"391.928935ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T21:21:45.612266Z","caller":"traceutil/trace.go:171","msg":"trace[1315829030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"154.997785ms","start":"2026-07-10T21:21:45.457193Z","end":"2026-07-10T21:21:45.612191Z","steps":["trace[1315829030] 'agreement among raft nodes before linearized reading' (duration: 154.81608ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:49.962004Z","caller":"traceutil/trace.go:171","msg":"trace[862181190] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"297.76184ms","start":"2026-07-10T21:21:49.66395Z","end":"2026-07-10T21:21:49.961712Z","steps":["trace[862181190] 'process raft request' (duration: 297.114083ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:51.2293Z","caller":"traceutil/trace.go:171","msg":"trace[1029844426] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"396.893804ms","start":"2026-07-10T21:21:50.832392Z","end":"2026-07-10T21:21:51.229286Z","steps":["trace[1029844426] 'process raft request' (duration: 396.609007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:21:51.229505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:21:50.832374Z","time spent":"397.019307ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:21:51.229575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.635991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-10T21:21:51.229661Z","caller":"traceutil/trace.go:171","msg":"trace[1844055332] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:433; }","duration":"242.746174ms","start":"2026-07-10T21:21:50.986894Z","end":"2026-07-10T21:21:51.229641Z","steps":["trace[1844055332] 'agreement among raft nodes before linearized reading' (duration: 242.530948ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:51.229223Z","caller":"traceutil/trace.go:171","msg":"trace[351839746] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"242.288582ms","start":"2026-07-10T21:21:50.9869Z","end":"2026-07-10T21:21:51.229188Z","steps":["trace[351839746] 'read index received' (duration: 241.923202ms)","trace[351839746] 'applied index is now lower than readState.Index' (duration: 364.18µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:21:53.38353Z","caller":"traceutil/trace.go:171","msg":"trace[262400302] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"145.392031ms","start":"2026-07-10T21:21:53.238111Z","end":"2026-07-10T21:21:53.383503Z","steps":["trace[262400302] 'process raft request' (duration: 145.214897ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:21:57.792918Z","caller":"traceutil/trace.go:171","msg":"trace[281171002] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"101.418246ms","start":"2026-07-10T21:21:57.691456Z","end":"2026-07-10T21:21:57.792875Z","steps":["trace[281171002] 'process raft request' (duration: 101.065127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:04.764012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.704547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T21:22:04.764511Z","caller":"traceutil/trace.go:171","msg":"trace[826497677] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"353.7328ms","start":"2026-07-10T21:22:04.410728Z","end":"2026-07-10T21:22:04.764461Z","steps":["trace[826497677] 'read index received' (duration: 31.998256ms)","trace[826497677] 'applied index is now lower than readState.Index' (duration: 321.733534ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:04.764518Z","caller":"traceutil/trace.go:171","msg":"trace[1951815982] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"353.690048ms","start":"2026-07-10T21:22:04.410796Z","end":"2026-07-10T21:22:04.764487Z","steps":["trace[1951815982] 'process raft request' (duration: 353.436802ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:04.764577Z","caller":"traceutil/trace.go:171","msg":"trace[728897046] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"352.453116ms","start":"2026-07-10T21:22:04.41206Z","end":"2026-07-10T21:22:04.764513Z","steps":["trace[728897046] 'process raft request' (duration: 352.239351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:04.764655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:04.410788Z","time spent":"353.803381ms","remote":"127.0.0.1:60152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-10T21:22:04.764652Z","caller":"traceutil/trace.go:171","msg":"trace[1288705582] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"354.269763ms","start":"2026-07-10T21:22:04.410356Z","end":"2026-07-10T21:22:04.764626Z","steps":["trace[1288705582] 'process raft request' (duration: 223.833257ms)","trace[1288705582] 'compare' (duration: 129.553744ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T21:22:04.764803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:04.410328Z","time spent":"354.418777ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:22:04.764798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:04.412046Z","time spent":"352.63105ms","remote":"127.0.0.1:60520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T21:22:04.764845Z","caller":"traceutil/trace.go:171","msg":"trace[320617961] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"350.958308ms","start":"2026-07-10T21:22:04.41387Z","end":"2026-07-10T21:22:04.764828Z","steps":["trace[320617961] 'process raft request' (duration: 350.519637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:04.764937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:04.413859Z","time spent":"351.02913ms","remote":"127.0.0.1:60510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14386,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T21:22:04.764994Z","caller":"traceutil/trace.go:171","msg":"trace[1831048647] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"354.417018ms","start":"2026-07-10T21:22:04.410558Z","end":"2026-07-10T21:22:04.764975Z","steps":["trace[1831048647] 'process raft request' (duration: 353.589946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:04.765076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:04.410549Z","time spent":"354.482399ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15061,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:22:04.765217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.47945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-07-10T21:22:04.765266Z","caller":"traceutil/trace.go:171","msg":"trace[74106960] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:520; }","duration":"354.554012ms","start":"2026-07-10T21:22:04.410697Z","end":"2026-07-10T21:22:04.765251Z","steps":["trace[74106960] 'agreement among raft nodes before linearized reading' (duration: 354.46486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:04.765309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:04.410686Z","time spent":"354.611993ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4358,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-07-10T21:22:04.765315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.320219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-07-10T21:22:04.765375Z","caller":"traceutil/trace.go:171","msg":"trace[318275050] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:520; }","duration":"291.404201ms","start":"2026-07-10T21:22:04.473947Z","end":"2026-07-10T21:22:04.765351Z","steps":["trace[318275050] 'agreement among raft nodes before linearized reading' (duration: 291.110953ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:04.890943Z","caller":"traceutil/trace.go:171","msg":"trace[1102254729] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"111.402775ms","start":"2026-07-10T21:22:04.779521Z","end":"2026-07-10T21:22:04.890924Z","steps":["trace[1102254729] 'process raft request' (duration: 111.350803ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:04.890994Z","caller":"traceutil/trace.go:171","msg":"trace[2013099874] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"116.312992ms","start":"2026-07-10T21:22:04.77466Z","end":"2026-07-10T21:22:04.890973Z","steps":["trace[2013099874] 'process raft request' (duration: 114.68343ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:05.01218Z","caller":"traceutil/trace.go:171","msg":"trace[256483443] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:536; }","duration":"228.57892ms","start":"2026-07-10T21:22:04.783578Z","end":"2026-07-10T21:22:05.012157Z","steps":["trace[256483443] 'read index received' (duration: 105.75247ms)","trace[256483443] 'applied index is now lower than readState.Index' (duration: 122.82536ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:05.012222Z","caller":"traceutil/trace.go:171","msg":"trace[945460111] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"230.935501ms","start":"2026-07-10T21:22:04.781247Z","end":"2026-07-10T21:22:05.012182Z","steps":["trace[945460111] 'process raft request' (duration: 227.932213ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:05.012332Z","caller":"traceutil/trace.go:171","msg":"trace[1031238987] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"227.371338ms","start":"2026-07-10T21:22:04.784931Z","end":"2026-07-10T21:22:05.012302Z","steps":["trace[1031238987] 'process raft request' (duration: 227.165212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:05.012762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.164995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-07-10T21:22:05.012813Z","caller":"traceutil/trace.go:171","msg":"trace[329053910] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:525; }","duration":"229.270537ms","start":"2026-07-10T21:22:04.783528Z","end":"2026-07-10T21:22:05.012798Z","steps":["trace[329053910] 'agreement among raft nodes before linearized reading' (duration: 229.180225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:05.012823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.951011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"warn","ts":"2026-07-10T21:22:05.012859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.486008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T21:22:05.012913Z","caller":"traceutil/trace.go:171","msg":"trace[1630188690] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:525; }","duration":"145.077325ms","start":"2026-07-10T21:22:04.867812Z","end":"2026-07-10T21:22:05.012889Z","steps":["trace[1630188690] 'agreement among raft nodes before linearized reading' (duration: 144.661494ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:05.012942Z","caller":"traceutil/trace.go:171","msg":"trace[365543503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"106.603251ms","start":"2026-07-10T21:22:04.906315Z","end":"2026-07-10T21:22:05.012918Z","steps":["trace[365543503] 'agreement among raft nodes before linearized reading' (duration: 106.469208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:05.013042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.561507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4632"} {"level":"info","ts":"2026-07-10T21:22:05.01309Z","caller":"traceutil/trace.go:171","msg":"trace[903874064] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:525; }","duration":"114.665299ms","start":"2026-07-10T21:22:04.898412Z","end":"2026-07-10T21:22:05.013077Z","steps":["trace[903874064] 'agreement among raft nodes before linearized reading' (duration: 114.579097ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:06.865677Z","caller":"traceutil/trace.go:171","msg":"trace[616706555] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"352.014441ms","start":"2026-07-10T21:22:06.513637Z","end":"2026-07-10T21:22:06.865651Z","steps":["trace[616706555] 'process raft request' (duration: 351.247321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:06.865997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:06.513621Z","time spent":"352.188055ms","remote":"127.0.0.1:60128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T21:22:06.870484Z","caller":"traceutil/trace.go:171","msg":"trace[256178631] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"345.091653ms","start":"2026-07-10T21:22:06.525354Z","end":"2026-07-10T21:22:06.870445Z","steps":["trace[256178631] 'process raft request' (duration: 344.671002ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:06.870568Z","caller":"traceutil/trace.go:171","msg":"trace[696811756] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"305.141795ms","start":"2026-07-10T21:22:06.565398Z","end":"2026-07-10T21:22:06.87054Z","steps":["trace[696811756] 'process raft request' (duration: 304.92527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:06.870719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:06.525328Z","time spent":"345.229026ms","remote":"127.0.0.1:60128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:22:06.870876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:06.565368Z","time spent":"305.377461ms","remote":"127.0.0.1:60128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-10T21:22:08.379786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115609629128922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-10T21:22:08.646232Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.102765958s","expected-duration":"1s"} {"level":"info","ts":"2026-07-10T21:22:08.6472Z","caller":"traceutil/trace.go:171","msg":"trace[451902805] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"767.973326ms","start":"2026-07-10T21:22:07.879201Z","end":"2026-07-10T21:22:08.647174Z","steps":["trace[451902805] 'read index received' (duration: 767.192926ms)","trace[451902805] 'applied index is now lower than readState.Index' (duration: 779.3µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:08.647378Z","caller":"traceutil/trace.go:171","msg":"trace[1526103260] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.104449391s","start":"2026-07-10T21:22:07.542899Z","end":"2026-07-10T21:22:08.647349Z","steps":["trace[1526103260] 'process raft request' (duration: 1.103860636s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:08.647506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:07.542882Z","time spent":"1.104560203s","remote":"127.0.0.1:60128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-10T21:22:08.647654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.404457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-10T21:22:08.647775Z","caller":"traceutil/trace.go:171","msg":"trace[1548110248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"768.601503ms","start":"2026-07-10T21:22:07.879151Z","end":"2026-07-10T21:22:08.647752Z","steps":["trace[1548110248] 'agreement among raft nodes before linearized reading' (duration: 768.239533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:08.647953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:07.879132Z","time spent":"768.755157ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-10T21:22:08.648005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.518144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-10T21:22:08.648047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.822908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-10T21:22:08.648104Z","caller":"traceutil/trace.go:171","msg":"trace[1722436954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"531.639587ms","start":"2026-07-10T21:22:08.116443Z","end":"2026-07-10T21:22:08.648082Z","steps":["trace[1722436954] 'agreement among raft nodes before linearized reading' (duration: 531.500324ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:08.648137Z","caller":"traceutil/trace.go:171","msg":"trace[251314473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"548.980132ms","start":"2026-07-10T21:22:08.099136Z","end":"2026-07-10T21:22:08.648116Z","steps":["trace[251314473] 'agreement among raft nodes before linearized reading' (duration: 548.820358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:08.64816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:08.116426Z","time spent":"531.71979ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-10T21:22:08.648185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:08.099118Z","time spent":"549.057464ms","remote":"127.0.0.1:60334","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-07-10T21:22:08.847111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.449054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T21:22:08.84729Z","caller":"traceutil/trace.go:171","msg":"trace[2094334257] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"193.938847ms","start":"2026-07-10T21:22:08.653337Z","end":"2026-07-10T21:22:08.847276Z","steps":["trace[2094334257] 'process raft request' (duration: 43.248767ms)","trace[2094334257] 'compare' (duration: 150.329501ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:08.847407Z","caller":"traceutil/trace.go:171","msg":"trace[1476627896] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"192.572633ms","start":"2026-07-10T21:22:08.654806Z","end":"2026-07-10T21:22:08.847379Z","steps":["trace[1476627896] 'process raft request' (duration: 192.410259ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:08.847439Z","caller":"traceutil/trace.go:171","msg":"trace[1668208622] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"192.602064ms","start":"2026-07-10T21:22:08.65481Z","end":"2026-07-10T21:22:08.847412Z","steps":["trace[1668208622] 'process raft request' (duration: 192.45635ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:08.847549Z","caller":"traceutil/trace.go:171","msg":"trace[215935691] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:620; }","duration":"125.528675ms","start":"2026-07-10T21:22:08.722013Z","end":"2026-07-10T21:22:08.847541Z","steps":["trace[215935691] 'read index received' (duration: 7.471521ms)","trace[215935691] 'applied index is now lower than readState.Index' (duration: 118.056214ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:08.847705Z","caller":"traceutil/trace.go:171","msg":"trace[1221612123] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"118.878805ms","start":"2026-07-10T21:22:08.728761Z","end":"2026-07-10T21:22:08.84764Z","steps":["trace[1221612123] 'process raft request' (duration: 118.688531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:08.847752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.7084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T21:22:08.847946Z","caller":"traceutil/trace.go:171","msg":"trace[1023879449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"125.938526ms","start":"2026-07-10T21:22:08.721982Z","end":"2026-07-10T21:22:08.84792Z","steps":["trace[1023879449] 'agreement among raft nodes before linearized reading' (duration: 125.588777ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:10.749755Z","caller":"traceutil/trace.go:171","msg":"trace[1281581774] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"167.259359ms","start":"2026-07-10T21:22:10.582403Z","end":"2026-07-10T21:22:10.749662Z","steps":["trace[1281581774] 'process raft request' (duration: 147.667019ms)","trace[1281581774] 'compare' (duration: 19.336924ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:11.645536Z","caller":"traceutil/trace.go:171","msg":"trace[582046652] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"108.193496ms","start":"2026-07-10T21:22:11.537317Z","end":"2026-07-10T21:22:11.645511Z","steps":["trace[582046652] 'read index received' (duration: 107.387635ms)","trace[582046652] 'applied index is now lower than readState.Index' (duration: 804.991µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:11.645602Z","caller":"traceutil/trace.go:171","msg":"trace[547129270] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"114.803285ms","start":"2026-07-10T21:22:11.530768Z","end":"2026-07-10T21:22:11.645571Z","steps":["trace[547129270] 'process raft request' (duration: 114.208779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:11.645717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.380882ms","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-07-10T21:22:11.645816Z","caller":"traceutil/trace.go:171","msg":"trace[880860088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"108.469553ms","start":"2026-07-10T21:22:11.537284Z","end":"2026-07-10T21:22:11.645754Z","steps":["trace[880860088] 'agreement among raft nodes before linearized reading' (duration: 108.32475ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:12.227262Z","caller":"traceutil/trace.go:171","msg":"trace[767602869] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"107.73425ms","start":"2026-07-10T21:22:12.119499Z","end":"2026-07-10T21:22:12.227234Z","steps":["trace[767602869] 'process raft request' (duration: 107.195796ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:13.847438Z","caller":"traceutil/trace.go:171","msg":"trace[662740015] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"148.079609ms","start":"2026-07-10T21:22:13.699336Z","end":"2026-07-10T21:22:13.847416Z","steps":["trace[662740015] 'process raft request' (duration: 147.875824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:15.456759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23982834s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T21:22:15.457003Z","caller":"traceutil/trace.go:171","msg":"trace[1463221619] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.417468653s","start":"2026-07-10T21:22:14.039509Z","end":"2026-07-10T21:22:15.456977Z","steps":["trace[1463221619] 'process raft request' (duration: 1.41737534s)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:15.457248Z","caller":"traceutil/trace.go:171","msg":"trace[587453107] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"1.550557009s","start":"2026-07-10T21:22:13.90666Z","end":"2026-07-10T21:22:15.457217Z","steps":["trace[587453107] 'read index received' (duration: 309.883167ms)","trace[587453107] 'applied index is now lower than readState.Index' (duration: 1.240670342s)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:15.457408Z","caller":"traceutil/trace.go:171","msg":"trace[136433402] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.598730902s","start":"2026-07-10T21:22:13.858667Z","end":"2026-07-10T21:22:15.457398Z","steps":["trace[136433402] 'process raft request' (duration: 358.170014ms)","trace[136433402] 'compare' (duration: 1.239368008s)"],"step_count":2} {"level":"warn","ts":"2026-07-10T21:22:15.457345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:14.03949Z","time spent":"1.417651327s","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:22:15.457641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550962029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T21:22:15.457666Z","caller":"traceutil/trace.go:171","msg":"trace[880992335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.551031821s","start":"2026-07-10T21:22:13.906629Z","end":"2026-07-10T21:22:15.45766Z","steps":["trace[880992335] 'agreement among raft nodes before linearized reading' (duration: 1.550937438s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:15.457682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:13.906613Z","time spent":"1.551065611s","remote":"127.0.0.1:60072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-10T21:22:15.457959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.714062ms","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-07-10T21:22:15.457983Z","caller":"traceutil/trace.go:171","msg":"trace[304285108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"579.777694ms","start":"2026-07-10T21:22:14.8782Z","end":"2026-07-10T21:22:15.457978Z","steps":["trace[304285108] 'agreement among raft nodes before linearized reading' (duration: 579.652181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:15.457998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:14.878177Z","time spent":"579.817664ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-10T21:22:15.457545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:13.858641Z","time spent":"1.598791513s","remote":"127.0.0.1:60170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:22:15.458142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.072629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-10T21:22:15.458466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.81968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-10T21:22:15.458524Z","caller":"traceutil/trace.go:171","msg":"trace[619598069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"567.915123ms","start":"2026-07-10T21:22:14.890589Z","end":"2026-07-10T21:22:15.458504Z","steps":["trace[619598069] 'agreement among raft nodes before linearized reading' (duration: 567.750689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:15.458571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:14.890566Z","time spent":"567.993055ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-10T21:22:15.459341Z","caller":"traceutil/trace.go:171","msg":"trace[1833840163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"484.177321ms","start":"2026-07-10T21:22:14.973978Z","end":"2026-07-10T21:22:15.458155Z","steps":["trace[1833840163] 'agreement among raft nodes before linearized reading' (duration: 484.12399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:15.459436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:14.97396Z","time spent":"485.454712ms","remote":"127.0.0.1:60334","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-07-10T21:22:17.838052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.328138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T21:22:17.838208Z","caller":"traceutil/trace.go:171","msg":"trace[1997174125] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"285.93969ms","start":"2026-07-10T21:22:17.552253Z","end":"2026-07-10T21:22:17.838192Z","steps":["trace[1997174125] 'process raft request' (duration: 285.889039ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:17.838255Z","caller":"traceutil/trace.go:171","msg":"trace[327473848] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"286.297069ms","start":"2026-07-10T21:22:17.551925Z","end":"2026-07-10T21:22:17.838222Z","steps":["trace[327473848] 'process raft request' (duration: 53.718864ms)","trace[327473848] 'compare' (duration: 232.234096ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:19.506205Z","caller":"traceutil/trace.go:171","msg":"trace[1453387416] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"455.336005ms","start":"2026-07-10T21:22:19.050844Z","end":"2026-07-10T21:22:19.50618Z","steps":["trace[1453387416] 'process raft request' (duration: 455.193471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:19.506351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:19.05082Z","time spent":"455.460458ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:22:20.342089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115609629129066,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-10T21:22:20.84332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115609629129066,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-10T21:22:21.048672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497172547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-10T21:22:21.048784Z","caller":"traceutil/trace.go:171","msg":"trace[1365066685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"1.497361311s","start":"2026-07-10T21:22:19.551397Z","end":"2026-07-10T21:22:21.048758Z","steps":["trace[1365066685] 'range keys from in-memory index tree' (duration: 1.496960671s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:21.049471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:19.551378Z","time spent":"1.49807832s","remote":"127.0.0.1:60334","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-07-10T21:22:21.243466Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.575249895s","expected-duration":"1s"} {"level":"info","ts":"2026-07-10T21:22:21.24388Z","caller":"traceutil/trace.go:171","msg":"trace[1672730071] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"1.402462972s","start":"2026-07-10T21:22:19.841372Z","end":"2026-07-10T21:22:21.243835Z","steps":["trace[1672730071] 'read index received' (duration: 1.40237566s)","trace[1672730071] 'applied index is now lower than readState.Index' (duration: 86.582µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:21.24396Z","caller":"traceutil/trace.go:171","msg":"trace[1981995078] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.575887511s","start":"2026-07-10T21:22:19.668045Z","end":"2026-07-10T21:22:21.243933Z","steps":["trace[1981995078] 'process raft request' (duration: 1.575663576s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:21.244124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402734859s","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-07-10T21:22:21.244193Z","caller":"traceutil/trace.go:171","msg":"trace[1938785859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"1.402854902s","start":"2026-07-10T21:22:19.841317Z","end":"2026-07-10T21:22:21.244172Z","steps":["trace[1938785859] 'agreement among raft nodes before linearized reading' (duration: 1.402638267s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:21.244222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:19.668013Z","time spent":"1.576096406s","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:22:21.244285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:19.841298Z","time spent":"1.402969594s","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-10T21:22:21.264402Z","caller":"traceutil/trace.go:171","msg":"trace[1860513660] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"208.908673ms","start":"2026-07-10T21:22:21.055476Z","end":"2026-07-10T21:22:21.264385Z","steps":["trace[1860513660] 'process raft request' (duration: 202.180145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:21.264557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347967269s","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-07-10T21:22:21.264589Z","caller":"traceutil/trace.go:171","msg":"trace[1622281569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"1.348021461s","start":"2026-07-10T21:22:19.91656Z","end":"2026-07-10T21:22:21.264581Z","steps":["trace[1622281569] 'agreement among raft nodes before linearized reading' (duration: 1.347853267s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:21.264609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:19.916546Z","time spent":"1.348058252s","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-10T21:22:21.264684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357479369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T21:22:21.264764Z","caller":"traceutil/trace.go:171","msg":"trace[1795519273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"1.357636262s","start":"2026-07-10T21:22:19.907109Z","end":"2026-07-10T21:22:21.264745Z","steps":["trace[1795519273] 'agreement among raft nodes before linearized reading' (duration: 1.357321984s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:21.264779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.798781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-10T21:22:21.264804Z","caller":"traceutil/trace.go:171","msg":"trace[51909976] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:663; }","duration":"275.858022ms","start":"2026-07-10T21:22:20.988938Z","end":"2026-07-10T21:22:21.264796Z","steps":["trace[51909976] 'agreement among raft nodes before linearized reading' (duration: 275.462013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:21.264801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:19.907085Z","time spent":"1.357708524s","remote":"127.0.0.1:60072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-10T21:22:21.264727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42315652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-10T21:22:21.265034Z","caller":"traceutil/trace.go:171","msg":"trace[1986995571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"1.423388156s","start":"2026-07-10T21:22:19.841562Z","end":"2026-07-10T21:22:21.26495Z","steps":["trace[1986995571] 'agreement among raft nodes before linearized reading' (duration: 1.422822502s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:21.265306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:19.841537Z","time spent":"1.423631683s","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-10T21:22:23.235332Z","caller":"traceutil/trace.go:171","msg":"trace[857244262] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"178.741555ms","start":"2026-07-10T21:22:23.056567Z","end":"2026-07-10T21:22:23.235308Z","steps":["trace[857244262] 'process raft request' (duration: 178.611282ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:23.245148Z","caller":"traceutil/trace.go:171","msg":"trace[962034352] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"176.441447ms","start":"2026-07-10T21:22:23.068687Z","end":"2026-07-10T21:22:23.245129Z","steps":["trace[962034352] 'process raft request' (duration: 176.319654ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:27.401848Z","caller":"traceutil/trace.go:171","msg":"trace[1116518787] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"112.365881ms","start":"2026-07-10T21:22:27.289455Z","end":"2026-07-10T21:22:27.40182Z","steps":["trace[1116518787] 'process raft request' (duration: 112.185257ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:27.402647Z","caller":"traceutil/trace.go:171","msg":"trace[2136852114] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"112.453283ms","start":"2026-07-10T21:22:27.290167Z","end":"2026-07-10T21:22:27.402621Z","steps":["trace[2136852114] 'process raft request' (duration: 112.34019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:39.112911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115609629129462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-10T21:22:39.273352Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103502584s","expected-duration":"1s"} {"level":"info","ts":"2026-07-10T21:22:39.273673Z","caller":"traceutil/trace.go:171","msg":"trace[1523021106] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"661.421382ms","start":"2026-07-10T21:22:38.61222Z","end":"2026-07-10T21:22:39.273642Z","steps":["trace[1523021106] 'read index received' (duration: 661.245058ms)","trace[1523021106] 'applied index is now lower than readState.Index' (duration: 175.484µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:22:39.273775Z","caller":"traceutil/trace.go:171","msg":"trace[1051116627] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"1.104079108s","start":"2026-07-10T21:22:38.169686Z","end":"2026-07-10T21:22:39.273766Z","steps":["trace[1051116627] 'process raft request' (duration: 1.103833512s)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:39.273874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:38.169655Z","time spent":"1.104151089s","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:22:39.273893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.634411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T21:22:39.274017Z","caller":"traceutil/trace.go:171","msg":"trace[1388832333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"550.759684ms","start":"2026-07-10T21:22:38.723203Z","end":"2026-07-10T21:22:39.273962Z","steps":["trace[1388832333] 'agreement among raft nodes before linearized reading' (duration: 550.60438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:39.274137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:38.723191Z","time spent":"550.886568ms","remote":"127.0.0.1:60072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-10T21:22:39.274104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.860803ms","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-07-10T21:22:39.274096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.636375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T21:22:39.274267Z","caller":"traceutil/trace.go:171","msg":"trace[1676256940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"662.082779ms","start":"2026-07-10T21:22:38.612162Z","end":"2026-07-10T21:22:39.274244Z","steps":["trace[1676256940] 'agreement among raft nodes before linearized reading' (duration: 661.73178ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:39.274288Z","caller":"traceutil/trace.go:171","msg":"trace[1756485309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"368.864441ms","start":"2026-07-10T21:22:38.905392Z","end":"2026-07-10T21:22:39.274257Z","steps":["trace[1756485309] 'agreement among raft nodes before linearized reading' (duration: 368.517683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:22:39.274327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:38.612133Z","time spent":"662.17874ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-10T21:22:39.274341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:22:38.905376Z","time spent":"368.950143ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-10T21:22:44.056314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.042157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-10T21:22:44.056401Z","caller":"traceutil/trace.go:171","msg":"trace[1103384421] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:875; }","duration":"184.191801ms","start":"2026-07-10T21:22:43.872193Z","end":"2026-07-10T21:22:44.056385Z","steps":["trace[1103384421] 'count revisions from in-memory index tree' (duration: 183.963285ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:22:53.498625Z","caller":"traceutil/trace.go:171","msg":"trace[1920844032] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"119.110459ms","start":"2026-07-10T21:22:53.379491Z","end":"2026-07-10T21:22:53.498601Z","steps":["trace[1920844032] 'process raft request' (duration: 118.965215ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:05.450833Z","caller":"traceutil/trace.go:171","msg":"trace[1325517704] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"281.582779ms","start":"2026-07-10T21:23:05.169216Z","end":"2026-07-10T21:23:05.450799Z","steps":["trace[1325517704] 'process raft request' (duration: 281.398865ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:44.109803Z","caller":"traceutil/trace.go:171","msg":"trace[478776556] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"123.36892ms","start":"2026-07-10T21:23:43.986337Z","end":"2026-07-10T21:23:44.109706Z","steps":["trace[478776556] 'process raft request' (duration: 78.357633ms)","trace[478776556] 'compare' (duration: 44.440404ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:23:44.946234Z","caller":"traceutil/trace.go:171","msg":"trace[59936604] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"152.746974ms","start":"2026-07-10T21:23:44.793456Z","end":"2026-07-10T21:23:44.946203Z","steps":["trace[59936604] 'process raft request' (duration: 152.473998ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:47.285341Z","caller":"traceutil/trace.go:171","msg":"trace[621817912] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"335.026004ms","start":"2026-07-10T21:23:46.950277Z","end":"2026-07-10T21:23:47.285303Z","steps":["trace[621817912] 'process raft request' (duration: 334.81474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:23:47.285505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:23:46.950249Z","time spent":"335.174868ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:23:48.170689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.473338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-10T21:23:48.170753Z","caller":"traceutil/trace.go:171","msg":"trace[423458767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1179; }","duration":"120.601901ms","start":"2026-07-10T21:23:48.050137Z","end":"2026-07-10T21:23:48.170739Z","steps":["trace[423458767] 'range keys from in-memory index tree' (duration: 120.246423ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:48.557902Z","caller":"traceutil/trace.go:171","msg":"trace[1133370215] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"187.826732ms","start":"2026-07-10T21:23:48.370049Z","end":"2026-07-10T21:23:48.557875Z","steps":["trace[1133370215] 'process raft request' (duration: 187.254009ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:53.4229Z","caller":"traceutil/trace.go:171","msg":"trace[1191517454] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"117.574425ms","start":"2026-07-10T21:23:53.305302Z","end":"2026-07-10T21:23:53.422876Z","steps":["trace[1191517454] 'process raft request' (duration: 117.423851ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:54.134738Z","caller":"traceutil/trace.go:171","msg":"trace[819861514] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"172.366744ms","start":"2026-07-10T21:23:53.962333Z","end":"2026-07-10T21:23:54.1347Z","steps":["trace[819861514] 'process raft request' (duration: 172.227781ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:54.656746Z","caller":"traceutil/trace.go:171","msg":"trace[652904272] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"317.071345ms","start":"2026-07-10T21:23:54.339648Z","end":"2026-07-10T21:23:54.656719Z","steps":["trace[652904272] 'read index received' (duration: 316.808419ms)","trace[652904272] 'applied index is now lower than readState.Index' (duration: 261.936µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:23:54.656998Z","caller":"traceutil/trace.go:171","msg":"trace[1378734613] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"401.234745ms","start":"2026-07-10T21:23:54.25574Z","end":"2026-07-10T21:23:54.656975Z","steps":["trace[1378734613] 'process raft request' (duration: 400.815306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:23:54.657167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:23:54.255725Z","time spent":"401.352087ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-10T21:23:54.657276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.075887ms","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-07-10T21:23:54.657191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.866676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-10T21:23:54.657295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.226954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-10T21:23:54.657395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.738651ms","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-07-10T21:23:54.657481Z","caller":"traceutil/trace.go:171","msg":"trace[87139980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1200; }","duration":"317.847544ms","start":"2026-07-10T21:23:54.339611Z","end":"2026-07-10T21:23:54.657458Z","steps":["trace[87139980] 'agreement among raft nodes before linearized reading' (duration: 317.616608ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:54.657486Z","caller":"traceutil/trace.go:171","msg":"trace[1948497098] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1200; }","duration":"164.45025ms","start":"2026-07-10T21:23:54.492994Z","end":"2026-07-10T21:23:54.657445Z","steps":["trace[1948497098] 'agreement among raft nodes before linearized reading' (duration: 164.195843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:23:54.657529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:23:54.339599Z","time spent":"317.920736ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-10T21:23:54.657587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.071372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-07-10T21:23:54.657676Z","caller":"traceutil/trace.go:171","msg":"trace[1335065938] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1200; }","duration":"180.178535ms","start":"2026-07-10T21:23:54.477481Z","end":"2026-07-10T21:23:54.65766Z","steps":["trace[1335065938] 'agreement among raft nodes before linearized reading' (duration: 179.928859ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:23:54.657385Z","caller":"traceutil/trace.go:171","msg":"trace[1545876392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1200; }","duration":"305.244021ms","start":"2026-07-10T21:23:54.352121Z","end":"2026-07-10T21:23:54.657365Z","steps":["trace[1545876392] 'agreement among raft nodes before linearized reading' (duration: 304.918823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:23:54.657823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:23:54.352111Z","time spent":"305.696602ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-10T21:23:54.657568Z","caller":"traceutil/trace.go:171","msg":"trace[558314596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1200; }","duration":"158.177405ms","start":"2026-07-10T21:23:54.499272Z","end":"2026-07-10T21:23:54.657449Z","steps":["trace[558314596] 'agreement among raft nodes before linearized reading' (duration: 157.840126ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:24:26.440507Z","caller":"traceutil/trace.go:171","msg":"trace[419629692] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"142.556181ms","start":"2026-07-10T21:24:26.297929Z","end":"2026-07-10T21:24:26.440485Z","steps":["trace[419629692] 'read index received' (duration: 142.421638ms)","trace[419629692] 'applied index is now lower than readState.Index' (duration: 131.383µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:24:26.440611Z","caller":"traceutil/trace.go:171","msg":"trace[884535764] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"147.574836ms","start":"2026-07-10T21:24:26.292999Z","end":"2026-07-10T21:24:26.440574Z","steps":["trace[884535764] 'process raft request' (duration: 147.34023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:24:26.44065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.697344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-10T21:24:26.440714Z","caller":"traceutil/trace.go:171","msg":"trace[913907] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1338; }","duration":"142.821047ms","start":"2026-07-10T21:24:26.297875Z","end":"2026-07-10T21:24:26.440696Z","steps":["trace[913907] 'agreement among raft nodes before linearized reading' (duration: 142.706614ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:24:35.263376Z","caller":"traceutil/trace.go:171","msg":"trace[1286264833] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"178.559699ms","start":"2026-07-10T21:24:35.084784Z","end":"2026-07-10T21:24:35.263344Z","steps":["trace[1286264833] 'process raft request' (duration: 170.593756ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:24:35.950745Z","caller":"traceutil/trace.go:171","msg":"trace[183394261] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"333.371994ms","start":"2026-07-10T21:24:35.61734Z","end":"2026-07-10T21:24:35.950712Z","steps":["trace[183394261] 'process raft request' (duration: 333.131188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:24:35.951056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-10T21:24:35.617315Z","time spent":"333.508236ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-10T21:25:02.841208Z","caller":"traceutil/trace.go:171","msg":"trace[2131318791] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1524; }","duration":"214.93987ms","start":"2026-07-10T21:25:02.626242Z","end":"2026-07-10T21:25:02.841182Z","steps":["trace[2131318791] 'read index received' (duration: 214.681854ms)","trace[2131318791] 'applied index is now lower than readState.Index' (duration: 254.736µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:25:02.841276Z","caller":"traceutil/trace.go:171","msg":"trace[162845683] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"237.658757ms","start":"2026-07-10T21:25:02.603582Z","end":"2026-07-10T21:25:02.84124Z","steps":["trace[162845683] 'process raft request' (duration: 237.403751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:25:02.841497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.555255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-10T21:25:02.841579Z","caller":"traceutil/trace.go:171","msg":"trace[1908022867] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:1468; }","duration":"180.681058ms","start":"2026-07-10T21:25:02.660879Z","end":"2026-07-10T21:25:02.84156Z","steps":["trace[1908022867] 'agreement among raft nodes before linearized reading' (duration: 180.499824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:25:02.841843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.563183ms","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:86 size:63842"} {"level":"info","ts":"2026-07-10T21:25:02.841893Z","caller":"traceutil/trace.go:171","msg":"trace[1692348300] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:86; response_revision:1468; }","duration":"215.664635ms","start":"2026-07-10T21:25:02.626216Z","end":"2026-07-10T21:25:02.841881Z","steps":["trace[1692348300] 'agreement among raft nodes before linearized reading' (duration: 215.100163ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:25:03.218227Z","caller":"traceutil/trace.go:171","msg":"trace[1117117768] linearizableReadLoop","detail":"{readStateIndex:1526; appliedIndex:1525; }","duration":"105.831352ms","start":"2026-07-10T21:25:03.112368Z","end":"2026-07-10T21:25:03.218199Z","steps":["trace[1117117768] 'read index received' (duration: 105.493063ms)","trace[1117117768] 'applied index is now lower than readState.Index' (duration: 336.529µs)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:25:03.218295Z","caller":"traceutil/trace.go:171","msg":"trace[1977202675] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"110.625092ms","start":"2026-07-10T21:25:03.107645Z","end":"2026-07-10T21:25:03.21827Z","steps":["trace[1977202675] 'process raft request' (duration: 110.348735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:25:03.218472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.078107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:1 size:199"} {"level":"warn","ts":"2026-07-10T21:25:03.218512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.820562ms","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-07-10T21:25:03.218545Z","caller":"traceutil/trace.go:171","msg":"trace[39350109] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:1; response_revision:1469; }","duration":"106.18342ms","start":"2026-07-10T21:25:03.112351Z","end":"2026-07-10T21:25:03.218534Z","steps":["trace[39350109] 'agreement among raft nodes before linearized reading' (duration: 106.012476ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:25:03.218573Z","caller":"traceutil/trace.go:171","msg":"trace[820157027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1469; }","duration":"105.902334ms","start":"2026-07-10T21:25:03.112655Z","end":"2026-07-10T21:25:03.218558Z","steps":["trace[820157027] 'agreement among raft nodes before linearized reading' (duration: 105.73313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:25:03.535869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.229479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-10T21:25:03.535975Z","caller":"traceutil/trace.go:171","msg":"trace[1898527358] linearizableReadLoop","detail":"{readStateIndex:1529; appliedIndex:1528; }","duration":"111.608034ms","start":"2026-07-10T21:25:03.424348Z","end":"2026-07-10T21:25:03.535956Z","steps":["trace[1898527358] 'read index received' (duration: 34.071µs)","trace[1898527358] 'applied index is now lower than readState.Index' (duration: 111.572323ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T21:25:03.536106Z","caller":"traceutil/trace.go:171","msg":"trace[541098079] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"156.913565ms","start":"2026-07-10T21:25:03.379159Z","end":"2026-07-10T21:25:03.536073Z","steps":["trace[541098079] 'process raft request' (duration: 18.376489ms)","trace[541098079] 'compare' (duration: 137.751419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T21:25:03.536159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.804008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-10T21:25:03.536243Z","caller":"traceutil/trace.go:171","msg":"trace[937800396] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1472; }","duration":"111.90598ms","start":"2026-07-10T21:25:03.424329Z","end":"2026-07-10T21:25:03.536235Z","steps":["trace[937800396] 'agreement among raft nodes before linearized reading' (duration: 111.764567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T21:25:03.53639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.443622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-10T21:25:03.536419Z","caller":"traceutil/trace.go:171","msg":"trace[571892713] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:1472; }","duration":"108.511333ms","start":"2026-07-10T21:25:03.4279Z","end":"2026-07-10T21:25:03.536412Z","steps":["trace[571892713] 'agreement among raft nodes before linearized reading' (duration: 108.38157ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T21:25:04.16348Z","caller":"traceutil/trace.go:171","msg":"trace[275434769] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"124.118668ms","start":"2026-07-10T21:25:04.039333Z","end":"2026-07-10T21:25:04.163452Z","steps":["trace[275434769] 'process raft request' (duration: 123.884323ms)"],"step_count":1}