{"level":"warn","ts":"2026-05-19T05:03:51.30445Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T05:03:51.30457Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T05:03:51.304664Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T05:03:51.304684Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-19T05:03:51.304719Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T05:03:51.305631Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-05-19T05:03:51.305763Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T05:03:51.310459Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.389164ms"} {"level":"info","ts":"2026-05-19T05:03:51.321614Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-05-19T05:03:51.321718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T05:03:51.321803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-05-19T05:03:51.32182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T05:03:51.321844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-05-19T05:03:51.321902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-05-19T05:03:51.332147Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T05:03:51.333653Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T05:03:51.339582Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T05:03:51.341539Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T05:03:51.341725Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T05:03:51.341816Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T05:03:51.341827Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T05:03:51.342093Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T05:03:51.344458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-05-19T05:03:51.34471Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-19T05:03:51.34933Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T05:03:51.349614Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-19T05:03:51.349664Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-19T05:03:51.349928Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T05:03:51.34997Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T05:03:52.122961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T05:03:52.123023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T05:03:52.123045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-05-19T05:03:52.123065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-05-19T05:03:52.123076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-19T05:03:52.123092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-05-19T05:03:52.123106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-19T05:03:52.124321Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T05:03:52.124327Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T05:03:52.124344Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:03:52.124414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T05:03:52.124845Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T05:03:52.124915Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T05:03:52.125631Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:03:52.12582Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:03:52.125892Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T05:03:52.126257Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T05:03:52.140339Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-05-19T05:04:10.750752Z","caller":"traceutil/trace.go:171","msg":"trace[580910311] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"127.774107ms","start":"2026-05-19T05:04:10.622914Z","end":"2026-05-19T05:04:10.750688Z","steps":["trace[580910311] 'process raft request' (duration: 127.575912ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:11.15949Z","caller":"traceutil/trace.go:171","msg":"trace[1880426652] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"169.419678ms","start":"2026-05-19T05:04:10.990051Z","end":"2026-05-19T05:04:11.159471Z","steps":["trace[1880426652] 'process raft request' (duration: 169.250364ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:17.63247Z","caller":"traceutil/trace.go:171","msg":"trace[1173853353] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"141.390561ms","start":"2026-05-19T05:04:17.491048Z","end":"2026-05-19T05:04:17.632439Z","steps":["trace[1173853353] 'process raft request' (duration: 141.230057ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.595307Z","caller":"traceutil/trace.go:171","msg":"trace[514218307] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"102.430025ms","start":"2026-05-19T05:04:21.492853Z","end":"2026-05-19T05:04:21.595283Z","steps":["trace[514218307] 'process raft request' (duration: 102.22225ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.791359Z","caller":"traceutil/trace.go:171","msg":"trace[1848350779] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"154.589225ms","start":"2026-05-19T05:04:21.636738Z","end":"2026-05-19T05:04:21.791327Z","steps":["trace[1848350779] 'process raft request' (duration: 153.824437ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.800004Z","caller":"traceutil/trace.go:171","msg":"trace[1647533606] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"163.168939ms","start":"2026-05-19T05:04:21.636819Z","end":"2026-05-19T05:04:21.799987Z","steps":["trace[1647533606] 'process raft request' (duration: 162.752079ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.80001Z","caller":"traceutil/trace.go:171","msg":"trace[1036276271] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"163.013654ms","start":"2026-05-19T05:04:21.636965Z","end":"2026-05-19T05:04:21.799978Z","steps":["trace[1036276271] 'process raft request' (duration: 162.768959ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.800211Z","caller":"traceutil/trace.go:171","msg":"trace[511537974] transaction","detail":"{read_only:false; number_of_response:1; response_revision:534; }","duration":"149.999575ms","start":"2026-05-19T05:04:21.650169Z","end":"2026-05-19T05:04:21.800169Z","steps":["trace[511537974] 'process raft request' (duration: 149.683397ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.80032Z","caller":"traceutil/trace.go:171","msg":"trace[975051767] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"147.720281ms","start":"2026-05-19T05:04:21.652587Z","end":"2026-05-19T05:04:21.800307Z","steps":["trace[975051767] 'process raft request' (duration: 147.492136ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.800215Z","caller":"traceutil/trace.go:171","msg":"trace[879962155] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"148.912189ms","start":"2026-05-19T05:04:21.651273Z","end":"2026-05-19T05:04:21.800185Z","steps":["trace[879962155] 'process raft request' (duration: 148.725025ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.800202Z","caller":"traceutil/trace.go:171","msg":"trace[687820926] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"149.578566ms","start":"2026-05-19T05:04:21.650576Z","end":"2026-05-19T05:04:21.800154Z","steps":["trace[687820926] 'process raft request' (duration: 149.316779ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:21.800399Z","caller":"traceutil/trace.go:171","msg":"trace[1543119427] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"100.224102ms","start":"2026-05-19T05:04:21.700163Z","end":"2026-05-19T05:04:21.800388Z","steps":["trace[1543119427] 'process raft request' (duration: 100.091849ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:28.080755Z","caller":"traceutil/trace.go:171","msg":"trace[459903065] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"506.083509ms","start":"2026-05-19T05:04:27.574623Z","end":"2026-05-19T05:04:28.080707Z","steps":["trace[459903065] 'process raft request' (duration: 505.904595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:28.081685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:27.5746Z","time spent":"506.298795ms","remote":"127.0.0.1:47292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T05:04:28.088765Z","caller":"traceutil/trace.go:171","msg":"trace[196742204] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"254.428367ms","start":"2026-05-19T05:04:27.834315Z","end":"2026-05-19T05:04:28.088744Z","steps":["trace[196742204] 'read index received' (duration: 246.12344ms)","trace[196742204] 'applied index is now lower than readState.Index' (duration: 8.304017ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:04:28.088873Z","caller":"traceutil/trace.go:171","msg":"trace[309710888] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"428.262609ms","start":"2026-05-19T05:04:27.6606Z","end":"2026-05-19T05:04:28.088862Z","steps":["trace[309710888] 'process raft request' (duration: 428.030914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:28.090054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:27.660574Z","time spent":"428.332691ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:04:28.090011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.673638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T05:04:28.09026Z","caller":"traceutil/trace.go:171","msg":"trace[1856318767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"255.966615ms","start":"2026-05-19T05:04:27.834264Z","end":"2026-05-19T05:04:28.090231Z","steps":["trace[1856318767] 'agreement among raft nodes before linearized reading' (duration: 255.572785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:28.376122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.220251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:04:28.376563Z","caller":"traceutil/trace.go:171","msg":"trace[1009853152] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"266.72143ms","start":"2026-05-19T05:04:28.10982Z","end":"2026-05-19T05:04:28.376541Z","steps":["trace[1009853152] 'process raft request' (duration: 160.780902ms)","trace[1009853152] 'compare' (duration: 105.039087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:04:29.121311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340793358098191,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T05:04:29.305894Z","caller":"traceutil/trace.go:171","msg":"trace[719643622] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"684.945791ms","start":"2026-05-19T05:04:28.620934Z","end":"2026-05-19T05:04:29.305879Z","steps":["trace[719643622] 'read index received' (duration: 684.150802ms)","trace[719643622] 'applied index is now lower than readState.Index' (duration: 794.279µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:04:29.305981Z","caller":"traceutil/trace.go:171","msg":"trace[1879698466] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"806.812818ms","start":"2026-05-19T05:04:28.499132Z","end":"2026-05-19T05:04:29.305945Z","steps":["trace[1879698466] 'process raft request' (duration: 806.222674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:29.306042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.102554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T05:04:29.306069Z","caller":"traceutil/trace.go:171","msg":"trace[1199605402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"685.170786ms","start":"2026-05-19T05:04:28.62089Z","end":"2026-05-19T05:04:29.306061Z","steps":["trace[1199605402] 'agreement among raft nodes before linearized reading' (duration: 685.079234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:29.306091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:28.620871Z","time spent":"685.214657ms","remote":"127.0.0.1:47082","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-05-19T05:04:29.306167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:28.499099Z","time spent":"806.975981ms","remote":"127.0.0.1:46938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:04:29.306258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.396831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T05:04:29.306281Z","caller":"traceutil/trace.go:171","msg":"trace[1028126300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"454.458362ms","start":"2026-05-19T05:04:28.851817Z","end":"2026-05-19T05:04:29.306275Z","steps":["trace[1028126300] 'agreement among raft nodes before linearized reading' (duration: 454.398791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:29.306297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:28.851797Z","time spent":"454.496013ms","remote":"127.0.0.1:47082","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-05-19T05:04:39.721828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.82292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:04:39.722033Z","caller":"traceutil/trace.go:171","msg":"trace[1830826724] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:758; }","duration":"297.119302ms","start":"2026-05-19T05:04:39.424898Z","end":"2026-05-19T05:04:39.722017Z","steps":["trace[1830826724] 'read index received' (duration: 129.035097ms)","trace[1830826724] 'applied index is now lower than readState.Index' (duration: 168.083375ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:04:39.722117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.224305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:04:39.722148Z","caller":"traceutil/trace.go:171","msg":"trace[1935681049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"297.276306ms","start":"2026-05-19T05:04:39.424863Z","end":"2026-05-19T05:04:39.72214Z","steps":["trace[1935681049] 'agreement among raft nodes before linearized reading' (duration: 297.207545ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:39.72234Z","caller":"traceutil/trace.go:171","msg":"trace[889368789] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"348.552945ms","start":"2026-05-19T05:04:39.373775Z","end":"2026-05-19T05:04:39.722327Z","steps":["trace[889368789] 'process raft request' (duration: 348.170646ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:04:39.722287Z","caller":"traceutil/trace.go:171","msg":"trace[1765043165] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"356.031313ms","start":"2026-05-19T05:04:39.366227Z","end":"2026-05-19T05:04:39.722258Z","steps":["trace[1765043165] 'process raft request' (duration: 187.694162ms)","trace[1765043165] 'compare' (duration: 167.634304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:04:39.722426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:39.373765Z","time spent":"348.628717ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:04:39.722543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:39.366194Z","time spent":"356.263029ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:04:46.46333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.282202ms","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-05-19T05:04:46.463399Z","caller":"traceutil/trace.go:171","msg":"trace[708159308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"288.406826ms","start":"2026-05-19T05:04:46.174978Z","end":"2026-05-19T05:04:46.463385Z","steps":["trace[708159308] 'range keys from in-memory index tree' (duration: 288.119678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:46.92619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340793358098593,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T05:04:47.039529Z","caller":"traceutil/trace.go:171","msg":"trace[1469555815] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"861.240962ms","start":"2026-05-19T05:04:46.178262Z","end":"2026-05-19T05:04:47.039503Z","steps":["trace[1469555815] 'process raft request' (duration: 861.044447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:47.039692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:46.178246Z","time spent":"861.356264ms","remote":"127.0.0.1:47050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:04:47.049243Z","caller":"traceutil/trace.go:171","msg":"trace[107104045] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:815; }","duration":"623.558752ms","start":"2026-05-19T05:04:46.425674Z","end":"2026-05-19T05:04:47.049232Z","steps":["trace[107104045] 'read index received' (duration: 613.550114ms)","trace[107104045] 'applied index is now lower than readState.Index' (duration: 10.007998ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:04:47.049347Z","caller":"traceutil/trace.go:171","msg":"trace[1683637169] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"581.452211ms","start":"2026-05-19T05:04:46.467856Z","end":"2026-05-19T05:04:47.049309Z","steps":["trace[1683637169] 'process raft request' (duration: 581.293807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:47.04949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.085471ms","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":"info","ts":"2026-05-19T05:04:47.049527Z","caller":"traceutil/trace.go:171","msg":"trace[1155002950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"249.172593ms","start":"2026-05-19T05:04:46.800347Z","end":"2026-05-19T05:04:47.049519Z","steps":["trace[1155002950] 'agreement among raft nodes before linearized reading' (duration: 248.992939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:47.049578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:46.46784Z","time spent":"581.622105ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:04:47.049641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.962572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:04:47.049717Z","caller":"traceutil/trace.go:171","msg":"trace[931536841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"624.069024ms","start":"2026-05-19T05:04:46.425626Z","end":"2026-05-19T05:04:47.049695Z","steps":["trace[931536841] 'agreement among raft nodes before linearized reading' (duration: 623.659965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:04:47.049772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:04:46.425605Z","time spent":"624.155926ms","remote":"127.0.0.1:46818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T05:05:15.378388Z","caller":"traceutil/trace.go:171","msg":"trace[1100087473] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"219.604599ms","start":"2026-05-19T05:05:15.15874Z","end":"2026-05-19T05:05:15.378345Z","steps":["trace[1100087473] 'process raft request' (duration: 219.461165ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:15.378796Z","caller":"traceutil/trace.go:171","msg":"trace[1439956863] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"188.993071ms","start":"2026-05-19T05:05:15.189773Z","end":"2026-05-19T05:05:15.378766Z","steps":["trace[1439956863] 'process raft request' (duration: 188.786926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:15.378736Z","caller":"traceutil/trace.go:171","msg":"trace[1492165592] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1014; }","duration":"131.988606ms","start":"2026-05-19T05:05:15.24673Z","end":"2026-05-19T05:05:15.378718Z","steps":["trace[1492165592] 'read index received' (duration: 131.457044ms)","trace[1492165592] 'applied index is now lower than readState.Index' (duration: 530.872µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:05:15.379146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.313739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-19T05:05:15.379127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.379626ms","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-05-19T05:05:15.379175Z","caller":"traceutil/trace.go:171","msg":"trace[1623400841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:984; }","duration":"112.37369ms","start":"2026-05-19T05:05:15.266792Z","end":"2026-05-19T05:05:15.379166Z","steps":["trace[1623400841] 'agreement among raft nodes before linearized reading' (duration: 112.235797ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:15.379206Z","caller":"traceutil/trace.go:171","msg":"trace[1710516643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"132.501979ms","start":"2026-05-19T05:05:15.246687Z","end":"2026-05-19T05:05:15.379189Z","steps":["trace[1710516643] 'agreement among raft nodes before linearized reading' (duration: 132.281823ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:21.855611Z","caller":"traceutil/trace.go:171","msg":"trace[1515536306] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"456.132769ms","start":"2026-05-19T05:05:21.399453Z","end":"2026-05-19T05:05:21.855586Z","steps":["trace[1515536306] 'read index received' (duration: 455.950885ms)","trace[1515536306] 'applied index is now lower than readState.Index' (duration: 180.784µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:05:21.855722Z","caller":"traceutil/trace.go:171","msg":"trace[116101182] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"460.055423ms","start":"2026-05-19T05:05:21.39563Z","end":"2026-05-19T05:05:21.855685Z","steps":["trace[116101182] 'process raft request' (duration: 459.791246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:21.855852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.385365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-19T05:05:21.855868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:05:21.395608Z","time spent":"460.183135ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:05:21.855893Z","caller":"traceutil/trace.go:171","msg":"trace[2132888199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"456.482847ms","start":"2026-05-19T05:05:21.3994Z","end":"2026-05-19T05:05:21.855883Z","steps":["trace[2132888199] 'agreement among raft nodes before linearized reading' (duration: 456.311103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:21.855966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:05:21.399381Z","time spent":"456.57575ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T05:05:21.856032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.245476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T05:05:21.856104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.109192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T05:05:21.85616Z","caller":"traceutil/trace.go:171","msg":"trace[1282369524] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1007; }","duration":"287.474452ms","start":"2026-05-19T05:05:21.568667Z","end":"2026-05-19T05:05:21.856142Z","steps":["trace[1282369524] 'agreement among raft nodes before linearized reading' (duration: 287.249126ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:21.856198Z","caller":"traceutil/trace.go:171","msg":"trace[111680901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"239.259025ms","start":"2026-05-19T05:05:21.616919Z","end":"2026-05-19T05:05:21.856178Z","steps":["trace[111680901] 'agreement among raft nodes before linearized reading' (duration: 238.962378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:21.856737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.243488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T05:05:21.856791Z","caller":"traceutil/trace.go:171","msg":"trace[726292138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"455.31582ms","start":"2026-05-19T05:05:21.401465Z","end":"2026-05-19T05:05:21.856781Z","steps":["trace[726292138] 'agreement among raft nodes before linearized reading' (duration: 454.384748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:21.856821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:05:21.401458Z","time spent":"455.35481ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T05:05:21.995739Z","caller":"traceutil/trace.go:171","msg":"trace[2036909375] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"135.696015ms","start":"2026-05-19T05:05:21.860029Z","end":"2026-05-19T05:05:21.995725Z","steps":["trace[2036909375] 'process raft request' (duration: 135.600622ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:22.000899Z","caller":"traceutil/trace.go:171","msg":"trace[1948462044] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"140.467848ms","start":"2026-05-19T05:05:21.860419Z","end":"2026-05-19T05:05:22.000886Z","steps":["trace[1948462044] 'process raft request' (duration: 140.293084ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:22.000946Z","caller":"traceutil/trace.go:171","msg":"trace[1721181474] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"140.210002ms","start":"2026-05-19T05:05:21.860699Z","end":"2026-05-19T05:05:22.000909Z","steps":["trace[1721181474] 'process raft request' (duration: 140.12986ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:44.128555Z","caller":"traceutil/trace.go:171","msg":"trace[1659530663] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"110.144478ms","start":"2026-05-19T05:05:44.018385Z","end":"2026-05-19T05:05:44.128529Z","steps":["trace[1659530663] 'process raft request' (duration: 109.972834ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:44.448608Z","caller":"traceutil/trace.go:171","msg":"trace[1597470404] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1118; }","duration":"205.226537ms","start":"2026-05-19T05:05:44.243356Z","end":"2026-05-19T05:05:44.448583Z","steps":["trace[1597470404] 'read index received' (duration: 86.532217ms)","trace[1597470404] 'applied index is now lower than readState.Index' (duration: 118.69303ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:05:44.448623Z","caller":"traceutil/trace.go:171","msg":"trace[1188572282] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"207.109411ms","start":"2026-05-19T05:05:44.241476Z","end":"2026-05-19T05:05:44.448585Z","steps":["trace[1188572282] 'process raft request' (duration: 124.765875ms)","trace[1188572282] 'compare' (duration: 82.108321ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:05:44.448819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.436302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T05:05:44.448869Z","caller":"traceutil/trace.go:171","msg":"trace[516098336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"205.529714ms","start":"2026-05-19T05:05:44.243326Z","end":"2026-05-19T05:05:44.448856Z","steps":["trace[516098336] 'agreement among raft nodes before linearized reading' (duration: 205.37496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:44.448882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.976639ms","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-05-19T05:05:44.448922Z","caller":"traceutil/trace.go:171","msg":"trace[1121857559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"194.051341ms","start":"2026-05-19T05:05:44.254859Z","end":"2026-05-19T05:05:44.44891Z","steps":["trace[1121857559] 'agreement among raft nodes before linearized reading' (duration: 193.871717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:44.684177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.037235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:05:44.684411Z","caller":"traceutil/trace.go:171","msg":"trace[2005812670] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"231.973563ms","start":"2026-05-19T05:05:44.452416Z","end":"2026-05-19T05:05:44.684389Z","steps":["trace[2005812670] 'process raft request' (duration: 113.644421ms)","trace[2005812670] 'compare' (duration: 117.930773ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:05:44.700336Z","caller":"traceutil/trace.go:171","msg":"trace[1310096956] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"247.261936ms","start":"2026-05-19T05:05:44.45306Z","end":"2026-05-19T05:05:44.700321Z","steps":["trace[1310096956] 'process raft request' (duration: 247.217175ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:44.700431Z","caller":"traceutil/trace.go:171","msg":"trace[1850858762] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"247.685216ms","start":"2026-05-19T05:05:44.452707Z","end":"2026-05-19T05:05:44.700392Z","steps":["trace[1850858762] 'process raft request' (duration: 247.491722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:45.296548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.747376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T05:05:45.296625Z","caller":"traceutil/trace.go:171","msg":"trace[1063879422] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:1084; }","duration":"151.869679ms","start":"2026-05-19T05:05:45.144737Z","end":"2026-05-19T05:05:45.296607Z","steps":["trace[1063879422] 'range keys from in-memory index tree' (duration: 151.5106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:45.296856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.056078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:05:45.296936Z","caller":"traceutil/trace.go:171","msg":"trace[1494243473] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1084; }","duration":"270.192811ms","start":"2026-05-19T05:05:45.026725Z","end":"2026-05-19T05:05:45.296918Z","steps":["trace[1494243473] 'count revisions from in-memory index tree' (duration: 269.980706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:45.862618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.531538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:05:45.86276Z","caller":"traceutil/trace.go:171","msg":"trace[746160076] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"437.098866ms","start":"2026-05-19T05:05:45.42564Z","end":"2026-05-19T05:05:45.862739Z","steps":["trace[746160076] 'read index received' (duration: 137.296342ms)","trace[746160076] 'applied index is now lower than readState.Index' (duration: 299.800914ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:05:45.862802Z","caller":"traceutil/trace.go:171","msg":"trace[299218108] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"545.172694ms","start":"2026-05-19T05:05:45.317596Z","end":"2026-05-19T05:05:45.862769Z","steps":["trace[299218108] 'process raft request' (duration: 245.38834ms)","trace[299218108] 'compare' (duration: 299.355934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:05:45.862975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:05:45.317564Z","time spent":"545.323868ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:05:45.862993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.352072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:05:45.863035Z","caller":"traceutil/trace.go:171","msg":"trace[2025038439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1085; }","duration":"437.430224ms","start":"2026-05-19T05:05:45.425592Z","end":"2026-05-19T05:05:45.863022Z","steps":["trace[2025038439] 'agreement among raft nodes before linearized reading' (duration: 437.204499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:45.863068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:05:45.42557Z","time spent":"437.491006ms","remote":"127.0.0.1:46818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T05:05:54.29937Z","caller":"traceutil/trace.go:171","msg":"trace[1152117974] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"138.063371ms","start":"2026-05-19T05:05:54.161291Z","end":"2026-05-19T05:05:54.299354Z","steps":["trace[1152117974] 'process raft request' (duration: 137.951928ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:54.944Z","caller":"traceutil/trace.go:171","msg":"trace[1592292716] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"152.833812ms","start":"2026-05-19T05:05:54.79114Z","end":"2026-05-19T05:05:54.943974Z","steps":["trace[1592292716] 'process raft request' (duration: 108.673683ms)","trace[1592292716] 'compare' (duration: 44.054406ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:05:57.124585Z","caller":"traceutil/trace.go:171","msg":"trace[568398998] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"172.227952ms","start":"2026-05-19T05:05:56.952331Z","end":"2026-05-19T05:05:57.124559Z","steps":["trace[568398998] 'process raft request' (duration: 172.067418ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:05:58.46432Z","caller":"traceutil/trace.go:171","msg":"trace[1250020183] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"146.319567ms","start":"2026-05-19T05:05:58.317981Z","end":"2026-05-19T05:05:58.4643Z","steps":["trace[1250020183] 'process raft request' (duration: 145.99694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:58.783731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.815251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:05:58.783842Z","caller":"traceutil/trace.go:171","msg":"trace[1808032905] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"295.266636ms","start":"2026-05-19T05:05:58.488556Z","end":"2026-05-19T05:05:58.783822Z","steps":["trace[1808032905] 'read index received' (duration: 57.282981ms)","trace[1808032905] 'applied index is now lower than readState.Index' (duration: 237.982655ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:05:58.783913Z","caller":"traceutil/trace.go:171","msg":"trace[2133528051] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"314.641837ms","start":"2026-05-19T05:05:58.469216Z","end":"2026-05-19T05:05:58.783858Z","steps":["trace[2133528051] 'process raft request' (duration: 76.615901ms)","trace[2133528051] 'compare' (duration: 237.723359ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:05:58.784018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.444521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T05:05:58.784078Z","caller":"traceutil/trace.go:171","msg":"trace[1698687786] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1178; }","duration":"295.546133ms","start":"2026-05-19T05:05:58.48852Z","end":"2026-05-19T05:05:58.784066Z","steps":["trace[1698687786] 'agreement among raft nodes before linearized reading' (duration: 295.400769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:58.784089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:05:58.469204Z","time spent":"314.78232ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:05:58.784232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.358354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T05:05:58.784251Z","caller":"traceutil/trace.go:171","msg":"trace[372835899] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1178; }","duration":"259.391624ms","start":"2026-05-19T05:05:58.524854Z","end":"2026-05-19T05:05:58.784246Z","steps":["trace[372835899] 'agreement among raft nodes before linearized reading' (duration: 259.338733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:58.784362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.249249ms","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":"info","ts":"2026-05-19T05:05:58.784402Z","caller":"traceutil/trace.go:171","msg":"trace[343287175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"192.324561ms","start":"2026-05-19T05:05:58.592065Z","end":"2026-05-19T05:05:58.784389Z","steps":["trace[343287175] 'agreement among raft nodes before linearized reading' (duration: 192.184877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:05:58.784841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.922558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:05:58.784864Z","caller":"traceutil/trace.go:171","msg":"trace[1207930802] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1178; }","duration":"247.43406ms","start":"2026-05-19T05:05:58.537423Z","end":"2026-05-19T05:05:58.784857Z","steps":["trace[1207930802] 'agreement among raft nodes before linearized reading' (duration: 246.924608ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:06:14.891334Z","caller":"traceutil/trace.go:171","msg":"trace[1392409733] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"436.80555ms","start":"2026-05-19T05:06:14.454501Z","end":"2026-05-19T05:06:14.891307Z","steps":["trace[1392409733] 'process raft request' (duration: 436.539844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:14.891491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:14.454482Z","time spent":"436.929013ms","remote":"127.0.0.1:47008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:06:14.93258Z","caller":"traceutil/trace.go:171","msg":"trace[13183092] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"419.884348ms","start":"2026-05-19T05:06:14.512665Z","end":"2026-05-19T05:06:14.93255Z","steps":["trace[13183092] 'process raft request' (duration: 419.665123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:14.932721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:14.512646Z","time spent":"420.00323ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T05:06:15.54664Z","caller":"traceutil/trace.go:171","msg":"trace[1946043362] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"123.569606ms","start":"2026-05-19T05:06:15.423048Z","end":"2026-05-19T05:06:15.546618Z","steps":["trace[1946043362] 'read index received' (duration: 123.374792ms)","trace[1946043362] 'applied index is now lower than readState.Index' (duration: 193.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:06:15.546692Z","caller":"traceutil/trace.go:171","msg":"trace[470917735] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"133.148224ms","start":"2026-05-19T05:06:15.413505Z","end":"2026-05-19T05:06:15.546653Z","steps":["trace[470917735] 'process raft request' (duration: 132.98251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:15.54683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.74055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:06:15.546889Z","caller":"traceutil/trace.go:171","msg":"trace[454038166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"123.859594ms","start":"2026-05-19T05:06:15.423014Z","end":"2026-05-19T05:06:15.546873Z","steps":["trace[454038166] 'agreement among raft nodes before linearized reading' (duration: 123.72141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:15.823162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.080205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:06:15.823279Z","caller":"traceutil/trace.go:171","msg":"trace[1562306767] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"271.784118ms","start":"2026-05-19T05:06:15.551475Z","end":"2026-05-19T05:06:15.823259Z","steps":["trace[1562306767] 'process raft request' (duration: 92.533779ms)","trace[1562306767] 'compare' (duration: 178.932442ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:06:16.328503Z","caller":"traceutil/trace.go:171","msg":"trace[1633981583] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"497.019201ms","start":"2026-05-19T05:06:15.831436Z","end":"2026-05-19T05:06:16.328455Z","steps":["trace[1633981583] 'process raft request' (duration: 496.788355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:16.328679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:15.831416Z","time spent":"497.188355ms","remote":"127.0.0.1:46910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T05:06:16.328426Z","caller":"traceutil/trace.go:171","msg":"trace[395508280] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"494.096251ms","start":"2026-05-19T05:06:15.834307Z","end":"2026-05-19T05:06:16.328403Z","steps":["trace[395508280] 'process raft request' (duration: 494.03765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:16.329139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:15.834283Z","time spent":"494.801458ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:06:16.725866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.346988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:06:16.725965Z","caller":"traceutil/trace.go:171","msg":"trace[946418844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"300.46291ms","start":"2026-05-19T05:06:16.425481Z","end":"2026-05-19T05:06:16.725944Z","steps":["trace[946418844] 'range keys from in-memory index tree' (duration: 300.268135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:16.726045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:16.425434Z","time spent":"300.600374ms","remote":"127.0.0.1:46818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T05:06:17.728132Z","caller":"traceutil/trace.go:171","msg":"trace[1773721368] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"412.663435ms","start":"2026-05-19T05:06:17.31545Z","end":"2026-05-19T05:06:17.728113Z","steps":["trace[1773721368] 'process raft request' (duration: 412.514382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:17.728259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:17.315423Z","time spent":"412.769637ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:06:18.204614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.566024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T05:06:18.204629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.993121ms","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":"info","ts":"2026-05-19T05:06:18.204701Z","caller":"traceutil/trace.go:171","msg":"trace[1029932320] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1269; }","duration":"234.661206ms","start":"2026-05-19T05:06:17.970014Z","end":"2026-05-19T05:06:18.204675Z","steps":["trace[1029932320] 'count revisions from in-memory index tree' (duration: 234.475261ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:06:18.20472Z","caller":"traceutil/trace.go:171","msg":"trace[53510845] 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:1269; }","duration":"104.157105ms","start":"2026-05-19T05:06:18.100541Z","end":"2026-05-19T05:06:18.204698Z","steps":["trace[53510845] 'count revisions from in-memory index tree' (duration: 103.905779ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:06:28.901962Z","caller":"traceutil/trace.go:171","msg":"trace[673195683] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"490.36104ms","start":"2026-05-19T05:06:28.411575Z","end":"2026-05-19T05:06:28.901936Z","steps":["trace[673195683] 'process raft request' (duration: 490.135595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:28.902133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:28.411562Z","time spent":"490.487593ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:06:29.722183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.982538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:06:29.72227Z","caller":"traceutil/trace.go:171","msg":"trace[2115206816] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"648.796768ms","start":"2026-05-19T05:06:29.073457Z","end":"2026-05-19T05:06:29.722253Z","steps":["trace[2115206816] 'read index received' (duration: 460.525763ms)","trace[2115206816] 'applied index is now lower than readState.Index' (duration: 188.270165ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:06:29.722335Z","caller":"traceutil/trace.go:171","msg":"trace[1979299651] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"723.14994ms","start":"2026-05-19T05:06:28.999176Z","end":"2026-05-19T05:06:29.722326Z","steps":["trace[1979299651] 'process raft request' (duration: 534.955287ms)","trace[1979299651] 'compare' (duration: 187.669831ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:06:29.722667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.216939ms","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-05-19T05:06:29.722737Z","caller":"traceutil/trace.go:171","msg":"trace[333793392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1344; }","duration":"496.371983ms","start":"2026-05-19T05:06:29.226347Z","end":"2026-05-19T05:06:29.722719Z","steps":["trace[333793392] 'agreement among raft nodes before linearized reading' (duration: 496.163218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:29.722776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:29.226323Z","time spent":"496.446064ms","remote":"127.0.0.1:47082","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-05-19T05:06:29.722888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:28.99915Z","time spent":"723.202992ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:06:29.7229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.058202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T05:06:29.722979Z","caller":"traceutil/trace.go:171","msg":"trace[123516136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"490.168076ms","start":"2026-05-19T05:06:29.232793Z","end":"2026-05-19T05:06:29.722961Z","steps":["trace[123516136] 'agreement among raft nodes before linearized reading' (duration: 489.92625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:29.723021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.920498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-19T05:06:29.723025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:29.232782Z","time spent":"490.234967ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T05:06:29.723053Z","caller":"traceutil/trace.go:171","msg":"trace[331291610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"494.994939ms","start":"2026-05-19T05:06:29.228048Z","end":"2026-05-19T05:06:29.723043Z","steps":["trace[331291610] 'agreement among raft nodes before linearized reading' (duration: 494.896887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:29.723082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:29.228026Z","time spent":"495.049961ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T05:06:29.723186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.711509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T05:06:29.723292Z","caller":"traceutil/trace.go:171","msg":"trace[1831365544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"649.850852ms","start":"2026-05-19T05:06:29.073399Z","end":"2026-05-19T05:06:29.72325Z","steps":["trace[1831365544] 'agreement among raft nodes before linearized reading' (duration: 649.590026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:29.723344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:29.073376Z","time spent":"649.955115ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T05:06:29.724233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.821171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T05:06:29.724333Z","caller":"traceutil/trace.go:171","msg":"trace[1077146009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"300.19346ms","start":"2026-05-19T05:06:29.424116Z","end":"2026-05-19T05:06:29.724309Z","steps":["trace[1077146009] 'agreement among raft nodes before linearized reading' (duration: 298.549781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:06:29.724396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:06:29.424097Z","time spent":"300.287412ms","remote":"127.0.0.1:46818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T05:07:02.097822Z","caller":"traceutil/trace.go:171","msg":"trace[1009174296] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"166.244823ms","start":"2026-05-19T05:07:01.931559Z","end":"2026-05-19T05:07:02.097804Z","steps":["trace[1009174296] 'read index received' (duration: 166.13742ms)","trace[1009174296] 'applied index is now lower than readState.Index' (duration: 106.773µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:07:02.097873Z","caller":"traceutil/trace.go:171","msg":"trace[1200081417] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"190.054198ms","start":"2026-05-19T05:07:01.907795Z","end":"2026-05-19T05:07:02.097849Z","steps":["trace[1200081417] 'process raft request' (duration: 189.918115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:07:02.097995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.420577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T05:07:02.098021Z","caller":"traceutil/trace.go:171","msg":"trace[1763076076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1495; }","duration":"166.464709ms","start":"2026-05-19T05:07:01.931547Z","end":"2026-05-19T05:07:02.098011Z","steps":["trace[1763076076] 'agreement among raft nodes before linearized reading' (duration: 166.345076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:07:02.098256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.884839ms","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":"info","ts":"2026-05-19T05:07:02.098349Z","caller":"traceutil/trace.go:171","msg":"trace[186420557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1495; }","duration":"158.995151ms","start":"2026-05-19T05:07:01.939328Z","end":"2026-05-19T05:07:02.098323Z","steps":["trace[186420557] 'agreement among raft nodes before linearized reading' (duration: 158.739335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:07:02.098315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.48509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T05:07:02.098491Z","caller":"traceutil/trace.go:171","msg":"trace[856160992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1495; }","duration":"134.686245ms","start":"2026-05-19T05:07:01.963785Z","end":"2026-05-19T05:07:02.098471Z","steps":["trace[856160992] 'agreement among raft nodes before linearized reading' (duration: 134.382028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:07:02.099381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.788436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T05:07:02.099614Z","caller":"traceutil/trace.go:171","msg":"trace[1436861170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1495; }","duration":"160.093597ms","start":"2026-05-19T05:07:01.939501Z","end":"2026-05-19T05:07:02.099594Z","steps":["trace[1436861170] 'agreement among raft nodes before linearized reading' (duration: 158.653923ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:07:02.232195Z","caller":"traceutil/trace.go:171","msg":"trace[2036527545] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"129.05067ms","start":"2026-05-19T05:07:02.103117Z","end":"2026-05-19T05:07:02.232168Z","steps":["trace[2036527545] 'process raft request' (duration: 124.742578ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:07:02.232197Z","caller":"traceutil/trace.go:171","msg":"trace[872709166] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"128.970559ms","start":"2026-05-19T05:07:02.10321Z","end":"2026-05-19T05:07:02.23218Z","steps":["trace[872709166] 'process raft request' (duration: 128.928618ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:07:02.232224Z","caller":"traceutil/trace.go:171","msg":"trace[1733046587] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"129.042971ms","start":"2026-05-19T05:07:02.103157Z","end":"2026-05-19T05:07:02.2322Z","steps":["trace[1733046587] 'process raft request' (duration: 128.936009ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:07:20.139398Z","caller":"traceutil/trace.go:171","msg":"trace[1419282353] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"150.858028ms","start":"2026-05-19T05:07:19.988512Z","end":"2026-05-19T05:07:20.13937Z","steps":["trace[1419282353] 'process raft request' (duration: 94.569163ms)","trace[1419282353] 'compare' (duration: 56.158552ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:07:22.645523Z","caller":"traceutil/trace.go:171","msg":"trace[1784777904] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"135.835881ms","start":"2026-05-19T05:07:22.509582Z","end":"2026-05-19T05:07:22.645418Z","steps":["trace[1784777904] 'process raft request' (duration: 135.611646ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:07:22.657926Z","caller":"traceutil/trace.go:171","msg":"trace[1583498378] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"147.345575ms","start":"2026-05-19T05:07:22.510556Z","end":"2026-05-19T05:07:22.657902Z","steps":["trace[1583498378] 'process raft request' (duration: 147.105249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:08:02.937052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.829281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:08:02.93719Z","caller":"traceutil/trace.go:171","msg":"trace[1783933898] linearizableReadLoop","detail":"{readStateIndex:1842; appliedIndex:1841; }","duration":"179.204076ms","start":"2026-05-19T05:08:02.757968Z","end":"2026-05-19T05:08:02.937172Z","steps":["trace[1783933898] 'read index received' (duration: 60.125768ms)","trace[1783933898] 'applied index is now lower than readState.Index' (duration: 119.077018ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:08:02.937215Z","caller":"traceutil/trace.go:171","msg":"trace[1820036037] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"225.867012ms","start":"2026-05-19T05:08:02.711314Z","end":"2026-05-19T05:08:02.937181Z","steps":["trace[1820036037] 'process raft request' (duration: 106.827605ms)","trace[1820036037] 'compare' (duration: 118.717599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:08:02.937374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.41064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-19T05:08:02.937407Z","caller":"traceutil/trace.go:171","msg":"trace[304558621] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1773; }","duration":"179.478212ms","start":"2026-05-19T05:08:02.757918Z","end":"2026-05-19T05:08:02.937396Z","steps":["trace[304558621] 'agreement among raft nodes before linearized reading' (duration: 179.358449ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:09:45.11491Z","caller":"traceutil/trace.go:171","msg":"trace[327761874] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"125.044444ms","start":"2026-05-19T05:09:44.989829Z","end":"2026-05-19T05:09:45.114873Z","steps":["trace[327761874] 'process raft request' (duration: 124.786017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:09:45.660568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340793358102099,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T05:09:45.666221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:09:45.159508Z","time spent":"506.707787ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T05:09:45.95107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.364674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T05:09:45.951428Z","caller":"traceutil/trace.go:171","msg":"trace[477849691] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"789.98811ms","start":"2026-05-19T05:09:45.161388Z","end":"2026-05-19T05:09:45.951376Z","steps":["trace[477849691] 'process raft request' (duration: 641.208836ms)","trace[477849691] 'compare' (duration: 148.22418ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:09:45.951554Z","caller":"traceutil/trace.go:171","msg":"trace[864455121] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"283.905819ms","start":"2026-05-19T05:09:45.667551Z","end":"2026-05-19T05:09:45.951457Z","steps":["trace[864455121] 'process raft request' (duration: 283.628852ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:09:45.951644Z","caller":"traceutil/trace.go:171","msg":"trace[300431146] linearizableReadLoop","detail":"{readStateIndex:2362; appliedIndex:2360; }","duration":"791.435266ms","start":"2026-05-19T05:09:45.160184Z","end":"2026-05-19T05:09:45.951619Z","steps":["trace[300431146] 'read index received' (duration: 505.898326ms)","trace[300431146] 'applied index is now lower than readState.Index' (duration: 285.53336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T05:09:45.952094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.877947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T05:09:45.952192Z","caller":"traceutil/trace.go:171","msg":"trace[729226161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2272; }","duration":"792.043002ms","start":"2026-05-19T05:09:45.160124Z","end":"2026-05-19T05:09:45.952167Z","steps":["trace[729226161] 'agreement among raft nodes before linearized reading' (duration: 791.637221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:09:45.952243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:09:45.160112Z","time spent":"792.123183ms","remote":"127.0.0.1:47082","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-05-19T05:09:45.952276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.692609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T05:09:45.952348Z","caller":"traceutil/trace.go:171","msg":"trace[1188377367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2272; }","duration":"488.786541ms","start":"2026-05-19T05:09:45.463544Z","end":"2026-05-19T05:09:45.95233Z","steps":["trace[1188377367] 'agreement among raft nodes before linearized reading' (duration: 488.592406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:09:45.951639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:09:45.161372Z","time spent":"790.157385ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T05:09:45.952417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:09:45.463532Z","time spent":"488.873353ms","remote":"127.0.0.1:47082","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-05-19T05:09:45.952593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.620036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T05:09:45.952663Z","caller":"traceutil/trace.go:171","msg":"trace[86728298] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2272; }","duration":"549.724089ms","start":"2026-05-19T05:09:45.402921Z","end":"2026-05-19T05:09:45.952645Z","steps":["trace[86728298] 'agreement among raft nodes before linearized reading' (duration: 548.893738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:09:45.952714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:09:45.4029Z","time spent":"549.80236ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T05:09:45.954148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.365065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T05:09:45.954253Z","caller":"traceutil/trace.go:171","msg":"trace[52099021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2272; }","duration":"445.833071ms","start":"2026-05-19T05:09:45.508401Z","end":"2026-05-19T05:09:45.954234Z","steps":["trace[52099021] 'agreement among raft nodes before linearized reading' (duration: 443.729149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:09:45.954438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T05:09:45.508384Z","time spent":"446.038197ms","remote":"127.0.0.1:47082","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":"info","ts":"2026-05-19T05:09:46.11717Z","caller":"traceutil/trace.go:171","msg":"trace[1410565259] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"160.22874ms","start":"2026-05-19T05:09:45.956918Z","end":"2026-05-19T05:09:46.117147Z","steps":["trace[1410565259] 'process raft request' (duration: 95.498448ms)","trace[1410565259] 'compare' (duration: 64.559878ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:09:46.117433Z","caller":"traceutil/trace.go:171","msg":"trace[1749961748] linearizableReadLoop","detail":"{readStateIndex:2365; appliedIndex:2363; }","duration":"159.79357ms","start":"2026-05-19T05:09:45.957616Z","end":"2026-05-19T05:09:46.11741Z","steps":["trace[1749961748] 'read index received' (duration: 24.227294ms)","trace[1749961748] 'applied index is now lower than readState.Index' (duration: 135.564966ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T05:09:46.117552Z","caller":"traceutil/trace.go:171","msg":"trace[518634924] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"159.816609ms","start":"2026-05-19T05:09:45.957709Z","end":"2026-05-19T05:09:46.117526Z","steps":["trace[518634924] 'process raft request' (duration: 159.400289ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T05:09:46.117701Z","caller":"traceutil/trace.go:171","msg":"trace[455745199] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"158.945228ms","start":"2026-05-19T05:09:45.958699Z","end":"2026-05-19T05:09:46.117645Z","steps":["trace[455745199] 'process raft request' (duration: 158.61387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T05:09:46.11799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.349743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T05:09:46.118039Z","caller":"traceutil/trace.go:171","msg":"trace[928130534] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2276; }","duration":"160.429475ms","start":"2026-05-19T05:09:45.957599Z","end":"2026-05-19T05:09:46.118028Z","steps":["trace[928130534] 'agreement among raft nodes before linearized reading' (duration: 160.321082ms)"],"step_count":1}